builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-061 starttime: 1475107305.65 results: success (0) buildid: 20160928141019 builduid: 042d86bf4bae42d3896672161b8f3724 revision: 5237c35095f44146ef4d797e5c53147f102e1321 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 17:01:45.645799) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 17:01:45.646247) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 17:01:45.646546) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 17:01:45.667046) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 17:01:45.667380) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-28 17:01:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 220M=0s 2016-09-28 17:01:46 (220 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081872 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 17:01:45.766658) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 17:01:45.767390) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013955 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 17:01:45.822034) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 17:01:45.822760) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 5237c35095f44146ef4d797e5c53147f102e1321 --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 5237c35095f44146ef4d797e5c53147f102e1321 --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=516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-28 17:01:46,367 truncating revision to first 12 chars 2016-09-28 17:01:46,367 Setting DEBUG logging. 2016-09-28 17:01:46,367 attempt 1/10 2016-09-28 17:01:46,367 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/5237c35095f4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-28 17:01:46,664 unpacking tar archive at: mozilla-beta-5237c35095f4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.614960 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 17:01:46.453351) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 17:01:46.453890) ========= script_repo_revision: 5237c35095f44146ef4d797e5c53147f102e1321 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 17:01:46.454304) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 17:01:46.454601) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 17:01:46.463467) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-09-28 17:01:46.463930) ========= /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=516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:01:47 INFO - MultiFileLogger online at 20160928 17:01:47 in /builds/slave/test 17:01:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 17:01:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:01:47 INFO - {'append_to_log': False, 17:01:47 INFO - 'base_work_dir': '/builds/slave/test', 17:01:47 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:01:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:01:47 INFO - 'branch': 'Mozilla-Beta', 17:01:47 INFO - 'buildbot_json_path': 'buildprops.json', 17:01:47 INFO - 'config_files': ('talos/linux_config.py',), 17:01:47 INFO - 'default_actions': ('clobber', 17:01:47 INFO - 'read-buildbot-config', 17:01:47 INFO - 'download-and-extract', 17:01:47 INFO - 'populate-webroot', 17:01:47 INFO - 'create-virtualenv', 17:01:47 INFO - 'install', 17:01:47 INFO - 'run-tests'), 17:01:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:01:47 INFO - 'download_minidump_stackwalk': True, 17:01:47 INFO - 'download_symbols': 'ondemand', 17:01:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:01:47 INFO - 'tooltool.py': '/tools/tooltool.py', 17:01:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:01:47 INFO - '/tools/misc-python/virtualenv.py')}, 17:01:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:01:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:01:47 INFO - 'installer_path': 'installer.exe', 17:01:47 INFO - 'log_level': 'info', 17:01:47 INFO - 'log_name': 'talos', 17:01:47 INFO - 'log_to_console': True, 17:01:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:01:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:01:47 INFO - 'opt_config_files': (), 17:01:47 INFO - 'pip_index': False, 17:01:47 INFO - 'sps_profile': False, 17:01:47 INFO - 'sps_profile_interval': 0, 17:01:47 INFO - 'suite': 'dromaeojs', 17:01:47 INFO - 'system_bits': '32', 17:01:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:01:47 INFO - 'title': 'talos-linux64-ix-061', 17:01:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:01:47 INFO - 'use_talos_json': True, 17:01:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:01:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:01:47 INFO - 'work_dir': 'build'} 17:01:47 INFO - [mozharness: 2016-09-29 00:01:47.102705Z] Running clobber step. 17:01:47 INFO - Running pre-action listener: _resource_record_pre_action 17:01:47 INFO - Running main action method: clobber 17:01:47 INFO - rmtree: /builds/slave/test/build 17:01:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:01:48 INFO - Running post-action listener: _resource_record_post_action 17:01:48 INFO - [mozharness: 2016-09-29 00:01:48.073643Z] Finished clobber step (success) 17:01:48 INFO - [mozharness: 2016-09-29 00:01:48.073744Z] Running read-buildbot-config step. 17:01:48 INFO - Running pre-action listener: _resource_record_pre_action 17:01:48 INFO - Running main action method: read_buildbot_config 17:01:48 INFO - Using buildbot properties: 17:01:48 INFO - { 17:01:48 INFO - "project": "", 17:01:48 INFO - "product": "firefox", 17:01:48 INFO - "script_repo_revision": "production", 17:01:48 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 17:01:48 INFO - "repository": "", 17:01:48 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 17:01:48 INFO - "buildid": "20160928141019", 17:01:48 INFO - "pgo_build": "True", 17:01:48 INFO - "basedir": "/builds/slave/test", 17:01:48 INFO - "buildnumber": 403, 17:01:48 INFO - "slavename": "talos-linux64-ix-061", 17:01:48 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:01:48 INFO - "platform": "ubuntu64_hw", 17:01:48 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 17:01:48 INFO - "branch": "mozilla-beta", 17:01:48 INFO - "repo_path": "releases/mozilla-beta", 17:01:48 INFO - "revision": "5237c35095f44146ef4d797e5c53147f102e1321", 17:01:48 INFO - "stage_platform": "linux64-pgo", 17:01:48 INFO - "builduid": "042d86bf4bae42d3896672161b8f3724", 17:01:48 INFO - "slavebuilddir": "test" 17:01:48 INFO - } 17:01:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 17:01:48 INFO - Running post-action listener: _resource_record_post_action 17:01:48 INFO - [mozharness: 2016-09-29 00:01:48.076462Z] Finished read-buildbot-config step (success) 17:01:48 INFO - [mozharness: 2016-09-29 00:01:48.076551Z] Running download-and-extract step. 17:01:48 INFO - Running pre-action listener: _resource_record_pre_action 17:01:48 INFO - Running main action method: download_and_extract 17:01:48 INFO - mkdir: /builds/slave/test/build/tests 17:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:01:48 INFO - https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:01:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:01:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:01:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:01:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:01:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:01:48 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:01:48 INFO - retry: Failed, sleeping 30 seconds before retrying 17:02:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 17:02:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:02:18 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:02:18 INFO - retry: Failed, sleeping 60 seconds before retrying 17:03:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 17:03:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:03:18 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:03:18 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 17:03:18 INFO - Caught exception: HTTP Error 404: Not Found 17:03:18 INFO - Caught exception: HTTP Error 404: Not Found 17:03:18 INFO - Caught exception: HTTP Error 404: Not Found 17:03:18 INFO - trying https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:03:18 INFO - Downloading https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:03:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:03:18 INFO - Downloaded 1327 bytes. 17:03:18 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:03:18 INFO - Using the following test package requirements: 17:03:18 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 17:03:18 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:03:18 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 17:03:18 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:03:18 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 17:03:18 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:03:18 INFO - u'jsshell-linux-x86_64.zip'], 17:03:18 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:03:18 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 17:03:18 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 17:03:18 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:03:18 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 17:03:18 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:03:18 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 17:03:18 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:03:18 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 17:03:18 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:03:18 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 17:03:18 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:03:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:03:18 INFO - https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:03:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:03:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:03:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:03:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:03:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:03:18 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:03:18 INFO - retry: Failed, sleeping 30 seconds before retrying 17:03:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 17:03:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:03:48 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:03:48 INFO - retry: Failed, sleeping 60 seconds before retrying 17:04:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 17:04:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:04:48 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:04:48 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 17:04:48 INFO - Caught exception: HTTP Error 404: Not Found 17:04:48 INFO - Caught exception: HTTP Error 404: Not Found 17:04:48 INFO - Caught exception: HTTP Error 404: Not Found 17:04:48 INFO - trying https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:04:48 INFO - Downloading https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:04:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:04:49 INFO - Downloaded 21773820 bytes. 17:04:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:04:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:04:50 INFO - Return code: 0 17:04:50 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:04: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')]} 17:04:50 INFO - https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:04:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:04:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:04:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:04:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:04:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:04:50 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:04:50 INFO - retry: Failed, sleeping 30 seconds before retrying 17:05:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 17:05:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:05:20 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:05:20 INFO - retry: Failed, sleeping 60 seconds before retrying 17:06:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 17:06:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:06:20 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:06:20 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 17:06:20 INFO - Caught exception: HTTP Error 404: Not Found 17:06:20 INFO - Caught exception: HTTP Error 404: Not Found 17:06:20 INFO - Caught exception: HTTP Error 404: Not Found 17:06:20 INFO - trying https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:06:20 INFO - Downloading https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:06:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:06:21 INFO - Downloaded 21773820 bytes. 17:06:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:06:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:06:23 INFO - Return code: 0 17:06:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:06:23 INFO - https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:06:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:06:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:06:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:06:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:06:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:06:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:06:23 INFO - retry: Failed, sleeping 30 seconds before retrying 17:06:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 17:06:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:06:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:06:53 INFO - retry: Failed, sleeping 60 seconds before retrying 17:07:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 17:07:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:07:53 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:07:53 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 17:07:53 INFO - Caught exception: HTTP Error 404: Not Found 17:07:53 INFO - Caught exception: HTTP Error 404: Not Found 17:07:53 INFO - Caught exception: HTTP Error 404: Not Found 17:07:53 INFO - trying https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:07:53 INFO - Downloading https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:07:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:07:54 INFO - Downloaded 14413740 bytes. 17:07:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:07:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 17:07:54 INFO - Return code: 0 17:07:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:07:54 INFO - https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:07:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:07:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:07:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:07:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:07:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:07:54 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:07:54 INFO - retry: Failed, sleeping 30 seconds before retrying 17:08:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 17:08:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:08:24 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:08:24 INFO - retry: Failed, sleeping 60 seconds before retrying 17:09:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 17:09:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:09:24 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:09:24 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 17:09:24 INFO - Caught exception: HTTP Error 404: Not Found 17:09:24 INFO - Caught exception: HTTP Error 404: Not Found 17:09:24 INFO - Caught exception: HTTP Error 404: Not Found 17:09:24 INFO - trying https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:09:24 INFO - Downloading https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:09:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:09:25 INFO - Downloaded 56775026 bytes. 17:09:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:09:25 INFO - mkdir: /builds/slave/test/properties 17:09:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:09:25 INFO - Writing to file /builds/slave/test/properties/build_url 17:09:25 INFO - Contents: 17:09:25 INFO - build_url:https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:09:26 INFO - Running post-action listener: _resource_record_post_action 17:09:26 INFO - Running post-action listener: set_extra_try_arguments 17:09:26 INFO - [mozharness: 2016-09-29 00:09:26.604957Z] Finished download-and-extract step (success) 17:09:26 INFO - [mozharness: 2016-09-29 00:09:26.605193Z] Running populate-webroot step. 17:09:26 INFO - Running pre-action listener: _resource_record_pre_action 17:09:26 INFO - Running main action method: populate_webroot 17:09:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:09:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:09:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:09:26 INFO - '--tppagecycles', 17:09:26 INFO - '7'], 17:09:26 INFO - 'tests': ['tsvgm']}}, 17:09:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:09:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:09:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:09:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:09:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:09:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:09:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:09:26 INFO - 'talos_options': ['--disable-e10s'], 17:09:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:09:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:09:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:09:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:09:26 INFO - 'talos_options': ['--disable-e10s'], 17:09:26 INFO - 'tests': ['damp', 'tps']}, 17:09:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:09:26 INFO - 'tests': ['damp', 'tps']}, 17:09:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:09:26 INFO - 'tests': ['dromaeo_dom']}, 17:09:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:09:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:09:26 INFO - 'tests': ['basic_compositor_video']}, 17:09:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:09:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:09:26 INFO - 'tests': ['a11yr', 17:09:26 INFO - 'ts_paint', 17:09:26 INFO - 'tpaint', 17:09:26 INFO - 'sessionrestore', 17:09:26 INFO - 'sessionrestore_no_auto_restore', 17:09:26 INFO - 'tabpaint']}, 17:09:26 INFO - 'other-e10s': {'tests': ['a11yr', 17:09:26 INFO - 'ts_paint', 17:09:26 INFO - 'tpaint', 17:09:26 INFO - 'sessionrestore', 17:09:26 INFO - 'sessionrestore_no_auto_restore', 17:09:26 INFO - 'tabpaint']}, 17:09:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:09:26 INFO - 'ts_paint', 17:09:26 INFO - 'tpaint', 17:09:26 INFO - 'sessionrestore', 17:09:26 INFO - 'sessionrestore_no_auto_restore', 17:09:26 INFO - 'tabpaint']}, 17:09:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:09:26 INFO - 'ts_paint', 17:09:26 INFO - 'tpaint', 17:09:26 INFO - 'sessionrestore', 17:09:26 INFO - 'sessionrestore_no_auto_restore', 17:09:26 INFO - 'tabpaint']}, 17:09:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:09:26 INFO - 'tests': ['a11yr', 17:09:26 INFO - 'ts_paint', 17:09:26 INFO - 'tpaint', 17:09:26 INFO - 'sessionrestore', 17:09:26 INFO - 'sessionrestore_no_auto_restore', 17:09:26 INFO - 'tabpaint']}, 17:09:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:09:26 INFO - 'tests': ['a11yr', 17:09:26 INFO - 'ts_paint', 17:09:26 INFO - 'tpaint', 17:09:26 INFO - 'sessionrestore', 17:09:26 INFO - 'sessionrestore_no_auto_restore', 17:09:26 INFO - 'tabpaint']}, 17:09:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:09:26 INFO - 'tests': ['tsvgx', 17:09:26 INFO - 'tsvgr_opacity', 17:09:26 INFO - 'tart', 17:09:26 INFO - 'tscrollx', 17:09:26 INFO - 'cart']}, 17:09:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:09:26 INFO - 'tsvgr_opacity', 17:09:26 INFO - 'tart', 17:09:26 INFO - 'tscrollx', 17:09:26 INFO - 'cart']}, 17:09:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:09:26 INFO - 'talos_options': ['--disable-e10s'], 17:09:26 INFO - 'tests': ['tp5o']}, 17:09:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:09:26 INFO - 'tests': ['tp5o']}, 17:09:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:09:26 INFO - 'talos_options': ['--disable-e10s', 17:09:26 INFO - '--xperf_path', 17:09:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:09:26 INFO - 'tests': ['tp5n']}, 17:09:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:09:26 INFO - 'talos_options': ['--xperf_path', 17:09:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:09:26 INFO - 'tests': ['tp5n']}}, 17:09:26 INFO - 'talos.zip': {'path': '', 17:09:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:09:26 INFO - Running post-action listener: _resource_record_post_action 17:09:26 INFO - [mozharness: 2016-09-29 00:09:26.624125Z] Finished populate-webroot step (success) 17:09:26 INFO - [mozharness: 2016-09-29 00:09:26.624214Z] Running create-virtualenv step. 17:09:26 INFO - Running pre-action listener: _resource_record_pre_action 17:09:26 INFO - Running main action method: create_virtualenv 17:09:26 INFO - Creating virtualenv /builds/slave/test/build/venv 17:09:26 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:09:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:09:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:09:26 INFO - Using real prefix '/usr' 17:09:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:09:27 INFO - Installing distribute.............................................................................................................................................................................................done. 17:09:29 INFO - Installing pip.................done. 17:09:29 INFO - Return code: 0 17:09:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:29 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 0x1956030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c17180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c153b0>, '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 0x1c162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c16740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:09:29 INFO - Using env: {'DISPLAY': ':0', 17:09:29 INFO - 'HOME': '/home/cltbld', 17:09:29 INFO - 'LANG': 'en_US.UTF-8', 17:09:29 INFO - 'LANGUAGE': 'en_US:en', 17:09:29 INFO - 'LOGNAME': 'cltbld', 17:09:29 INFO - 'MAIL': '/var/mail/cltbld', 17:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:29 INFO - 'MOZ_NO_REMOTE': '1', 17:09:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:29 INFO - 'NO_EM_RESTART': '1', 17:09:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:29 INFO - 'PWD': '/builds/slave/test', 17:09:29 INFO - 'SHELL': '/bin/bash', 17:09:29 INFO - 'SHLVL': '1', 17:09:29 INFO - 'TERM': 'linux', 17:09:29 INFO - 'TMOUT': '86400', 17:09:29 INFO - 'USER': 'cltbld', 17:09:29 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 17:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:29 INFO - '_': '/tools/buildbot/bin/python'} 17:09:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:09:29 INFO - Cleaning up... 17:09:29 INFO - Return code: 0 17:09:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:29 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 0x1956030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c17180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c153b0>, '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 0x1c162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c16740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:09:29 INFO - Using env: {'DISPLAY': ':0', 17:09:29 INFO - 'HOME': '/home/cltbld', 17:09:29 INFO - 'LANG': 'en_US.UTF-8', 17:09:29 INFO - 'LANGUAGE': 'en_US:en', 17:09:29 INFO - 'LOGNAME': 'cltbld', 17:09:29 INFO - 'MAIL': '/var/mail/cltbld', 17:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:29 INFO - 'MOZ_NO_REMOTE': '1', 17:09:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:29 INFO - 'NO_EM_RESTART': '1', 17:09:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:29 INFO - 'PWD': '/builds/slave/test', 17:09:29 INFO - 'SHELL': '/bin/bash', 17:09:29 INFO - 'SHLVL': '1', 17:09:29 INFO - 'TERM': 'linux', 17:09:29 INFO - 'TMOUT': '86400', 17:09:29 INFO - 'USER': 'cltbld', 17:09:29 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 17:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:29 INFO - '_': '/tools/buildbot/bin/python'} 17:09:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:29 INFO - Downloading/unpacking psutil>=3.1.1 17:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:09:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:09:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:09:31 INFO - Installing collected packages: psutil 17:09:31 INFO - Running setup.py install for psutil 17:09:31 INFO - building 'psutil._psutil_linux' extension 17:09:31 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 17:09:31 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 17:09:31 INFO - building 'psutil._psutil_posix' extension 17:09:31 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 17:09:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 17:09:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:09:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:09:32 INFO - Successfully installed psutil 17:09:32 INFO - Cleaning up... 17:09:32 INFO - Return code: 0 17:09:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:32 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 0x1956030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c17180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c153b0>, '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 0x1c162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c16740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:09:32 INFO - Using env: {'DISPLAY': ':0', 17:09:32 INFO - 'HOME': '/home/cltbld', 17:09:32 INFO - 'LANG': 'en_US.UTF-8', 17:09:32 INFO - 'LANGUAGE': 'en_US:en', 17:09:32 INFO - 'LOGNAME': 'cltbld', 17:09:32 INFO - 'MAIL': '/var/mail/cltbld', 17:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:32 INFO - 'MOZ_NO_REMOTE': '1', 17:09:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:32 INFO - 'NO_EM_RESTART': '1', 17:09:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:32 INFO - 'PWD': '/builds/slave/test', 17:09:32 INFO - 'SHELL': '/bin/bash', 17:09:32 INFO - 'SHLVL': '1', 17:09:32 INFO - 'TERM': 'linux', 17:09:32 INFO - 'TMOUT': '86400', 17:09:32 INFO - 'USER': 'cltbld', 17:09:32 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 17:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:32 INFO - '_': '/tools/buildbot/bin/python'} 17:09:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:09:34 INFO - Installing collected packages: mozsystemmonitor 17:09:34 INFO - Running setup.py install for mozsystemmonitor 17:09:34 INFO - Successfully installed mozsystemmonitor 17:09:34 INFO - Cleaning up... 17:09:34 INFO - Return code: 0 17:09:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:34 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 0x1956030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c17180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c153b0>, '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 0x1c162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c16740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:09:34 INFO - Using env: {'DISPLAY': ':0', 17:09:34 INFO - 'HOME': '/home/cltbld', 17:09:34 INFO - 'LANG': 'en_US.UTF-8', 17:09:34 INFO - 'LANGUAGE': 'en_US:en', 17:09:34 INFO - 'LOGNAME': 'cltbld', 17:09:34 INFO - 'MAIL': '/var/mail/cltbld', 17:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:34 INFO - 'MOZ_NO_REMOTE': '1', 17:09:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:34 INFO - 'NO_EM_RESTART': '1', 17:09:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:34 INFO - 'PWD': '/builds/slave/test', 17:09:34 INFO - 'SHELL': '/bin/bash', 17:09:34 INFO - 'SHLVL': '1', 17:09:34 INFO - 'TERM': 'linux', 17:09:34 INFO - 'TMOUT': '86400', 17:09:34 INFO - 'USER': 'cltbld', 17:09:34 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 17:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:34 INFO - '_': '/tools/buildbot/bin/python'} 17:09:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:34 INFO - Downloading/unpacking jsonschema==2.5.1 17:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:09:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:36 INFO - Downloading functools32-3.2.3-2.tar.gz 17:09:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:09:36 INFO - warning: no files found matching '*.txt' 17:09:36 INFO - no previously-included directories found matching 'build' 17:09:36 INFO - no previously-included directories found matching 'dist' 17:09:36 INFO - no previously-included directories found matching '.git*' 17:09:36 INFO - Installing collected packages: jsonschema, functools32 17:09:36 INFO - Running setup.py install for functools32 17:09:36 INFO - warning: no files found matching '*.txt' 17:09:36 INFO - no previously-included directories found matching 'build' 17:09:36 INFO - no previously-included directories found matching 'dist' 17:09:36 INFO - no previously-included directories found matching '.git*' 17:09:36 INFO - Successfully installed jsonschema functools32 17:09:36 INFO - Cleaning up... 17:09:37 INFO - Return code: 0 17:09:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:09: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')]} 17:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09: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')]} 17:09:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1956030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c17180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c153b0>, '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 0x1c162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c16740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:09:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:09:37 INFO - Using env: {'DISPLAY': ':0', 17:09:37 INFO - 'HOME': '/home/cltbld', 17:09:37 INFO - 'LANG': 'en_US.UTF-8', 17:09:37 INFO - 'LANGUAGE': 'en_US:en', 17:09:37 INFO - 'LOGNAME': 'cltbld', 17:09:37 INFO - 'MAIL': '/var/mail/cltbld', 17:09:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:37 INFO - 'MOZ_NO_REMOTE': '1', 17:09:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:37 INFO - 'NO_EM_RESTART': '1', 17:09:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:37 INFO - 'PWD': '/builds/slave/test', 17:09:37 INFO - 'SHELL': '/bin/bash', 17:09:37 INFO - 'SHLVL': '1', 17:09:37 INFO - 'TERM': 'linux', 17:09:37 INFO - 'TMOUT': '86400', 17:09:37 INFO - 'USER': 'cltbld', 17:09:37 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 17:09:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:37 INFO - '_': '/tools/buildbot/bin/python'} 17:09:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:09:37 INFO - Cleaning up... 17:09:37 INFO - Return code: 0 17:09:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:09: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')]} 17:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09: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')]} 17:09:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1956030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c17180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c153b0>, '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 0x1c162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c16740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:09:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:09:37 INFO - Using env: {'DISPLAY': ':0', 17:09:37 INFO - 'HOME': '/home/cltbld', 17:09:37 INFO - 'LANG': 'en_US.UTF-8', 17:09:37 INFO - 'LANGUAGE': 'en_US:en', 17:09:37 INFO - 'LOGNAME': 'cltbld', 17:09:37 INFO - 'MAIL': '/var/mail/cltbld', 17:09:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:37 INFO - 'MOZ_NO_REMOTE': '1', 17:09:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:37 INFO - 'NO_EM_RESTART': '1', 17:09:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:37 INFO - 'PWD': '/builds/slave/test', 17:09:37 INFO - 'SHELL': '/bin/bash', 17:09:37 INFO - 'SHLVL': '1', 17:09:37 INFO - 'TERM': 'linux', 17:09:37 INFO - 'TMOUT': '86400', 17:09:37 INFO - 'USER': 'cltbld', 17:09:37 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 17:09:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:37 INFO - '_': '/tools/buildbot/bin/python'} 17:09:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:37 INFO - Downloading/unpacking blobuploader==1.2.4 17:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:39 INFO - Downloading blobuploader-1.2.4.tar.gz 17:09:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:09:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:09:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:40 INFO - Downloading docopt-0.6.1.tar.gz 17:09:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:09:40 INFO - Installing collected packages: blobuploader, requests, docopt 17:09:40 INFO - Running setup.py install for blobuploader 17:09:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:09:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:09:40 INFO - Running setup.py install for requests 17:09:40 INFO - Running setup.py install for docopt 17:09:40 INFO - Successfully installed blobuploader requests docopt 17:09:40 INFO - Cleaning up... 17:09:40 INFO - Return code: 0 17:09:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:40 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 0x1956030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c17180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c153b0>, '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 0x1c162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c16740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:09:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:09:40 INFO - Using env: {'DISPLAY': ':0', 17:09:40 INFO - 'HOME': '/home/cltbld', 17:09:40 INFO - 'LANG': 'en_US.UTF-8', 17:09:40 INFO - 'LANGUAGE': 'en_US:en', 17:09:40 INFO - 'LOGNAME': 'cltbld', 17:09:40 INFO - 'MAIL': '/var/mail/cltbld', 17:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:40 INFO - 'MOZ_NO_REMOTE': '1', 17:09:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:40 INFO - 'NO_EM_RESTART': '1', 17:09:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:40 INFO - 'PWD': '/builds/slave/test', 17:09:40 INFO - 'SHELL': '/bin/bash', 17:09:40 INFO - 'SHLVL': '1', 17:09:40 INFO - 'TERM': 'linux', 17:09:40 INFO - 'TMOUT': '86400', 17:09:40 INFO - 'USER': 'cltbld', 17:09:40 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 17:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:40 INFO - '_': '/tools/buildbot/bin/python'} 17:09:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:09:40 INFO - Running setup.py (path:/tmp/pip-w0oGvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:09:41 INFO - Running setup.py (path:/tmp/pip-ZY_5gC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:09:41 INFO - Running setup.py (path:/tmp/pip-6Uu92G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:09:41 INFO - Running setup.py (path:/tmp/pip-jVuWxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:09:41 INFO - Running setup.py (path:/tmp/pip-h5K4AV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:09:41 INFO - Running setup.py (path:/tmp/pip-wOQInU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:09:41 INFO - Running setup.py (path:/tmp/pip-XHg_U9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:09:41 INFO - Running setup.py (path:/tmp/pip-Yq4U_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:09:41 INFO - Running setup.py (path:/tmp/pip-E6d0WQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:09:41 INFO - Running setup.py (path:/tmp/pip-2YGYxg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:09:41 INFO - Running setup.py (path:/tmp/pip-D0CyR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:09:41 INFO - Running setup.py (path:/tmp/pip-Qkc0uZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:09:41 INFO - Running setup.py (path:/tmp/pip-ZxivqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:09:41 INFO - Running setup.py (path:/tmp/pip-O9hzZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:41 INFO - Running setup.py (path:/tmp/pip-c9v9h3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:09:42 INFO - Running setup.py (path:/tmp/pip-VqAPqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:09:42 INFO - Running setup.py (path:/tmp/pip-DFTGi6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:09:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:09:42 INFO - Running setup.py install for manifestparser 17:09:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:09:42 INFO - Running setup.py install for mozcrash 17:09:42 INFO - Running setup.py install for mozdebug 17:09:42 INFO - Running setup.py install for mozdevice 17:09:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:09:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:09:42 INFO - Running setup.py install for mozfile 17:09:42 INFO - Running setup.py install for mozhttpd 17:09:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:09:42 INFO - Running setup.py install for mozinfo 17:09:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:09:42 INFO - Running setup.py install for mozInstall 17:09:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:09:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:09:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:09:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:09:42 INFO - Running setup.py install for mozleak 17:09:43 INFO - Running setup.py install for mozlog 17:09:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:09:43 INFO - Running setup.py install for moznetwork 17:09:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:09:43 INFO - Running setup.py install for mozprocess 17:09:43 INFO - Running setup.py install for mozprofile 17:09:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:09:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:09:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:09:43 INFO - Running setup.py install for mozrunner 17:09:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:09:43 INFO - Running setup.py install for mozscreenshot 17:09:43 INFO - Running setup.py install for moztest 17:09:43 INFO - Running setup.py install for mozversion 17:09:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:09:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:09:43 INFO - Cleaning up... 17:09:43 INFO - Return code: 0 17:09:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:09:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:44 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 0x1956030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c17180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c153b0>, '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 0x1c162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c16740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:09:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:09:44 INFO - Using env: {'DISPLAY': ':0', 17:09:44 INFO - 'HOME': '/home/cltbld', 17:09:44 INFO - 'LANG': 'en_US.UTF-8', 17:09:44 INFO - 'LANGUAGE': 'en_US:en', 17:09:44 INFO - 'LOGNAME': 'cltbld', 17:09:44 INFO - 'MAIL': '/var/mail/cltbld', 17:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:44 INFO - 'MOZ_NO_REMOTE': '1', 17:09:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:44 INFO - 'NO_EM_RESTART': '1', 17:09:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:44 INFO - 'PWD': '/builds/slave/test', 17:09:44 INFO - 'SHELL': '/bin/bash', 17:09:44 INFO - 'SHLVL': '1', 17:09:44 INFO - 'TERM': 'linux', 17:09:44 INFO - 'TMOUT': '86400', 17:09:44 INFO - 'USER': 'cltbld', 17:09:44 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 17:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:44 INFO - '_': '/tools/buildbot/bin/python'} 17:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:09:44 INFO - Running setup.py (path:/tmp/pip-XEMSYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:09:44 INFO - Running setup.py (path:/tmp/pip-Asavbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:09:44 INFO - Running setup.py (path:/tmp/pip-9WKnQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:09:44 INFO - Running setup.py (path:/tmp/pip-XwXa2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:09:44 INFO - Running setup.py (path:/tmp/pip-bvRewE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:09:44 INFO - Running setup.py (path:/tmp/pip-3WyYWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:09:44 INFO - Running setup.py (path:/tmp/pip-YC9VNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:09:44 INFO - Running setup.py (path:/tmp/pip-y400Ya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:09:44 INFO - Running setup.py (path:/tmp/pip-MQnmkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:09:44 INFO - Running setup.py (path:/tmp/pip-l9ekjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:09:44 INFO - Running setup.py (path:/tmp/pip-CMelcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:09:45 INFO - Running setup.py (path:/tmp/pip-u5Vat0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:09:45 INFO - Running setup.py (path:/tmp/pip-jG_O1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:09:45 INFO - Running setup.py (path:/tmp/pip-GTcO98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:45 INFO - Running setup.py (path:/tmp/pip-7t2c8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:09:45 INFO - Running setup.py (path:/tmp/pip-HqvJB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:09:45 INFO - Running setup.py (path:/tmp/pip-qf3MFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:09:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:47 INFO - Downloading blessings-1.6.tar.gz 17:09:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:09:47 INFO - Installing collected packages: blessings 17:09:47 INFO - Running setup.py install for blessings 17:09:47 INFO - Successfully installed blessings 17:09:47 INFO - Cleaning up... 17:09:47 INFO - Return code: 0 17:09:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:09:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:09:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:09:47 INFO - Reading from file tmpfile_stdout 17:09:47 INFO - Current package versions: 17:09:47 INFO - argparse == 1.2.1 17:09:47 INFO - blessings == 1.6 17:09:47 INFO - blobuploader == 1.2.4 17:09:47 INFO - docopt == 0.6.1 17:09:47 INFO - functools32 == 3.2.3-2 17:09:47 INFO - jsonschema == 2.5.1 17:09:47 INFO - manifestparser == 1.1 17:09:47 INFO - mozInstall == 1.12 17:09:47 INFO - mozcrash == 0.17 17:09:47 INFO - mozdebug == 0.1 17:09:47 INFO - mozdevice == 0.48 17:09:47 INFO - mozfile == 1.2 17:09:47 INFO - mozhttpd == 0.7 17:09:47 INFO - mozinfo == 0.9 17:09:47 INFO - mozleak == 0.1 17:09:47 INFO - mozlog == 3.3 17:09:47 INFO - moznetwork == 0.27 17:09:47 INFO - mozprocess == 0.23 17:09:47 INFO - mozprofile == 0.28 17:09:47 INFO - mozrunner == 6.12 17:09:47 INFO - mozscreenshot == 0.1 17:09:47 INFO - mozsystemmonitor == 0.3 17:09:47 INFO - moztest == 0.7 17:09:47 INFO - mozversion == 1.4 17:09:47 INFO - psutil == 3.1.1 17:09:47 INFO - requests == 1.2.3 17:09:47 INFO - wsgiref == 0.1.2 17:09:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:09: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')]} 17:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09: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')]} 17:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:47 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 0x1956030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c17180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c153b0>, '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 0x1c162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c16740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:09:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:09:47 INFO - Using env: {'DISPLAY': ':0', 17:09:47 INFO - 'HOME': '/home/cltbld', 17:09:47 INFO - 'LANG': 'en_US.UTF-8', 17:09:47 INFO - 'LANGUAGE': 'en_US:en', 17:09:47 INFO - 'LOGNAME': 'cltbld', 17:09:47 INFO - 'MAIL': '/var/mail/cltbld', 17:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:47 INFO - 'MOZ_NO_REMOTE': '1', 17:09:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:47 INFO - 'NO_EM_RESTART': '1', 17:09:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:47 INFO - 'PWD': '/builds/slave/test', 17:09:47 INFO - 'SHELL': '/bin/bash', 17:09:47 INFO - 'SHLVL': '1', 17:09:47 INFO - 'TERM': 'linux', 17:09:47 INFO - 'TMOUT': '86400', 17:09:47 INFO - 'USER': 'cltbld', 17:09:47 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 17:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:47 INFO - '_': '/tools/buildbot/bin/python'} 17:09:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:09:47 INFO - Cleaning up... 17:09:48 INFO - Return code: 0 17:09:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:48 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 0x1956030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c17180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c153b0>, '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 0x1c162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c16740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:09:48 INFO - Using env: {'DISPLAY': ':0', 17:09:48 INFO - 'HOME': '/home/cltbld', 17:09:48 INFO - 'LANG': 'en_US.UTF-8', 17:09:48 INFO - 'LANGUAGE': 'en_US:en', 17:09:48 INFO - 'LOGNAME': 'cltbld', 17:09:48 INFO - 'MAIL': '/var/mail/cltbld', 17:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:48 INFO - 'MOZ_NO_REMOTE': '1', 17:09:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:48 INFO - 'NO_EM_RESTART': '1', 17:09:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:48 INFO - 'PWD': '/builds/slave/test', 17:09:48 INFO - 'SHELL': '/bin/bash', 17:09:48 INFO - 'SHLVL': '1', 17:09:48 INFO - 'TERM': 'linux', 17:09:48 INFO - 'TMOUT': '86400', 17:09:48 INFO - 'USER': 'cltbld', 17:09:48 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 17:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:48 INFO - '_': '/tools/buildbot/bin/python'} 17:09:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:09:48 INFO - Cleaning up... 17:09:48 INFO - Return code: 0 17:09:48 INFO - Running post-action listener: _resource_record_post_action 17:09:48 INFO - Running post-action listener: _start_resource_monitoring 17:09:48 INFO - Starting resource monitoring. 17:09:48 INFO - [mozharness: 2016-09-29 00:09:48.265189Z] Finished create-virtualenv step (success) 17:09:48 INFO - [mozharness: 2016-09-29 00:09:48.265623Z] Running install step. 17:09:48 INFO - Running pre-action listener: _resource_record_pre_action 17:09:48 INFO - Running main action method: install 17:09:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:09:48 INFO - Reading from file tmpfile_stdout 17:09:48 INFO - Detecting whether we're running mozinstall >=1.0... 17:09:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:09:48 INFO - Reading from file tmpfile_stdout 17:09:48 INFO - Output received: 17:09:48 INFO - Usage: mozinstall [options] installer 17:09:48 INFO - Options: 17:09:48 INFO - -h, --help show this help message and exit 17:09:48 INFO - -d DEST, --destination=DEST 17:09:48 INFO - Directory to install application into. [default: 17:09:48 INFO - "/builds/slave/test"] 17:09:48 INFO - --app=APP Application being installed. [default: firefox] 17:09:48 INFO - mkdir: /builds/slave/test/build/application 17:09:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:10:02 INFO - Reading from file tmpfile_stdout 17:10:02 INFO - Output received: 17:10:02 INFO - /builds/slave/test/build/application/firefox/firefox 17:10:02 INFO - Running post-action listener: _resource_record_post_action 17:10:02 INFO - [mozharness: 2016-09-29 00:10:02.053893Z] Finished install step (success) 17:10:02 INFO - [mozharness: 2016-09-29 00:10:02.054248Z] Running run-tests step. 17:10:02 INFO - Running pre-action listener: _resource_record_pre_action 17:10:02 INFO - Running main action method: run_tests 17:10:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:10:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:10:02 INFO - Python 2.7.3 17:10:02 INFO - Return code: 0 17:10:02 INFO - grabbing minidump binary from tooltool 17:10: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')]} 17:10:02 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 0x1c153b0>, '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 0x1c162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c16740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:10:02 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:10:02 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 17:10:02 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:10:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:10:02 INFO - Return code: 0 17:10:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:10:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:10:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:10:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:10:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:10:02 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/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 17:10:02 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/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-061 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 17:10:02 INFO - Using env: {'DISPLAY': ':0', 17:10:02 INFO - 'HOME': '/home/cltbld', 17:10:02 INFO - 'LANG': 'en_US.UTF-8', 17:10:02 INFO - 'LANGUAGE': 'en_US:en', 17:10:02 INFO - 'LOGNAME': 'cltbld', 17:10:02 INFO - 'MAIL': '/var/mail/cltbld', 17:10:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:10:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:10:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:02 INFO - 'MOZ_NO_REMOTE': '1', 17:10:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:10:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:02 INFO - 'NO_EM_RESTART': '1', 17:10:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:02 INFO - 'PWD': '/builds/slave/test', 17:10:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:10:02 INFO - 'SHELL': '/bin/bash', 17:10:02 INFO - 'SHLVL': '1', 17:10:02 INFO - 'TERM': 'linux', 17:10:02 INFO - 'TMOUT': '86400', 17:10:02 INFO - 'USER': 'cltbld', 17:10:02 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379', 17:10:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:02 INFO - '_': '/tools/buildbot/bin/python'} 17:10:02 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/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 17:10:02 INFO - mozversion application_buildid: 20160928141019 17:10:02 INFO - mozversion application_changeset: 5237c35095f44146ef4d797e5c53147f102e1321 17:10:02 INFO - mozversion application_display_name: Firefox 17:10:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:10:02 INFO - mozversion application_name: Firefox 17:10:02 INFO - mozversion application_remotingname: firefox 17:10:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:10:02 INFO - mozversion application_vendor: Mozilla 17:10:02 INFO - mozversion application_version: 50.0 17:10:02 INFO - mozversion platform_buildid: 20160928141019 17:10:02 INFO - mozversion platform_changeset: 5237c35095f44146ef4d797e5c53147f102e1321 17:10:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:10:02 INFO - mozversion platform_version: 50.0 17:10:02 INFO - using testdate: 1475107802 17:10:02 INFO - actual date: 1475107802 17:10:02 INFO - starting webserver on 'localhost:33023' 17:10:02 INFO - SUITE-START | Running 2 tests 17:10:02 INFO - TEST-START | dromaeo_css 17:10:02 INFO - operating with platform_type : linux_ 17:10:02 INFO - Initialising browser for dromaeo_css test... 17:10:02 INFO - TEST-INFO | started process 28394 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkNvIOj/profile http://localhost:33023/getInfo.html) 17:10:06 INFO - PROCESS | 28394 | __metrics Screen width/height:1600/1200 17:10:06 INFO - PROCESS | 28394 | colorDepth:24 17:10:06 INFO - PROCESS | 28394 | Browser inner width/height: 1024/697 17:10:06 INFO - PROCESS | 28394 | __metrics 17:10:07 INFO - PROCESS | 28394 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:10:07 INFO - PROCESS | 28394 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:10:07 INFO - PROCESS | 28394 | console.error: PushService: 17:10:07 INFO - PROCESS | 28394 | stateChangeProcessEnqueue: Error transitioning state 17:10:07 INFO - PROCESS | 28394 | UnknownError 17:10:08 INFO - TEST-INFO | 28394: exit 0 17:10:08 INFO - Browser initialized. 17:10:08 INFO - Running cycle 1/1 for dromaeo_css test... 17:10:08 INFO - TEST-INFO | started process 28482 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkNvIOj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:10:20 INFO - PROCESS | 28482 | RSS: Main: 143552512 17:10:20 INFO - PROCESS | 28482 | 17:11:11 INFO - PROCESS | 28482 | 1475107871983 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] 17:13:19 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:33023/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33023/tests/dromaeo/cssquery-ext.html) 17:13:21 INFO - PROCESS | 28482 | RSS: Main: 263090176 17:13:21 INFO - PROCESS | 28482 | 17:16:23 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:33023/tests/dromaeo/cssquery-ext.html (next: http://localhost:33023/tests/dromaeo/cssquery-jquery.html) 17:16:24 INFO - PROCESS | 28482 | RSS: Main: 229830656 17:16:24 INFO - PROCESS | 28482 | 17:19:25 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:33023/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33023/tests/dromaeo/cssquery-mootools.html) 17:19:26 INFO - PROCESS | 28482 | RSS: Main: 206041088 17:19:26 INFO - PROCESS | 28482 | 17:22:28 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:33023/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33023/tests/dromaeo/cssquery-prototype.html) 17:22:28 INFO - PROCESS | 28482 | RSS: Main: 202567680 17:22:28 INFO - PROCESS | 28482 | 17:25:31 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:33023/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33023/tests/dromaeo/cssquery-yui.html) 17:25:32 INFO - PROCESS | 28482 | RSS: Main: 178978816 17:25:32 INFO - PROCESS | 28482 | 17:28:34 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:33023/tests/dromaeo/cssquery-yui.html (next: http://localhost:33023/tests/dromaeo/cssquery-dojo.html) 17:28:34 INFO - PROCESS | 28482 | RSS: Main: 266911744 17:28:34 INFO - PROCESS | 28482 | 17:28:34 INFO - PROCESS | 28482 | __start_tp_report 17:28:34 INFO - PROCESS | 28482 | _x_x_mozilla_page_load 17:28:34 INFO - PROCESS | 28482 | _x_x_mozilla_page_load_details 17:28:34 INFO - PROCESS | 28482 | |i|pagename|runs| 17:28:34 INFO - PROCESS | 28482 | |0;dojo.html;3207.7594180436467;3218.2758879252165;3232.595925509311;3251.6098068231818;3272.869085236589;5646.265985421895;5739.913901291484;5748.19525266463;5778.942210577893;5784.913226301604;5770.105633626784;5831.533477321815;5849.912251316223;5850.0932355484965;5853.7365818538165;5262.9473705263035;5315.654482458647;5320.494553017457;5341.385740639818;5354.625176237665;5191.6884986900905;5375.569954403658;5381.946180538203;5397.62216644835;5402.864928376783;5101.795928162887;5144.356955380577;5201.609879259052;5214.217867319894;5226.92159617606;5223.2165175223645;5247.501487358714;5283.445238249967;5357.196420536911;5364.8122315718765;5199.298094757197;5242.18746094356;5311.415744268127;5323.813666521653;5325.121354976424;5574.303212098488;5599.020171469976;5618.719064046782;5622.3253209614695;5636.830895073154;6087.878242435171;6123.112148738453;6251.718672659741;6252.249730032379;6257.155284036688;6070.514358851281;6253.374662533755;6266.185395898504;6278.466330361879;6284.685765711742;5701.087825947829;5737.196792449102;5775.971120144414;5785.71071446426;5834.766609335664;6413.518986076063;6417;6419.935800642027;6420.1974753155855;6420.967895160587;5950.464458198783;6043.546733994968;6118.20463339763;6127.142200091991;6129.019356902874;6171;6175.62946223219;6190.969045154746;6193.163922870355;6208.937910620836;6170.074488826712;6179.134921111048;6184.381561843779;6193.969030154821;6205.6586887721605;5972.522198224132;5986.07215881539;6143.631382117087;6186.319504854462;6197.659128747962;4650.767461626905;4681.695689780154;4686.742229177428;4733.5266473352385;4762.761861906891;4166.895827604334;4188.162367526507;4195.58044195578;4227.408162857202;4235.195312890541;5748.281464816898;5937.435943585352;5950.583459157904;5954.255718035246;5956.493698035629;6118.923762351576;6188.8762224755255;6194.628322300677;6286.182796236551;6302.054691796176;5947.018741907536;5947.970260148758;5956.493698035629;5963.179433054862;6003.5797494174985;5361.892762144736;5396.21754845553;5401.5948803839865;5401.675899446046;5409.675419474844;6154;6252.718627661766;6257.124002639634;6293.213348331458;6409.2949775525585;6198.597091189239;6199.876002480015;6205.317415084427;6208.689565521796;6213.440790328711;6343.3022367538615;6345.904811427925;6353.0152826312;6353.777617783356;6423.132877061536;5950.553708471795;5993.400659933972;6061.393860613904;6063.181470501602;6130.122981477994;6216.129741836056;6251.6561589113035;6261.436470717657;6267.592606480746;6269.843253918689;6078.574499784972;6114.6636934969;6135.14108024868;6139.109829074849;6141.048137538689;5969.7313620888035;6003.939960600513;6058;6062.030075187949;6134.283518479682;6074.058520929263;6078.483328917092;6078.848028799315;6080.179175811386;6081.5742897996715;6013.128096425906;6101.786437474844;6101.908471372844;6103.529224525481;6108.389161084034;5824.650520968586;5827.213326200908;5855.063189889597;5862.296524417097;5926.259217597801 17:28:34 INFO - PROCESS | 28482 | |1;ext.html;590.7548367427518;633.8415396150963;649.7595889520876;664.166471078796;672.9764458243963;34786.82606586967;46140.769296153514;48072;48137.277940830914;48148.999999999956;48626.75686621562;48657.51342486574;48993;49057.264141037915;49178.50821491784;8274.2966847818;9311.394759340621;9445.905540944572;9451.858222126639;10824.24230303879;10879.401632910187;11365.659030229106;11384.71538211543;11409.31544107352;11409.714757131096;11494.885051149507;11516.712082197928;11525.308481491096;11525.942370288138;11536.653900383002;11405.828912566318;11436.256643318158;11463.598774042952;11471.942640286828;11473.311601303907;11278.154138439568;11347.092232621371;11355.318680879174;11362.545498180063;11369.31784092957;44978.550214497765;45363.54636453626;45525.77237113793;45569.54430455686;45613.31580026302;3851.788151651658;4080.5715399882884;4580.610648094916;4683.906321873544;4713.6464765142755;35000.82499587486;35007.29985400278;35184.29631407383;35546.28907421863;37266.068348291235;4515.61617262533;4604.631629469668;4633.6061434778085;4640;4662.300654901758;399590;400860;401113;401186;401667;67624.3237567618;67920.6603966977;68066.319336806;68082;68184.65907670528;40297.194056118715;41159.7942010288;41282.1743565133;43230.35154472685;43341;19671;19866.1060252289;19873;19877.801221987884;19884.304049358492;12227.93886030582;14163.291835408188;14913.70172596542;15186.392544298325;15211.239438028055;21258.78741212599;21376.57246855054;21457.67813482799;21529.67705484419;21616.459588510414;7552.773416797505;7658.0694471503375;7720.53676779395;7723.150453450116;7784;1018.2312354172552;1053.257453495284;1110.9111271098293;1157.2188772578645;1171.917965742407;34851.825740871136;35723.82138089293;35992.280154396765;36150.45774313388;36524.81737591295;1144.4449442020614;1164.7903377392151;1230.6369620961839;1257.8867901888873;1290.5289569307322;17961.7305740414;18625.161867715986;19099.2360305589;19154.808451915582;19171;641554;642487;646263;650135;652916;66061.33938660551;66384;66833.33166668368;66915;67086;1326.7943468762357;1421.2822524624999;1424.8575142485668;1428.7071150414347;1442.35094207607;34933.301333973686;36669.816650916575;36901.44647830338;37209.81395093116;37412.25175496529;55648.44351556433;56274.718626406604;56282.71858640681;56405.15392269037;56456.717716411156;437015;438047;438244;439074;439716;28164.15507534777;30913.536296955113;31663.683363166074;32020.67979320177;33357.66642333545;53420.73289633527;53477.732611336694;53853;53938.73030634822;55272.17091743708;44652.81146964756;44828.32757508575;46031.30953035774;46998.53001469941;48093.27860082032;44641.98806130263;45762.54237457716;46179.30731039104;46488.767556162005;48046.27930581114;45020.77489612531;45491;45940.081198376516;46181.538184617726;46432;36829.815850920575;39650.801745991084;40636.59363406328;40663.39004914988;41137;30004.849975750854;30425;31032;31416.214594635316;31547 17:28:34 INFO - PROCESS | 28482 | |2;jquery.html;396.2016536678595;415.14909810780415;420.5521120007194;438.38844811487985;439.4155772822147;19303.806961930375;21074.99999999998;21151.576968460642;21876.671849922222;22111.889440552815;20486.078126484306;20941.895290523564;21023.369298921018;21111.788882111174;21183;5943.702814859251;6201.317855035943;6213.192285002943;6213.658248796337;6215.285242197137;6013.819585412444;6021.939780602203;6136.171616831715;6158.137860699506;6159.661218632973;6054.364291749369;6054.788082417116;6055.21282233309;6058.394160583928;6070.605410648316;5978.790742324019;6040.456358927696;6097.939020609803;6098.32918378978;6122.632642041447;5266.2890509781455;5496.148097044925;5519.779208831642;5526.143447765603;5560.304961879765;15233.695326093526;15490.9225453872;15490.9225453872;18217.998010109437;24582.508349833082;2412.8552286862846;2427.8179136564827;2447.290285817111;2533.2526904563197;2631.460550587125;10888.727781805439;10905.34567925927;11122.49948752308;11459.255148415326;12316.261024338479;1929.2765213045109;2006.8494862885343;2018.8485863560293;2039.9388018359473;2056.794320567931;63780.68109659422;81212;86893.13106868976;87676;88883;11744.119191060705;11778.646640600795;11931.284122952651;12160.331181785086;12161.878381216251;17076.146192690565;17076.743848842278;17096.572585685208;17109.14454277281;17119.914400428166;8664.696735614349;8687.782805429788;8698.217160455462;8727.869081963776;8822.294216462797;8232.423730338818;8248.587570621445;8450.87323690145;8456.619452124669;8483.490990540588;5159.277701121846;5213.322268105122;5218.269442278084;5230.770768869389;5346.278252435949;2062.8556001080083;2094.7276854008883;2139.721836161289;2151.6449785785485;2180.269609680775;913.9223166030962;1005.7385079879284;1015.4212099103588;1027.1269420992096;1120.5265076424475;19466.70799938002;20860.165593376398;21031.57936841285;21092;21454;1014.7564584499667;1024.0680980307884;1043.8695163104612;1063.2663462211049;1085.7394225385851;10732.901871610922;11662.53349865996;11863.64409067712;11884.34636095023;11987.34069626179;567560;579971;589912;589979;590904;97600.51199743956;100937;101656;104354.4782276099;106407;979.5787811241235;1001.1840350114779;1020.9234307426973;1029.264076185531;1047.4497743339748;18378.908105459388;18707.783994040343;18716.532086697942;18928.62142757165;19013.619727605095;31966;36367;47819.5218047829;48134;48297;64721.67639161774;64956.67521662361;65484.67257663681;66179.66910165419;67457.66271168613;8860.444019100463;9238.491882946502;9450.43297402158;9454.858177127211;9584.233261339215;12850.871491284968;13249.271290079138;13800.585982420544;13835.515756948458;14100.78848817248;12317.876821231674;12358.691032724253;12511.499540018298;12599.43302551369;12663.430145643284;12217.938910305747;12241.449134789134;12379.381030948596;12424.37878106073;12581.993440524919;12516.12387132892;12517;12647.747045058863;12667.113302069136;12733.681657958625;9289.117533834275;9316.44101353921;9386.389884657476;9698.418094914328;9750.366226195274;10789.838152427878;12246.938765306117;12468.875311247135;12532.749345012866;12603.49586016549 17:28:34 INFO - PROCESS | 28482 | |3;mootools.html;1443.797868298439;1528.365728222788;1562.4609509719146;1733.7226043832989;1850.2506484873632;9267.165955064043;9662.17871480924;9704.902950970489;9973.301868869183;9992.550335234913;9765.072318129784;9940.602375904973;9942.254330925174;9947.900520994788;9952.552135153917;2633.762961333475;2683.18162960297;2684.033747850772;2687.6371689821917;2689.1394753678733;2562.295368773593;2562.500312439075;2577.2010676690193;2581.1353196679133;2581.8709064546697;2621.30535408117;2633.302174923647;2677.4645070985785;2677.54481738104;2680.5175068487665;2533.7719605235525;2555.7955363571054;2559.398541342783;2562.449073449208;2565.204786516167;2573.4853029394008;2592.1057235253807;2607.569750991084;2610.4909542639193;2613.3335999320243;9790.706278811647;9948.651797187063;10011.14905233056;10013.048760367827;10018.549165287579;5873.148393482921;6036.5170786336985;6042.1238920356745;6096.573239873211;6101.816945491642;3843.673287770543;3879.844806207748;3907.1208977979727;3909.276783795007;3917.1186483041374;1193.9403029848559;1203.8916497515265;1216.2155409760744;1240.5596013415288;1247.9563215287421;14297.14217146964;14309.642258943506;14341.067830590979;14348.49780257686;14425.711485770331;4878.463369029475;4943.72809495474;5125.538701516882;5161.406438259648;5228.686278823283;10850.186236032328;10859.294145880493;10860.185486088574;10861.619843305447;10862.728431789268;5993.4905533029305;6001.5198784097165;6018.307894592091;6018.909716354258;6028.156058151862;4762.404699412574;4926.630502712311;5536.86157846049;5550.500454959073;5616.466435688603;4313.072689371737;4322.135572885435;4374.475062992461;4411.29419292918;4426.3581780641625;1303.1008604063165;1306.679863433446;1325.105553751214;1337.164272329794;1339.3504150487006;1029.5161274201114;1078.8813230544633;1083.6532309660834;1083.8428427877914;1085.6851513061124;6514.478841692748;6732.697028633724;6909.378155965886;6953.478489113337;6984.860302793915;509.6407033041697;529.2246858352532;530.8672831792052;532.1857557936363;543.7960764713232;4600.42494688164;4636.9536304637195;4708.364370809897;4715.858524244278;4720.12677654635;12469.625911222496;12690.365481725878;12691.746165076647;12850.100492965403;12937.741245175044;12477.376131193403;12794;12806.359682015862;12813;12813.551525696555;9361.157495825408;9474.099960503741;9484.810303793885;9495.335326527214;9495.525223738785;6877.552959057645;6905.930940690529;6910.965445172742;6924.307569243036;7014.929850701632;11362.943185284352;11675.88324116782;11731.472083756422;11751.882481175078;11845.407729613657;10588.576456941637;11373;11548.133889958455;11829.526818927146;12008.699782505508;8022.438429309892;8544.487330759926;8752.256058235122;8782.868256976279;9104.04407537199;9882.555285012048;9982.700518984442;10049.246306526893;10057.49712514386;10059;8814.2948564116;8941.552922353987;8985.865212021694;9099.727008189764;9208;8686.261667758312;8712.69505567302;8727.389082764146;8797.42816716937;8909.420887642282;7571.205023472456;7726.266004729542;7741.845163096594;7746.690132394867;7746.92253077462;5509.696966666872;5608.38307786135;5641.717914104196;5673.290838645169;5691.089425691869;5794.449527294901;5834.299884013945;5878.823635290777;5912.911306330495;5956.821295361146 17:28:34 INFO - PROCESS | 28482 | |4;prototype.html;332.36518250142234;348.62696914301694;352.62269371772203;369.8428168028587;373.67863637271944;5374.838754837351;5544.390117087128;5594.776208951642;5655.943440565593;5671.23438335824;5523.944760552393;5526.972365138179;5530.253415788868;5555.722213889309;5575.665460072399;4351;4351.216780979423;4365.476142862853;4391.86824395267;4397.736135831844;4205.7897105144775;4376.387305777193;4387.517373088957;4405.977970110161;4451.910961780763;4360.934585981213;4364.10535840152;4367.323064924942;4385.188740083079;4397.714148580348;4408.691391602589;4410.5368936261875;4417.22698527758;4437.755923424211;4456.8885777855485;3825.923481530354;3901.887961930836;3991.6806655467494;3994.580569040267;4002.1795531916177;5377.569794416477;5396.460353964611;5403.243545903577;5413.918791218135;5422.701751403672;4996;5016.398032236155;5018.272350509155;5032.917922646649;5065.265536497187;5652.09566469367;5699.74351154199;5812.564057695689;5819.243498345188;5860.97069514654;4279.2083464559155;4289.249381358281;4289.914201715979;4309.762963037032;4335.067960388532;6035.7887473938645;6076.240469941257;6082.847928801771;6091.812096641157;6127.325994140641;4347.543507931685;4400.691951563424;4449.599536041772;4454.3986561813745;4475.239209334421;4480.753558554311;4489.932651010237;4501.212287849613;4508.32375143731;4520.18636645407;5079.822206222764;5082.415522214918;5097.158968770185;5104.872378190575;5115.488451154854;5602.887942241132;5692.345380281238;5867;5882.823515294548;5943.64338139713;1513.3568233500719;1550.193899172424;1554.59309325061;1559.9220038998233;1563.2027665890478;962.8013123660946;986.3440811860177;988.3229987458614;993.6671215142864;1018.6536577563664;4968.90062198754;4969.27945447908;4974.626902982291;4983.476734942851;5009.874753131201;5219.8255392536375;5220;5242.659227150223;5251.684898906002;5261.500157485033;4663.696859704109;4962.354893863775;4980.950190498049;5015.774290156952;5027.622928280393;2773.778097752175;2785.6935737068907;2786.2616406652255;2792.2600510864636;2815.7888158388205;5329.387120481117;5397.7571009304675;5404.7297635118875;5417.485338892878;5428.45715428454;5228.660137091077;5288.603354748409;5642.266505354278;6118.632882027093;6137.846553836101;6239.660127452205;6241.469475094668;6241.812745617639;6246.281677607042;6255.843603909848;5826.475617194304;5932.940670593239;6031.276246850406;6037.45662890342;6052.122442245762;5898.852528686817;5963.970180149072;5964.254468191476;5969;5976.133460648269;6228.131560531913;6239.126522286792;6239.282482514387;6247.0629405589525;6248.593841400295;4131.607497287753;4287.935680964851;4335.393044973769;4351.173277077345;4354.04211073576;5815.41845815415;5968.4926781224085;5986.491148252448;5994.580379373577;5997.640141591519;5864.794732184353;5886.323072846678;5914.526837853047;5916.792912248051;5966.880662386815;5782.5373970081455;5845.860057288788;5865.384134665798;5883.293844785136;5888.293404791452;5679.744411501575;5806.44838740319;5862.3844496328975;5874.500667443144;5885.882282354243;5861.677607731615;5912.970435147797;5950.9702451487465;5951.761929522944;5960.493358064613;5635.549156067423;5737.91393129095;5773.6824474654295;5774.162746401664;5864.765409383577 17:28:34 INFO - PROCESS | 28482 | |5;yui.html;807.5800583696478;825.6078362777678;851.7870532366908;873.6767396063457;906.3791302957475;16618.418355357564;17809.198586063627;17839.375621853236;17906.194221260026;17910.462686119405;15814.683706325895;16033.599160021005;16044.037357758543;16051.277692503836;16066.75899861503;731.4952682648982;858.935579831512;878.3719640457088;896.238197532099;897.8922529296461;887.1394747095308;888.0542222533014;890.0743227043866;890.514669505118;891.0910870911666;943.2642538819731;944.329526036515;948.1466679987997;949.4018768176039;949.7957939043105;935.4434111703525;936.091990768953;937.7327461673391;938.1181689212119;938.1744065222629;686.8797960356918;688.896665500174;692.2281655953626;694.2467422846217;694.4826104552141;15937;15940.521784346489;15941.043537387677;15944.760828587581;15973.440929567525;15289.541313760605;15361;15405.145671192955;15859.762103568455;15917.602059948478;14088.436462541486;14192.219427931459;14280.571582852532;14379.209143497104;14746.336414861356;4958.702477851304;5104.336436263299;5150.995555866608;5225.08561001827;5271.341082364705;89695.55152224327;91242.5437872813;91971.54014229953;93000;93078.53460732721;12439.813402798965;13186.406611702496;13295.468181272641;13372.665683357995;13396.330183490787;24465.510689786104;24534.386640333778;24536.509269814505;24577.631335529983;24623.630645540332;9256.676016339396;9440.622375104918;9667.854982175273;9859.112679858847;9957.900420995697;4975.427825800007;5110.897782044412;5265.52610265078;5368.785248590091;5391.838244852661;3708.1100535871738;3812.5806161322234;3815.4467602197524;3821.6560509554274;3826.1199924017633;644.8097811145723;654.9279579246279;665.4244078871741;674.1707699529607;682.7405585877335;683.4737252315689;698.4970821008866;749.8575270698659;761.9390448764085;772.764306886405;10137.645182418728;10272.33229840058;10377;10405.271630985759;10803.783924321628;736.2784471218237;756.2626439221825;756.8940348351235;759.8556274307974;776.9456138070394;4608.400907881954;4928;5650.830475085754;5736.397678243808;5861.413858614104;96821.51589242008;121842;127654.36172819076;129824;131920;102879;105069;105930.47034764777;106738;108822;86706;88499;90401;91196.08803912009;92809.53595231981;10291;10963.287386319864;11136.387498687325;11156;11439.828402573801;46879.29681054719;50237.24644130414;51407.485925141766;51500.742496287276;53369.19946200888;71988.28011719816;72006;79854;80352.19647803447;82609;13876.930615346859;13985.370658320604;14111.364988575333;14120;14143;16010.839891601401;16222.51332460028;16291.266892989606;17480;17739.556511087325;14039.789403159166;14105.929470352583;14286.214258215483;14607.853921461074;15028.849711502746;15328.46350377776;15370.46203382921;15492.90863169225;15630;15850;13886.444542218198;14204.715905682035;14667.633309167357;14738.484153054591;15078.773818392514;12423.751524969632;12654;12893.419796109385;12933.159344642569;13521;13184.802227966396;13313.800292995806;13458.798118028433;13468.797968030683;13478.932605336911 17:28:34 INFO - PROCESS | 28482 | __end_tp_report 17:28:34 INFO - PROCESS | 28482 | __start_cc_report 17:28:34 INFO - PROCESS | 28482 | _x_x_mozilla_cycle_collect,974 17:28:34 INFO - PROCESS | 28482 | __end_cc_report 17:28:34 INFO - PROCESS | 28482 | __startTimestamp1475108914643__endTimestamp 17:28:34 INFO - PROCESS | 28482 | 17:28:34 INFO - PROCESS | 28482 | ------- Summary: start ------- 17:28:34 INFO - PROCESS | 28482 | Number of tests: 6 17:28:34 INFO - PROCESS | 28482 | 17:28:34 INFO - PROCESS | 28482 | [#0] dojo.html Cycles:165 Average:5748.88 Median:5972.52 stddev:666.45 (11.2%) stddev-sans-first:637.99 17:28:34 INFO - PROCESS | 28482 | Values: 3207.8 3218.3 3232.6 3251.6 3272.9 5646.3 5739.9 5748.2 5778.9 5784.9 5770.1 5831.5 5849.9 5850.1 5853.7 5262.9 5315.7 5320.5 5341.4 5354.6 5191.7 5375.6 5381.9 5397.6 5402.9 5101.8 5144.4 5201.6 5214.2 5226.9 5223.2 5247.5 5283.4 5357.2 5364.8 5199.3 5242.2 5311.4 5323.8 5325.1 5574.3 5599.0 5618.7 5622.3 5636.8 6087.9 6123.1 6251.7 6252.2 6257.2 6070.5 6253.4 6266.2 6278.5 6284.7 5701.1 5737.2 5776.0 5785.7 5834.8 6413.5 6417.0 6419.9 6420.2 6421.0 5950.5 6043.5 6118.2 6127.1 6129.0 6171.0 6175.6 6191.0 6193.2 6208.9 6170.1 6179.1 6184.4 6194.0 6205.7 5972.5 5986.1 6143.6 6186.3 6197.7 4650.8 4681.7 4686.7 4733.5 4762.8 4166.9 4188.2 4195.6 4227.4 4235.2 5748.3 5937.4 5950.6 5954.3 5956.5 6118.9 6188.9 6194.6 6286.2 6302.1 5947.0 5948.0 5956.5 5963.2 6003.6 5361.9 5396.2 5401.6 5401.7 5409.7 6154.0 6252.7 6257.1 6293.2 6409.3 6198.6 6199.9 6205.3 6208.7 6213.4 6343.3 6345.9 6353.0 6353.8 6423.1 5950.6 5993.4 6061.4 6063.2 6130.1 6216.1 6251.7 6261.4 6267.6 6269.8 6078.6 6114.7 6135.1 6139.1 6141.0 5969.7 6003.9 6058.0 6062.0 6134.3 6074.1 6078.5 6078.8 6080.2 6081.6 6013.1 6101.8 6101.9 6103.5 6108.4 5824.7 5827.2 5855.1 5862.3 5926.3 17:28:34 INFO - PROCESS | 28482 | 17:28:34 INFO - PROCESS | 28482 | [#1] ext.html Cycles:180 Average:66964.15 Median:40466.89 stddev:134642.45 (332.7%) stddev-sans-first:134927.93 17:28:34 INFO - PROCESS | 28482 | Values: 590.8 633.8 649.8 664.2 673.0 34786.8 46140.8 48072.0 48137.3 48149.0 48626.8 48657.5 48993.0 49057.3 49178.5 8274.3 9311.4 9445.9 9451.9 10824.2 10879.4 11365.7 11384.7 11409.3 11409.7 11494.9 11516.7 11525.3 11525.9 11536.7 11405.8 11436.3 11463.6 11471.9 11473.3 11278.2 11347.1 11355.3 11362.5 11369.3 44978.6 45363.5 45525.8 45569.5 45613.3 3851.8 4080.6 4580.6 4683.9 4713.6 35000.8 35007.3 35184.3 35546.3 37266.1 4515.6 4604.6 4633.6 4640.0 4662.3 399590.0 400860.0 401113.0 401186.0 401667.0 67624.3 67920.7 68066.3 68082.0 68184.7 40297.2 41159.8 41282.2 43230.4 43341.0 19671.0 19866.1 19873.0 19877.8 19884.3 12227.9 14163.3 14913.7 15186.4 15211.2 21258.8 21376.6 21457.7 21529.7 21616.5 7552.8 7658.1 7720.5 7723.2 7784.0 1018.2 1053.3 1110.9 1157.2 1171.9 34851.8 35723.8 35992.3 36150.5 36524.8 1144.4 1164.8 1230.6 1257.9 1290.5 17961.7 18625.2 19099.2 19154.8 19171.0 641554.0 642487.0 646263.0 650135.0 652916.0 66061.3 66384.0 66833.3 66915.0 67086.0 1326.8 1421.3 1424.9 1428.7 1442.4 34933.3 36669.8 36901.4 37209.8 37412.3 55648.4 56274.7 56282.7 56405.2 56456.7 437015.0 438047.0 438244.0 439074.0 439716.0 28164.2 30913.5 31663.7 32020.7 33357.7 53420.7 53477.7 53853.0 53938.7 55272.2 44652.8 44828.3 46031.3 46998.5 48093.3 44642.0 45762.5 46179.3 46488.8 48046.3 45020.8 45491.0 45940.1 46181.5 46432.0 36829.8 39650.8 40636.6 40663.4 41137.0 30004.8 30425.0 31032.0 31416.2 31547.0 17:28:34 INFO - PROCESS | 28482 | 17:28:34 INFO - PROCESS | 28482 | [#2] jquery.html Cycles:180 Average:32541.99 Median:11796.86 stddev:96018.78 (813.9%) stddev-sans-first:96257.80 17:28:34 INFO - PROCESS | 28482 | Values: 396.2 415.1 420.6 438.4 439.4 19303.8 21075.0 21151.6 21876.7 22111.9 20486.1 20941.9 21023.4 21111.8 21183.0 5943.7 6201.3 6213.2 6213.7 6215.3 6013.8 6021.9 6136.2 6158.1 6159.7 6054.4 6054.8 6055.2 6058.4 6070.6 5978.8 6040.5 6097.9 6098.3 6122.6 5266.3 5496.1 5519.8 5526.1 5560.3 15233.7 15490.9 15490.9 18218.0 24582.5 2412.9 2427.8 2447.3 2533.3 2631.5 10888.7 10905.3 11122.5 11459.3 12316.3 1929.3 2006.8 2018.8 2039.9 2056.8 63780.7 81212.0 86893.1 87676.0 88883.0 11744.1 11778.6 11931.3 12160.3 12161.9 17076.1 17076.7 17096.6 17109.1 17119.9 8664.7 8687.8 8698.2 8727.9 8822.3 8232.4 8248.6 8450.9 8456.6 8483.5 5159.3 5213.3 5218.3 5230.8 5346.3 2062.9 2094.7 2139.7 2151.6 2180.3 913.9 1005.7 1015.4 1027.1 1120.5 19466.7 20860.2 21031.6 21092.0 21454.0 1014.8 1024.1 1043.9 1063.3 1085.7 10732.9 11662.5 11863.6 11884.3 11987.3 567560.0 579971.0 589912.0 589979.0 590904.0 97600.5 100937.0 101656.0 104354.5 106407.0 979.6 1001.2 1020.9 1029.3 1047.4 18378.9 18707.8 18716.5 18928.6 19013.6 31966.0 36367.0 47819.5 48134.0 48297.0 64721.7 64956.7 65484.7 66179.7 67457.7 8860.4 9238.5 9450.4 9454.9 9584.2 12850.9 13249.3 13800.6 13835.5 14100.8 12317.9 12358.7 12511.5 12599.4 12663.4 12217.9 12241.4 12379.4 12424.4 12582.0 12516.1 12517.0 12647.7 12667.1 12733.7 9289.1 9316.4 9386.4 9698.4 9750.4 10789.8 12246.9 12468.9 12532.7 12603.5 17:28:34 INFO - PROCESS | 28482 | 17:28:34 INFO - PROCESS | 28482 | [#3] mootools.html Cycles:180 Average:6566.52 Median:4513.39 stddev:3818.91 (84.6%) stddev-sans-first:3810.22 17:28:34 INFO - PROCESS | 28482 | Values: 1443.8 1528.4 1562.5 1733.7 1850.3 9267.2 9662.2 9704.9 9973.3 9992.6 9765.1 9940.6 9942.3 9947.9 9952.6 2633.8 2683.2 2684.0 2687.6 2689.1 2562.3 2562.5 2577.2 2581.1 2581.9 2621.3 2633.3 2677.5 2677.5 2680.5 2533.8 2555.8 2559.4 2562.4 2565.2 2573.5 2592.1 2607.6 2610.5 2613.3 9790.7 9948.7 10011.1 10013.0 10018.5 5873.1 6036.5 6042.1 6096.6 6101.8 3843.7 3879.8 3907.1 3909.3 3917.1 1193.9 1203.9 1216.2 1240.6 1248.0 14297.1 14309.6 14341.1 14348.5 14425.7 4878.5 4943.7 5125.5 5161.4 5228.7 10850.2 10859.3 10860.2 10861.6 10862.7 5993.5 6001.5 6018.3 6018.9 6028.2 4762.4 4926.6 5536.9 5550.5 5616.5 4313.1 4322.1 4374.5 4411.3 4426.4 1303.1 1306.7 1325.1 1337.2 1339.4 1029.5 1078.9 1083.7 1083.8 1085.7 6514.5 6732.7 6909.4 6953.5 6984.9 509.6 529.2 530.9 532.2 543.8 4600.4 4637.0 4708.4 4715.9 4720.1 12469.6 12690.4 12691.7 12850.1 12937.7 12477.4 12794.0 12806.4 12813.0 12813.6 9361.2 9474.1 9484.8 9495.3 9495.5 6877.6 6905.9 6911.0 6924.3 7014.9 11362.9 11675.9 11731.5 11751.9 11845.4 10588.6 11373.0 11548.1 11829.5 12008.7 8022.4 8544.5 8752.3 8782.9 9104.0 9882.6 9982.7 10049.2 10057.5 10059.0 8814.3 8941.6 8985.9 9099.7 9208.0 8686.3 8712.7 8727.4 8797.4 8909.4 7571.2 7726.3 7741.8 7746.7 7746.9 5509.7 5608.4 5641.7 5673.3 5691.1 5794.4 5834.3 5878.8 5912.9 5956.8 17:28:34 INFO - PROCESS | 28482 | 17:28:34 INFO - PROCESS | 28482 | [#4] prototype.html Cycles:180 Average:4856.56 Median:5387.02 stddev:1413.93 (26.2%) stddev-sans-first:1376.52 17:28:34 INFO - PROCESS | 28482 | Values: 332.4 348.6 352.6 369.8 373.7 5374.8 5544.4 5594.8 5655.9 5671.2 5523.9 5527.0 5530.3 5555.7 5575.7 4351.0 4351.2 4365.5 4391.9 4397.7 4205.8 4376.4 4387.5 4406.0 4451.9 4360.9 4364.1 4367.3 4385.2 4397.7 4408.7 4410.5 4417.2 4437.8 4456.9 3825.9 3901.9 3991.7 3994.6 4002.2 5377.6 5396.5 5403.2 5413.9 5422.7 4996.0 5016.4 5018.3 5032.9 5065.3 5652.1 5699.7 5812.6 5819.2 5861.0 4279.2 4289.2 4289.9 4309.8 4335.1 6035.8 6076.2 6082.8 6091.8 6127.3 4347.5 4400.7 4449.6 4454.4 4475.2 4480.8 4489.9 4501.2 4508.3 4520.2 5079.8 5082.4 5097.2 5104.9 5115.5 5602.9 5692.3 5867.0 5882.8 5943.6 1513.4 1550.2 1554.6 1559.9 1563.2 962.8 986.3 988.3 993.7 1018.7 4968.9 4969.3 4974.6 4983.5 5009.9 5219.8 5220.0 5242.7 5251.7 5261.5 4663.7 4962.4 4981.0 5015.8 5027.6 2773.8 2785.7 2786.3 2792.3 2815.8 5329.4 5397.8 5404.7 5417.5 5428.5 5228.7 5288.6 5642.3 6118.6 6137.8 6239.7 6241.5 6241.8 6246.3 6255.8 5826.5 5932.9 6031.3 6037.5 6052.1 5898.9 5964.0 5964.3 5969.0 5976.1 6228.1 6239.1 6239.3 6247.1 6248.6 4131.6 4287.9 4335.4 4351.2 4354.0 5815.4 5968.5 5986.5 5994.6 5997.6 5864.8 5886.3 5914.5 5916.8 5966.9 5782.5 5845.9 5865.4 5883.3 5888.3 5679.7 5806.4 5862.4 5874.5 5885.9 5861.7 5913.0 5951.0 5951.8 5960.5 5635.5 5737.9 5773.7 5774.2 5864.8 17:28:34 INFO - PROCESS | 28482 | 17:28:34 INFO - PROCESS | 28482 | [#5] yui.html Cycles:180 Average:22606.08 Median:3814.01 stddev:32071.62 (840.9%) stddev-sans-first:32119.82 17:28:34 INFO - PROCESS | 28482 | Values: 807.6 825.6 851.8 873.7 906.4 16618.4 17809.2 17839.4 17906.2 17910.5 15814.7 16033.6 16044.0 16051.3 16066.8 731.5 858.9 878.4 896.2 897.9 887.1 888.1 890.1 890.5 891.1 943.3 944.3 948.1 949.4 949.8 935.4 936.1 937.7 938.1 938.2 686.9 688.9 692.2 694.2 694.5 15937.0 15940.5 15941.0 15944.8 15973.4 15289.5 15361.0 15405.1 15859.8 15917.6 14088.4 14192.2 14280.6 14379.2 14746.3 4958.7 5104.3 5151.0 5225.1 5271.3 89695.6 91242.5 91971.5 93000.0 93078.5 12439.8 13186.4 13295.5 13372.7 13396.3 24465.5 24534.4 24536.5 24577.6 24623.6 9256.7 9440.6 9667.9 9859.1 9957.9 4975.4 5110.9 5265.5 5368.8 5391.8 3708.1 3812.6 3815.4 3821.7 3826.1 644.8 654.9 665.4 674.2 682.7 683.5 698.5 749.9 761.9 772.8 10137.6 10272.3 10377.0 10405.3 10803.8 736.3 756.3 756.9 759.9 776.9 4608.4 4928.0 5650.8 5736.4 5861.4 96821.5 121842.0 127654.4 129824.0 131920.0 102879.0 105069.0 105930.5 106738.0 108822.0 86706.0 88499.0 90401.0 91196.1 92809.5 10291.0 10963.3 11136.4 11156.0 11439.8 46879.3 50237.2 51407.5 51500.7 53369.2 71988.3 72006.0 79854.0 80352.2 82609.0 13876.9 13985.4 14111.4 14120.0 14143.0 16010.8 16222.5 16291.3 17480.0 17739.6 14039.8 14105.9 14286.2 14607.9 15028.8 15328.5 15370.5 15492.9 15630.0 15850.0 13886.4 14204.7 14667.6 14738.5 15078.8 12423.8 12654.0 12893.4 12933.2 13521.0 13184.8 13313.8 13458.8 13468.8 13478.9 17:28:34 INFO - PROCESS | 28482 | -------- Summary: end -------- 17:28:34 INFO - PROCESS | 28482 | 17:28:35 INFO - TEST-INFO | 28482: exit 0 17:28:35 INFO - TEST-OK | dromaeo_css | took 1113045ms 17:28:35 INFO - TEST-START | kraken 17:28:35 INFO - operating with platform_type : linux_ 17:28:35 INFO - Initialising browser for kraken test... 17:28:35 INFO - TEST-INFO | started process 28677 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpib4LEX/profile http://localhost:33023/getInfo.html) 17:28:39 INFO - PROCESS | 28677 | __metrics Screen width/height:1600/1200 17:28:39 INFO - PROCESS | 28677 | colorDepth:24 17:28:39 INFO - PROCESS | 28677 | Browser inner width/height: 1024/697 17:28:39 INFO - PROCESS | 28677 | __metrics 17:28:40 INFO - PROCESS | 28677 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:28:40 INFO - PROCESS | 28677 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:28:41 INFO - TEST-INFO | 28677: exit 0 17:28:41 INFO - Browser initialized. 17:28:41 INFO - Running cycle 1/1 for kraken test... 17:28:41 INFO - TEST-INFO | started process 28761 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpib4LEX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:28:53 INFO - PROCESS | 28761 | RSS: Main: 143474688 17:28:53 INFO - PROCESS | 28761 | 17:28:55 INFO - PROCESS | 28761 | Cycle 1(1): loaded http://localhost:33023/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33023/tests/kraken/driver.html?testName=audio-beat-detection) 17:28:56 INFO - PROCESS | 28761 | RSS: Main: 199266304 17:28:56 INFO - PROCESS | 28761 | 17:28:59 INFO - PROCESS | 28761 | Cycle 1(1): loaded http://localhost:33023/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33023/tests/kraken/driver.html?testName=audio-dft) 17:28:59 INFO - PROCESS | 28761 | RSS: Main: 182751232 17:28:59 INFO - PROCESS | 28761 | 17:29:02 INFO - PROCESS | 28761 | Cycle 1(1): loaded http://localhost:33023/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33023/tests/kraken/driver.html?testName=audio-fft) 17:29:03 INFO - PROCESS | 28761 | RSS: Main: 274907136 17:29:03 INFO - PROCESS | 28761 | 17:29:05 INFO - PROCESS | 28761 | Cycle 1(1): loaded http://localhost:33023/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33023/tests/kraken/driver.html?testName=audio-oscillator) 17:29:05 INFO - PROCESS | 28761 | RSS: Main: 180469760 17:29:05 INFO - PROCESS | 28761 | 17:29:07 INFO - PROCESS | 28761 | Cycle 1(1): loaded http://localhost:33023/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33023/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:29:08 INFO - PROCESS | 28761 | RSS: Main: 178429952 17:29:08 INFO - PROCESS | 28761 | 17:29:11 INFO - PROCESS | 28761 | Cycle 1(1): loaded http://localhost:33023/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33023/tests/kraken/driver.html?testName=imaging-darkroom) 17:29:12 INFO - PROCESS | 28761 | RSS: Main: 214753280 17:29:12 INFO - PROCESS | 28761 | 17:29:15 INFO - PROCESS | 28761 | Cycle 1(1): loaded http://localhost:33023/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33023/tests/kraken/driver.html?testName=imaging-desaturate) 17:29:16 INFO - PROCESS | 28761 | RSS: Main: 213753856 17:29:16 INFO - PROCESS | 28761 | 17:29:18 INFO - PROCESS | 28761 | Cycle 1(1): loaded http://localhost:33023/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33023/tests/kraken/driver.html?testName=json-parse-financial) 17:29:19 INFO - PROCESS | 28761 | RSS: Main: 213397504 17:29:19 INFO - PROCESS | 28761 | 17:29:20 INFO - PROCESS | 28761 | Cycle 1(1): loaded http://localhost:33023/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33023/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:29:21 INFO - PROCESS | 28761 | RSS: Main: 175820800 17:29:21 INFO - PROCESS | 28761 | 17:29:23 INFO - PROCESS | 28761 | Cycle 1(1): loaded http://localhost:33023/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33023/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:29:23 INFO - PROCESS | 28761 | RSS: Main: 182968320 17:29:23 INFO - PROCESS | 28761 | 17:29:25 INFO - PROCESS | 28761 | Cycle 1(1): loaded http://localhost:33023/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33023/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:29:26 INFO - PROCESS | 28761 | RSS: Main: 184389632 17:29:26 INFO - PROCESS | 28761 | 17:29:28 INFO - PROCESS | 28761 | Cycle 1(1): loaded http://localhost:33023/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33023/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:29:29 INFO - PROCESS | 28761 | RSS: Main: 185110528 17:29:29 INFO - PROCESS | 28761 | 17:29:32 INFO - PROCESS | 28761 | Cycle 1(1): loaded http://localhost:33023/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33023/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:29:32 INFO - PROCESS | 28761 | RSS: Main: 183402496 17:29:32 INFO - PROCESS | 28761 | 17:29:34 INFO - PROCESS | 28761 | Cycle 1(1): loaded http://localhost:33023/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33023/tests/kraken/driver.html?testName=ai-astar) 17:29:34 INFO - PROCESS | 28761 | RSS: Main: 234000384 17:29:34 INFO - PROCESS | 28761 | 17:29:34 INFO - PROCESS | 28761 | __start_tp_report 17:29:34 INFO - PROCESS | 28761 | _x_x_mozilla_page_load 17:29:34 INFO - PROCESS | 28761 | _x_x_mozilla_page_load_details 17:29:34 INFO - PROCESS | 28761 | |i|pagename|runs| 17:29:34 INFO - PROCESS | 28761 | |0;ai-astar;113;114;123;111;112;112;110;111;111;111 17:29:34 INFO - PROCESS | 28761 | |1;audio-beat-detection;143;115;140;185;116;143;150;121;168;115 17:29:34 INFO - PROCESS | 28761 | |2;audio-dft;162;160;161;162;161;162;160;160;160;161 17:29:34 INFO - PROCESS | 28761 | |3;audio-fft;74;74;84;184;76;75;74;74;154;75 17:29:34 INFO - PROCESS | 28761 | |4;audio-oscillator;83;83;154;84;84;83;143;82;83;148 17:29:34 INFO - PROCESS | 28761 | |5;imaging-gaussian-blur;116;117;117;116;117;117;117;117;116;117 17:29:34 INFO - PROCESS | 28761 | |6;imaging-darkroom;146;146;145;146;146;146;146;147;145;146 17:29:34 INFO - PROCESS | 28761 | |7;imaging-desaturate;94;94;95;94;95;95;94;95;95;95 17:29:34 INFO - PROCESS | 28761 | |8;json-parse-financial;50;51;50;51;50;54;52;50;51;51 17:29:34 INFO - PROCESS | 28761 | |9;json-stringify-tinderbox;59;59;61;58;59;59;60;59;58;60 17:29:34 INFO - PROCESS | 28761 | |10;stanford-crypto-aes;74;72;73;72;77;72;77;72;71;72 17:29:34 INFO - PROCESS | 28761 | |11;stanford-crypto-ccm;119;133;129;128;117;125;116;118;161;134 17:29:34 INFO - PROCESS | 28761 | |12;stanford-crypto-pbkdf2;160;151;152;153;151;152;156;154;151;152 17:29:34 INFO - PROCESS | 28761 | |13;stanford-crypto-sha256-iterative;61;63;65;61;61;61;63;62;63;60 17:29:34 INFO - PROCESS | 28761 | __end_tp_report 17:29:34 INFO - PROCESS | 28761 | __start_cc_report 17:29:34 INFO - PROCESS | 28761 | _x_x_mozilla_cycle_collect,1668 17:29:34 INFO - PROCESS | 28761 | __end_cc_report 17:29:34 INFO - PROCESS | 28761 | __startTimestamp1475108974575__endTimestamp 17:29:34 INFO - PROCESS | 28761 | 17:29:34 INFO - PROCESS | 28761 | ------- Summary: start ------- 17:29:34 INFO - PROCESS | 28761 | Number of tests: 14 17:29:34 INFO - PROCESS | 28761 | 17:29:34 INFO - PROCESS | 28761 | [#0] ai-astar Cycles:10 Average:112.80 Median:112.00 stddev:3.77 (3.4%) stddev-sans-first:3.99 17:29:34 INFO - PROCESS | 28761 | Values: 113.0 114.0 123.0 111.0 112.0 112.0 110.0 111.0 111.0 111.0 17:29:34 INFO - PROCESS | 28761 | 17:29:34 INFO - PROCESS | 28761 | [#1] audio-beat-detection Cycles:10 Average:139.60 Median:143.00 stddev:23.83 (16.7%) stddev-sans-first:25.25 17:29:34 INFO - PROCESS | 28761 | Values: 143.0 115.0 140.0 185.0 116.0 143.0 150.0 121.0 168.0 115.0 17:29:34 INFO - PROCESS | 28761 | 17:29:34 INFO - PROCESS | 28761 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.88 (0.5%) stddev-sans-first:0.83 17:29:34 INFO - PROCESS | 28761 | Values: 162.0 160.0 161.0 162.0 161.0 162.0 160.0 160.0 160.0 161.0 17:29:34 INFO - PROCESS | 28761 | 17:29:34 INFO - PROCESS | 28761 | [#3] audio-fft Cycles:10 Average:94.40 Median:74.50 stddev:40.06 (53.8%) stddev-sans-first:41.81 17:29:34 INFO - PROCESS | 28761 | Values: 74.0 74.0 84.0 184.0 76.0 75.0 74.0 74.0 154.0 75.0 17:29:34 INFO - PROCESS | 28761 | 17:29:34 INFO - PROCESS | 28761 | [#4] audio-oscillator Cycles:10 Average:102.70 Median:83.00 stddev:31.60 (38.1%) stddev-sans-first:32.70 17:29:34 INFO - PROCESS | 28761 | Values: 83.0 83.0 154.0 84.0 84.0 83.0 143.0 82.0 83.0 148.0 17:29:34 INFO - PROCESS | 28761 | 17:29:34 INFO - PROCESS | 28761 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 17:29:34 INFO - PROCESS | 28761 | Values: 116.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 17:29:34 INFO - PROCESS | 28761 | 17:29:34 INFO - PROCESS | 28761 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 17:29:34 INFO - PROCESS | 28761 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 146.0 147.0 145.0 146.0 17:29:34 INFO - PROCESS | 28761 | 17:29:34 INFO - PROCESS | 28761 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 17:29:34 INFO - PROCESS | 28761 | Values: 94.0 94.0 95.0 94.0 95.0 95.0 94.0 95.0 95.0 95.0 17:29:34 INFO - PROCESS | 28761 | 17:29:34 INFO - PROCESS | 28761 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.00 stddev:1.25 (2.4%) stddev-sans-first:1.27 17:29:34 INFO - PROCESS | 28761 | Values: 50.0 51.0 50.0 51.0 50.0 54.0 52.0 50.0 51.0 51.0 17:29:34 INFO - PROCESS | 28761 | 17:29:34 INFO - PROCESS | 28761 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:59.00 stddev:0.92 (1.6%) stddev-sans-first:0.97 17:29:34 INFO - PROCESS | 28761 | Values: 59.0 59.0 61.0 58.0 59.0 59.0 60.0 59.0 58.0 60.0 17:29:34 INFO - PROCESS | 28761 | 17:29:34 INFO - PROCESS | 28761 | [#10] stanford-crypto-aes Cycles:10 Average:73.20 Median:72.50 stddev:2.15 (3.0%) stddev-sans-first:2.26 17:29:34 INFO - PROCESS | 28761 | Values: 74.0 72.0 73.0 72.0 77.0 72.0 77.0 72.0 71.0 72.0 17:29:34 INFO - PROCESS | 28761 | 17:29:34 INFO - PROCESS | 28761 | [#11] stanford-crypto-ccm Cycles:10 Average:128.00 Median:128.50 stddev:13.36 (10.4%) stddev-sans-first:13.77 17:29:34 INFO - PROCESS | 28761 | Values: 119.0 133.0 129.0 128.0 117.0 125.0 116.0 118.0 161.0 134.0 17:29:34 INFO - PROCESS | 28761 | 17:29:34 INFO - PROCESS | 28761 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.20 Median:152.50 stddev:2.86 (1.9%) stddev-sans-first:1.67 17:29:34 INFO - PROCESS | 28761 | Values: 160.0 151.0 152.0 153.0 151.0 152.0 156.0 154.0 151.0 152.0 17:29:34 INFO - PROCESS | 28761 | 17:29:34 INFO - PROCESS | 28761 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.00 Median:62.50 stddev:1.49 (2.4%) stddev-sans-first:1.54 17:29:34 INFO - PROCESS | 28761 | Values: 61.0 63.0 65.0 61.0 61.0 61.0 63.0 62.0 63.0 60.0 17:29:34 INFO - PROCESS | 28761 | -------- Summary: end -------- 17:29:34 INFO - PROCESS | 28761 | 17:29:35 INFO - TEST-INFO | 28761: exit 0 17:29:35 INFO - TEST-OK | kraken | took 60151ms 17:29:35 INFO - SUITE-END | took 1173s 17:29:35 INFO - Completed test suite (00:19:33) 17:29:35 INFO - javascript benchmark 17:29:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3207.7594180436467, 3218.2758879252165, 3232.595925509311, 3251.6098068231818, 3272.869085236589, 5646.265985421895, 5739.913901291484, 5748.19525266463, 5778.942210577893, 5784.913226301604, 5770.105633626784, 5831.533477321815, 5849.912251316223, 5850.0932355484965, 5853.7365818538165, 5262.9473705263035, 5315.654482458647, 5320.494553017457, 5341.385740639818, 5354.625176237665, 5191.6884986900905, 5375.569954403658, 5381.946180538203, 5397.62216644835, 5402.864928376783, 5101.795928162887, 5144.356955380577, 5201.609879259052, 5214.217867319894, 5226.92159617606, 5223.2165175223645, 5247.501487358714, 5283.445238249967, 5357.196420536911, 5364.8122315718765, 5199.298094757197, 5242.18746094356, 5311.415744268127, 5323.813666521653, 5325.121354976424, 5574.303212098488, 5599.020171469976, 5618.719064046782, 5622.3253209614695, 5636.830895073154, 6087.878242435171, 6123.112148738453, 6251.718672659741, 6252.249730032379, 6257.155284036688, 6070.514358851281, 6253.374662533755, 6266.185395898504, 6278.466330361879, 6284.685765711742, 5701.087825947829, 5737.196792449102, 5775.971120144414, 5785.71071446426, 5834.766609335664, 6413.518986076063, 6417.0, 6419.935800642027, 6420.1974753155855, 6420.967895160587, 5950.464458198783, 6043.546733994968, 6118.20463339763, 6127.142200091991, 6129.019356902874, 6171.0, 6175.62946223219, 6190.969045154746, 6193.163922870355, 6208.937910620836, 6170.074488826712, 6179.134921111048, 6184.381561843779, 6193.969030154821, 6205.6586887721605, 5972.522198224132, 5986.07215881539, 6143.631382117087, 6186.319504854462, 6197.659128747962, 4650.767461626905, 4681.695689780154, 4686.742229177428, 4733.5266473352385, 4762.761861906891, 4166.895827604334, 4188.162367526507, 4195.58044195578, 4227.408162857202, 4235.195312890541, 5748.281464816898, 5937.435943585352, 5950.583459157904, 5954.255718035246, 5956.493698035629, 6118.923762351576, 6188.8762224755255, 6194.628322300677, 6286.182796236551, 6302.054691796176, 5947.018741907536, 5947.970260148758, 5956.493698035629, 5963.179433054862, 6003.5797494174985, 5361.892762144736, 5396.21754845553, 5401.5948803839865, 5401.675899446046, 5409.675419474844, 6154.0, 6252.718627661766, 6257.124002639634, 6293.213348331458, 6409.2949775525585, 6198.597091189239, 6199.876002480015, 6205.317415084427, 6208.689565521796, 6213.440790328711, 6343.3022367538615, 6345.904811427925, 6353.0152826312, 6353.777617783356, 6423.132877061536, 5950.553708471795, 5993.400659933972, 6061.393860613904, 6063.181470501602, 6130.122981477994, 6216.129741836056, 6251.6561589113035, 6261.436470717657, 6267.592606480746, 6269.843253918689, 6078.574499784972, 6114.6636934969, 6135.14108024868, 6139.109829074849, 6141.048137538689, 5969.7313620888035, 6003.939960600513, 6058.0, 6062.030075187949, 6134.283518479682, 6074.058520929263, 6078.483328917092, 6078.848028799315, 6080.179175811386, 6081.5742897996715, 6013.128096425906, 6101.786437474844, 6101.908471372844, 6103.529224525481, 6108.389161084034, 5824.650520968586, 5827.213326200908, 5855.063189889597, 5862.296524417097, 5926.259217597801], "value": 5702.042031117983, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.7548367427518, 633.8415396150963, 649.7595889520876, 664.166471078796, 672.9764458243963, 34786.82606586967, 46140.769296153514, 48072.0, 48137.277940830914, 48148.999999999956, 48626.75686621562, 48657.51342486574, 48993.0, 49057.264141037915, 49178.50821491784, 8274.2966847818, 9311.394759340621, 9445.905540944572, 9451.858222126639, 10824.24230303879, 10879.401632910187, 11365.659030229106, 11384.71538211543, 11409.31544107352, 11409.714757131096, 11494.885051149507, 11516.712082197928, 11525.308481491096, 11525.942370288138, 11536.653900383002, 11405.828912566318, 11436.256643318158, 11463.598774042952, 11471.942640286828, 11473.311601303907, 11278.154138439568, 11347.092232621371, 11355.318680879174, 11362.545498180063, 11369.31784092957, 44978.550214497765, 45363.54636453626, 45525.77237113793, 45569.54430455686, 45613.31580026302, 3851.788151651658, 4080.5715399882884, 4580.610648094916, 4683.906321873544, 4713.6464765142755, 35000.82499587486, 35007.29985400278, 35184.29631407383, 35546.28907421863, 37266.068348291235, 4515.61617262533, 4604.631629469668, 4633.6061434778085, 4640.0, 4662.300654901758, 399590.0, 400860.0, 401113.0, 401186.0, 401667.0, 67624.3237567618, 67920.6603966977, 68066.319336806, 68082.0, 68184.65907670528, 40297.194056118715, 41159.7942010288, 41282.1743565133, 43230.35154472685, 43341.0, 19671.0, 19866.1060252289, 19873.0, 19877.801221987884, 19884.304049358492, 12227.93886030582, 14163.291835408188, 14913.70172596542, 15186.392544298325, 15211.239438028055, 21258.78741212599, 21376.57246855054, 21457.67813482799, 21529.67705484419, 21616.459588510414, 7552.773416797505, 7658.0694471503375, 7720.53676779395, 7723.150453450116, 7784.0, 1018.2312354172552, 1053.257453495284, 1110.9111271098293, 1157.2188772578645, 1171.917965742407, 34851.825740871136, 35723.82138089293, 35992.280154396765, 36150.45774313388, 36524.81737591295, 1144.4449442020614, 1164.7903377392151, 1230.6369620961839, 1257.8867901888873, 1290.5289569307322, 17961.7305740414, 18625.161867715986, 19099.2360305589, 19154.808451915582, 19171.0, 641554.0, 642487.0, 646263.0, 650135.0, 652916.0, 66061.33938660551, 66384.0, 66833.33166668368, 66915.0, 67086.0, 1326.7943468762357, 1421.2822524624999, 1424.8575142485668, 1428.7071150414347, 1442.35094207607, 34933.301333973686, 36669.816650916575, 36901.44647830338, 37209.81395093116, 37412.25175496529, 55648.44351556433, 56274.718626406604, 56282.71858640681, 56405.15392269037, 56456.717716411156, 437015.0, 438047.0, 438244.0, 439074.0, 439716.0, 28164.15507534777, 30913.536296955113, 31663.683363166074, 32020.67979320177, 33357.66642333545, 53420.73289633527, 53477.732611336694, 53853.0, 53938.73030634822, 55272.17091743708, 44652.81146964756, 44828.32757508575, 46031.30953035774, 46998.53001469941, 48093.27860082032, 44641.98806130263, 45762.54237457716, 46179.30731039104, 46488.767556162005, 48046.27930581114, 45020.77489612531, 45491.0, 45940.081198376516, 46181.538184617726, 46432.0, 36829.815850920575, 39650.801745991084, 40636.59363406328, 40663.39004914988, 41137.0, 30004.849975750854, 30425.0, 31032.0, 31416.214594635316, 31547.0], "value": 22387.34644361444, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.2016536678595, 415.14909810780415, 420.5521120007194, 438.38844811487985, 439.4155772822147, 19303.806961930375, 21074.99999999998, 21151.576968460642, 21876.671849922222, 22111.889440552815, 20486.078126484306, 20941.895290523564, 21023.369298921018, 21111.788882111174, 21183.0, 5943.702814859251, 6201.317855035943, 6213.192285002943, 6213.658248796337, 6215.285242197137, 6013.819585412444, 6021.939780602203, 6136.171616831715, 6158.137860699506, 6159.661218632973, 6054.364291749369, 6054.788082417116, 6055.21282233309, 6058.394160583928, 6070.605410648316, 5978.790742324019, 6040.456358927696, 6097.939020609803, 6098.32918378978, 6122.632642041447, 5266.2890509781455, 5496.148097044925, 5519.779208831642, 5526.143447765603, 5560.304961879765, 15233.695326093526, 15490.9225453872, 15490.9225453872, 18217.998010109437, 24582.508349833082, 2412.8552286862846, 2427.8179136564827, 2447.290285817111, 2533.2526904563197, 2631.460550587125, 10888.727781805439, 10905.34567925927, 11122.49948752308, 11459.255148415326, 12316.261024338479, 1929.2765213045109, 2006.8494862885343, 2018.8485863560293, 2039.9388018359473, 2056.794320567931, 63780.68109659422, 81212.0, 86893.13106868976, 87676.0, 88883.0, 11744.119191060705, 11778.646640600795, 11931.284122952651, 12160.331181785086, 12161.878381216251, 17076.146192690565, 17076.743848842278, 17096.572585685208, 17109.14454277281, 17119.914400428166, 8664.696735614349, 8687.782805429788, 8698.217160455462, 8727.869081963776, 8822.294216462797, 8232.423730338818, 8248.587570621445, 8450.87323690145, 8456.619452124669, 8483.490990540588, 5159.277701121846, 5213.322268105122, 5218.269442278084, 5230.770768869389, 5346.278252435949, 2062.8556001080083, 2094.7276854008883, 2139.721836161289, 2151.6449785785485, 2180.269609680775, 913.9223166030962, 1005.7385079879284, 1015.4212099103588, 1027.1269420992096, 1120.5265076424475, 19466.70799938002, 20860.165593376398, 21031.57936841285, 21092.0, 21454.0, 1014.7564584499667, 1024.0680980307884, 1043.8695163104612, 1063.2663462211049, 1085.7394225385851, 10732.901871610922, 11662.53349865996, 11863.64409067712, 11884.34636095023, 11987.34069626179, 567560.0, 579971.0, 589912.0, 589979.0, 590904.0, 97600.51199743956, 100937.0, 101656.0, 104354.4782276099, 106407.0, 979.5787811241235, 1001.1840350114779, 1020.9234307426973, 1029.264076185531, 1047.4497743339748, 18378.908105459388, 18707.783994040343, 18716.532086697942, 18928.62142757165, 19013.619727605095, 31966.0, 36367.0, 47819.5218047829, 48134.0, 48297.0, 64721.67639161774, 64956.67521662361, 65484.67257663681, 66179.66910165419, 67457.66271168613, 8860.444019100463, 9238.491882946502, 9450.43297402158, 9454.858177127211, 9584.233261339215, 12850.871491284968, 13249.271290079138, 13800.585982420544, 13835.515756948458, 14100.78848817248, 12317.876821231674, 12358.691032724253, 12511.499540018298, 12599.43302551369, 12663.430145643284, 12217.938910305747, 12241.449134789134, 12379.381030948596, 12424.37878106073, 12581.993440524919, 12516.12387132892, 12517.0, 12647.747045058863, 12667.113302069136, 12733.681657958625, 9289.117533834275, 9316.44101353921, 9386.389884657476, 9698.418094914328, 9750.366226195274, 10789.838152427878, 12246.938765306117, 12468.875311247135, 12532.749345012866, 12603.49586016549], "value": 9780.388392297848, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1443.797868298439, 1528.365728222788, 1562.4609509719146, 1733.7226043832989, 1850.2506484873632, 9267.165955064043, 9662.17871480924, 9704.902950970489, 9973.301868869183, 9992.550335234913, 9765.072318129784, 9940.602375904973, 9942.254330925174, 9947.900520994788, 9952.552135153917, 2633.762961333475, 2683.18162960297, 2684.033747850772, 2687.6371689821917, 2689.1394753678733, 2562.295368773593, 2562.500312439075, 2577.2010676690193, 2581.1353196679133, 2581.8709064546697, 2621.30535408117, 2633.302174923647, 2677.4645070985785, 2677.54481738104, 2680.5175068487665, 2533.7719605235525, 2555.7955363571054, 2559.398541342783, 2562.449073449208, 2565.204786516167, 2573.4853029394008, 2592.1057235253807, 2607.569750991084, 2610.4909542639193, 2613.3335999320243, 9790.706278811647, 9948.651797187063, 10011.14905233056, 10013.048760367827, 10018.549165287579, 5873.148393482921, 6036.5170786336985, 6042.1238920356745, 6096.573239873211, 6101.816945491642, 3843.673287770543, 3879.844806207748, 3907.1208977979727, 3909.276783795007, 3917.1186483041374, 1193.9403029848559, 1203.8916497515265, 1216.2155409760744, 1240.5596013415288, 1247.9563215287421, 14297.14217146964, 14309.642258943506, 14341.067830590979, 14348.49780257686, 14425.711485770331, 4878.463369029475, 4943.72809495474, 5125.538701516882, 5161.406438259648, 5228.686278823283, 10850.186236032328, 10859.294145880493, 10860.185486088574, 10861.619843305447, 10862.728431789268, 5993.4905533029305, 6001.5198784097165, 6018.307894592091, 6018.909716354258, 6028.156058151862, 4762.404699412574, 4926.630502712311, 5536.86157846049, 5550.500454959073, 5616.466435688603, 4313.072689371737, 4322.135572885435, 4374.475062992461, 4411.29419292918, 4426.3581780641625, 1303.1008604063165, 1306.679863433446, 1325.105553751214, 1337.164272329794, 1339.3504150487006, 1029.5161274201114, 1078.8813230544633, 1083.6532309660834, 1083.8428427877914, 1085.6851513061124, 6514.478841692748, 6732.697028633724, 6909.378155965886, 6953.478489113337, 6984.860302793915, 509.6407033041697, 529.2246858352532, 530.8672831792052, 532.1857557936363, 543.7960764713232, 4600.42494688164, 4636.9536304637195, 4708.364370809897, 4715.858524244278, 4720.12677654635, 12469.625911222496, 12690.365481725878, 12691.746165076647, 12850.100492965403, 12937.741245175044, 12477.376131193403, 12794.0, 12806.359682015862, 12813.0, 12813.551525696555, 9361.157495825408, 9474.099960503741, 9484.810303793885, 9495.335326527214, 9495.525223738785, 6877.552959057645, 6905.930940690529, 6910.965445172742, 6924.307569243036, 7014.929850701632, 11362.943185284352, 11675.88324116782, 11731.472083756422, 11751.882481175078, 11845.407729613657, 10588.576456941637, 11373.0, 11548.133889958455, 11829.526818927146, 12008.699782505508, 8022.438429309892, 8544.487330759926, 8752.256058235122, 8782.868256976279, 9104.04407537199, 9882.555285012048, 9982.700518984442, 10049.246306526893, 10057.49712514386, 10059.0, 8814.2948564116, 8941.552922353987, 8985.865212021694, 9099.727008189764, 9208.0, 8686.261667758312, 8712.69505567302, 8727.389082764146, 8797.42816716937, 8909.420887642282, 7571.205023472456, 7726.266004729542, 7741.845163096594, 7746.690132394867, 7746.92253077462, 5509.696966666872, 5608.38307786135, 5641.717914104196, 5673.290838645169, 5691.089425691869, 5794.449527294901, 5834.299884013945, 5878.823635290777, 5912.911306330495, 5956.821295361146], "value": 5124.431616001291, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.36518250142234, 348.62696914301694, 352.62269371772203, 369.8428168028587, 373.67863637271944, 5374.838754837351, 5544.390117087128, 5594.776208951642, 5655.943440565593, 5671.23438335824, 5523.944760552393, 5526.972365138179, 5530.253415788868, 5555.722213889309, 5575.665460072399, 4351.0, 4351.216780979423, 4365.476142862853, 4391.86824395267, 4397.736135831844, 4205.7897105144775, 4376.387305777193, 4387.517373088957, 4405.977970110161, 4451.910961780763, 4360.934585981213, 4364.10535840152, 4367.323064924942, 4385.188740083079, 4397.714148580348, 4408.691391602589, 4410.5368936261875, 4417.22698527758, 4437.755923424211, 4456.8885777855485, 3825.923481530354, 3901.887961930836, 3991.6806655467494, 3994.580569040267, 4002.1795531916177, 5377.569794416477, 5396.460353964611, 5403.243545903577, 5413.918791218135, 5422.701751403672, 4996.0, 5016.398032236155, 5018.272350509155, 5032.917922646649, 5065.265536497187, 5652.09566469367, 5699.74351154199, 5812.564057695689, 5819.243498345188, 5860.97069514654, 4279.2083464559155, 4289.249381358281, 4289.914201715979, 4309.762963037032, 4335.067960388532, 6035.7887473938645, 6076.240469941257, 6082.847928801771, 6091.812096641157, 6127.325994140641, 4347.543507931685, 4400.691951563424, 4449.599536041772, 4454.3986561813745, 4475.239209334421, 4480.753558554311, 4489.932651010237, 4501.212287849613, 4508.32375143731, 4520.18636645407, 5079.822206222764, 5082.415522214918, 5097.158968770185, 5104.872378190575, 5115.488451154854, 5602.887942241132, 5692.345380281238, 5867.0, 5882.823515294548, 5943.64338139713, 1513.3568233500719, 1550.193899172424, 1554.59309325061, 1559.9220038998233, 1563.2027665890478, 962.8013123660946, 986.3440811860177, 988.3229987458614, 993.6671215142864, 1018.6536577563664, 4968.90062198754, 4969.27945447908, 4974.626902982291, 4983.476734942851, 5009.874753131201, 5219.8255392536375, 5220.0, 5242.659227150223, 5251.684898906002, 5261.500157485033, 4663.696859704109, 4962.354893863775, 4980.950190498049, 5015.774290156952, 5027.622928280393, 2773.778097752175, 2785.6935737068907, 2786.2616406652255, 2792.2600510864636, 2815.7888158388205, 5329.387120481117, 5397.7571009304675, 5404.7297635118875, 5417.485338892878, 5428.45715428454, 5228.660137091077, 5288.603354748409, 5642.266505354278, 6118.632882027093, 6137.846553836101, 6239.660127452205, 6241.469475094668, 6241.812745617639, 6246.281677607042, 6255.843603909848, 5826.475617194304, 5932.940670593239, 6031.276246850406, 6037.45662890342, 6052.122442245762, 5898.852528686817, 5963.970180149072, 5964.254468191476, 5969.0, 5976.133460648269, 6228.131560531913, 6239.126522286792, 6239.282482514387, 6247.0629405589525, 6248.593841400295, 4131.607497287753, 4287.935680964851, 4335.393044973769, 4351.173277077345, 4354.04211073576, 5815.41845815415, 5968.4926781224085, 5986.491148252448, 5994.580379373577, 5997.640141591519, 5864.794732184353, 5886.323072846678, 5914.526837853047, 5916.792912248051, 5966.880662386815, 5782.5373970081455, 5845.860057288788, 5865.384134665798, 5883.293844785136, 5888.293404791452, 5679.744411501575, 5806.44838740319, 5862.3844496328975, 5874.500667443144, 5885.882282354243, 5861.677607731615, 5912.970435147797, 5950.9702451487465, 5951.761929522944, 5960.493358064613, 5635.549156067423, 5737.91393129095, 5773.6824474654295, 5774.162746401664, 5864.765409383577], "value": 4410.3101911345775, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [807.5800583696478, 825.6078362777678, 851.7870532366908, 873.6767396063457, 906.3791302957475, 16618.418355357564, 17809.198586063627, 17839.375621853236, 17906.194221260026, 17910.462686119405, 15814.683706325895, 16033.599160021005, 16044.037357758543, 16051.277692503836, 16066.75899861503, 731.4952682648982, 858.935579831512, 878.3719640457088, 896.238197532099, 897.8922529296461, 887.1394747095308, 888.0542222533014, 890.0743227043866, 890.514669505118, 891.0910870911666, 943.2642538819731, 944.329526036515, 948.1466679987997, 949.4018768176039, 949.7957939043105, 935.4434111703525, 936.091990768953, 937.7327461673391, 938.1181689212119, 938.1744065222629, 686.8797960356918, 688.896665500174, 692.2281655953626, 694.2467422846217, 694.4826104552141, 15937.0, 15940.521784346489, 15941.043537387677, 15944.760828587581, 15973.440929567525, 15289.541313760605, 15361.0, 15405.145671192955, 15859.762103568455, 15917.602059948478, 14088.436462541486, 14192.219427931459, 14280.571582852532, 14379.209143497104, 14746.336414861356, 4958.702477851304, 5104.336436263299, 5150.995555866608, 5225.08561001827, 5271.341082364705, 89695.55152224327, 91242.5437872813, 91971.54014229953, 93000.0, 93078.53460732721, 12439.813402798965, 13186.406611702496, 13295.468181272641, 13372.665683357995, 13396.330183490787, 24465.510689786104, 24534.386640333778, 24536.509269814505, 24577.631335529983, 24623.630645540332, 9256.676016339396, 9440.622375104918, 9667.854982175273, 9859.112679858847, 9957.900420995697, 4975.427825800007, 5110.897782044412, 5265.52610265078, 5368.785248590091, 5391.838244852661, 3708.1100535871738, 3812.5806161322234, 3815.4467602197524, 3821.6560509554274, 3826.1199924017633, 644.8097811145723, 654.9279579246279, 665.4244078871741, 674.1707699529607, 682.7405585877335, 683.4737252315689, 698.4970821008866, 749.8575270698659, 761.9390448764085, 772.764306886405, 10137.645182418728, 10272.33229840058, 10377.0, 10405.271630985759, 10803.783924321628, 736.2784471218237, 756.2626439221825, 756.8940348351235, 759.8556274307974, 776.9456138070394, 4608.400907881954, 4928.0, 5650.830475085754, 5736.397678243808, 5861.413858614104, 96821.51589242008, 121842.0, 127654.36172819076, 129824.0, 131920.0, 102879.0, 105069.0, 105930.47034764777, 106738.0, 108822.0, 86706.0, 88499.0, 90401.0, 91196.08803912009, 92809.53595231981, 10291.0, 10963.287386319864, 11136.387498687325, 11156.0, 11439.828402573801, 46879.29681054719, 50237.24644130414, 51407.485925141766, 51500.742496287276, 53369.19946200888, 71988.28011719816, 72006.0, 79854.0, 80352.19647803447, 82609.0, 13876.930615346859, 13985.370658320604, 14111.364988575333, 14120.0, 14143.0, 16010.839891601401, 16222.51332460028, 16291.266892989606, 17480.0, 17739.556511087325, 14039.789403159166, 14105.929470352583, 14286.214258215483, 14607.853921461074, 15028.849711502746, 15328.46350377776, 15370.46203382921, 15492.90863169225, 15630.0, 15850.0, 13886.444542218198, 14204.715905682035, 14667.633309167357, 14738.484153054591, 15078.773818392514, 12423.751524969632, 12654.0, 12893.419796109385, 12933.159344642569, 13521.0, 13184.802227966396, 13313.800292995806, 13458.798118028433, 13468.797968030683, 13478.932605336911], "value": 8359.755955682998, "unit": "score"}], "name": "dromaeo_css", "value": 7860.621192218794, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 123.0, 111.0, 112.0, 112.0, 110.0, 111.0, 111.0, 111.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 115.0, 140.0, 185.0, 116.0, 143.0, 150.0, 121.0, 168.0, 115.0], "value": 139.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 161.0, 162.0, 161.0, 162.0, 160.0, 160.0, 160.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 84.0, 184.0, 76.0, 75.0, 74.0, 74.0, 154.0, 75.0], "value": 94.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 154.0, 84.0, 84.0, 83.0, 143.0, 82.0, 83.0, 148.0], "value": 102.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 147.0, 145.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 50.0, 51.0, 50.0, 54.0, 52.0, 50.0, 51.0, 51.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 61.0, 58.0, 59.0, 59.0, 60.0, 59.0, 58.0, 60.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 73.0, 72.0, 77.0, 72.0, 77.0, 72.0, 71.0, 72.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 133.0, 129.0, 128.0, 117.0, 125.0, 116.0, 118.0, 161.0, 134.0], "value": 128.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 151.0, 152.0, 153.0, 151.0, 152.0, 156.0, 154.0, 151.0, 152.0], "value": 153.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 65.0, 61.0, 61.0, 61.0, 63.0, 62.0, 63.0, 60.0], "value": 62.0, "unit": "score"}], "name": "kraken", "value": 1494.2, "alertThreshold": 2.0}]} 17:29:35 INFO - Return code: 0 17:29:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:29:35 INFO - # TBPL SUCCESS # 17:29:35 INFO - Running post-action listener: _resource_record_post_action 17:29:35 INFO - [mozharness: 2016-09-29 00:29:35.891075Z] Finished run-tests step (success) 17:29:35 INFO - Running post-run listener: _resource_record_post_run 17:29:35 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 287617024; Read time: 52; Write time: 188136 17:29:35 INFO - TinderboxPrint: CPU usage
12.9% 17:29:35 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 52 17:29:35 INFO - TinderboxPrint: I/O write bytes / time
287,617,024 / 188,136 17:29:35 INFO - TinderboxPrint: CPU idle
8,248.7 (87.0%) 17:29:35 INFO - TinderboxPrint: CPU user
1,182.7 (12.5%) 17:29:35 INFO - TinderboxPrint: Swap in / out
0 / 0 17:29:35 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71409664; Read time: 0; Write time: 83804 17:29:35 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 216207360; Read time: 8; Write time: 104332 17:29:36 INFO - Running post-run listener: _upload_blobber_files 17:29:36 INFO - Blob upload gear active. 17:29:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:29:36 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 17:29:36 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'] 17:29:36 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 17:29:36 INFO - (blobuploader) - INFO - Open directory for files ... 17:29:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:29:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:29:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:29:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:29:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:29:37 INFO - (blobuploader) - INFO - Done attempting. 17:29:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:29:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:29:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:29:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:29:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:29:45 INFO - (blobuploader) - INFO - Done attempting. 17:29:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:29:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:29:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:29:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:29:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:29:46 INFO - (blobuploader) - INFO - Done attempting. 17:29:46 INFO - (blobuploader) - INFO - Iteration through files over. 17:29:46 INFO - Return code: 0 17:29:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:29:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:29:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/46a9028ac62a0e0b9ecbc7fcd96aeae551855077d9081aff2a0983c1011e5331b352e401a54d8768ca2f6d0501d68959d2a53d3fff0f083675d96e50c42d4ccc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9f7d6591f97cad9f744b81a6f2b2563e8637b787ff9f4e92464091c90507eacc5bc02badc25247846a71fb6d0107c9a541ad94383433ce3775ad739d01990707", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f38608ba6b032584d1d38b0660961102ad542849e6f25256b089adb5c9b0c459669cf1b4d77c18fbd5b9a23ef99e1d215a2ca26429bb4059893715804f157a72"} 17:29:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:29:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:29:46 INFO - Contents: 17:29:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/46a9028ac62a0e0b9ecbc7fcd96aeae551855077d9081aff2a0983c1011e5331b352e401a54d8768ca2f6d0501d68959d2a53d3fff0f083675d96e50c42d4ccc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9f7d6591f97cad9f744b81a6f2b2563e8637b787ff9f4e92464091c90507eacc5bc02badc25247846a71fb6d0107c9a541ad94383433ce3775ad739d01990707", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f38608ba6b032584d1d38b0660961102ad542849e6f25256b089adb5c9b0c459669cf1b4d77c18fbd5b9a23ef99e1d215a2ca26429bb4059893715804f157a72"} 17:29:46 INFO - Running post-run listener: copy_logs_to_upload_dir 17:29:46 INFO - Copying logs to upload dir... 17:29:46 INFO - mkdir: /builds/slave/test/build/upload/logs 17:29:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.587549 ========= master_lag: 0.14 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-09-28 17:29:46.187534) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 17:29:46.191054) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379 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/46a9028ac62a0e0b9ecbc7fcd96aeae551855077d9081aff2a0983c1011e5331b352e401a54d8768ca2f6d0501d68959d2a53d3fff0f083675d96e50c42d4ccc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9f7d6591f97cad9f744b81a6f2b2563e8637b787ff9f4e92464091c90507eacc5bc02badc25247846a71fb6d0107c9a541ad94383433ce3775ad739d01990707", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f38608ba6b032584d1d38b0660961102ad542849e6f25256b089adb5c9b0c459669cf1b4d77c18fbd5b9a23ef99e1d215a2ca26429bb4059893715804f157a72"} build_url:https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011291 build_url: 'https://queue.taskcluster.net/v1/task/KZy448JmRxW_8WWox7Ps9w/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/46a9028ac62a0e0b9ecbc7fcd96aeae551855077d9081aff2a0983c1011e5331b352e401a54d8768ca2f6d0501d68959d2a53d3fff0f083675d96e50c42d4ccc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9f7d6591f97cad9f744b81a6f2b2563e8637b787ff9f4e92464091c90507eacc5bc02badc25247846a71fb6d0107c9a541ad94383433ce3775ad739d01990707", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f38608ba6b032584d1d38b0660961102ad542849e6f25256b089adb5c9b0c459669cf1b4d77c18fbd5b9a23ef99e1d215a2ca26429bb4059893715804f157a72"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 17:29:46.218277) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 17:29:46.218614) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1475107305.42941-1962245379 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004598 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 17:29:46.271285) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 17:29:46.272027) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 17:29:46.272641) ========= ========= Total master_lag: 0.27 =========