builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0006 starttime: 1467541923.82 results: success (0) buildid: 20160703011523 builduid: d5f10c7169954150ac34f80025790374 revision: 13ce99eb20be5ebc3ff877ac0b1c12f88e9e4d67 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-03 03:32:03.825314) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-03 03:32:03.825792) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-03 03:32:03.826105) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-03 03:32:04.010551) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 03:32:04.010897) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-03 03:32:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to 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% 7.16M=0.002s 2016-07-03 03:32:04 (7.16 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.501367 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 03:32:04.588855) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 03:32:04.589250) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.029548 ========= master_lag: 0.09 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 03:32:04.712662) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-03 03:32:04.713296) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 13ce99eb20be5ebc3ff877ac0b1c12f88e9e4d67 --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 13ce99eb20be5ebc3ff877ac0b1c12f88e9e4d67 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-03 03:32:04,793 truncating revision to first 12 chars 2016-07-03 03:32:04,793 Setting DEBUG logging. 2016-07-03 03:32:04,793 attempt 1/10 2016-07-03 03:32:04,793 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/13ce99eb20be?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-03 03:32:06,444 unpacking tar archive at: mozilla-beta-13ce99eb20be/testing/mozharness/ program finished with exit code 0 elapsedTime=1.923266 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-03 03:32:06.664022) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-03 03:32:06.664301) ========= script_repo_revision: 13ce99eb20be5ebc3ff877ac0b1c12f88e9e4d67 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-03 03:32:06.664661) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-03 03:32:06.664951) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-03 03:32:06.685351) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-07-03 03:32:06.686047) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_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/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:32:06 INFO - MultiFileLogger online at 20160703 03:32:06 in /builds/slave/test 03:32:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 03:32:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:32:06 INFO - {'append_to_log': False, 03:32:06 INFO - 'base_work_dir': '/builds/slave/test', 03:32:06 INFO - 'blob_upload_branch': 'Mozilla-Beta', 03:32:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:32:06 INFO - 'branch': 'Mozilla-Beta', 03:32:06 INFO - 'buildbot_json_path': 'buildprops.json', 03:32:06 INFO - 'config_files': ('talos/mac_config.py',), 03:32:06 INFO - 'default_actions': ('clobber', 03:32:06 INFO - 'read-buildbot-config', 03:32:06 INFO - 'download-and-extract', 03:32:06 INFO - 'populate-webroot', 03:32:06 INFO - 'create-virtualenv', 03:32:06 INFO - 'install', 03:32:06 INFO - 'run-tests'), 03:32:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:32:06 INFO - 'download_minidump_stackwalk': True, 03:32:06 INFO - 'download_symbols': 'ondemand', 03:32:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:32:06 INFO - 'tooltool.py': '/tools/tooltool.py', 03:32:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:32:06 INFO - '/tools/misc-python/virtualenv.py')}, 03:32:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:32:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:32:06 INFO - 'installer_path': 'installer.exe', 03:32:06 INFO - 'log_level': 'info', 03:32:06 INFO - 'log_name': 'talos', 03:32:06 INFO - 'log_to_console': True, 03:32:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:32:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:32:06 INFO - 'opt_config_files': (), 03:32:06 INFO - 'pip_index': False, 03:32:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:32:06 INFO - 'cmd': ('bash', 03:32:06 INFO - '-c', 03:32:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:32:06 INFO - 'enabled': True, 03:32:06 INFO - 'halt_on_failure': False, 03:32:06 INFO - 'name': 'check_screen_resolution'},), 03:32:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:32:06 INFO - 'cmd': ('bash', 03:32:06 INFO - '-c', 03:32:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:32:06 INFO - 'enabled': True, 03:32:06 INFO - 'halt_on_failure': False, 03:32:06 INFO - 'name': 'check_screen_resolution'},), 03:32:06 INFO - 'run_cmd_checks_enabled': True, 03:32:06 INFO - 'sps_profile': False, 03:32:06 INFO - 'sps_profile_interval': 0, 03:32:06 INFO - 'suite': 'dromaeojs', 03:32:06 INFO - 'system_bits': '32', 03:32:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:32:06 INFO - 'title': 't-yosemite-r7-0006', 03:32:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:32:06 INFO - 'use_talos_json': True, 03:32:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:32:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:32:06 INFO - 'work_dir': 'build'} 03:32:06 INFO - ##### 03:32:06 INFO - ##### Running clobber step. 03:32:06 INFO - ##### 03:32:06 INFO - Running pre-action listener: _resource_record_pre_action 03:32:06 INFO - Running main action method: clobber 03:32:06 INFO - rmtree: /builds/slave/test/build 03:32:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:32:08 INFO - Running post-action listener: _resource_record_post_action 03:32:08 INFO - ##### 03:32:08 INFO - ##### Running read-buildbot-config step. 03:32:08 INFO - ##### 03:32:08 INFO - Running pre-action listener: _resource_record_pre_action 03:32:08 INFO - Running main action method: read_buildbot_config 03:32:08 INFO - Using buildbot properties: 03:32:08 INFO - { 03:32:08 INFO - "project": "", 03:32:08 INFO - "product": "firefox", 03:32:08 INFO - "script_repo_revision": "production", 03:32:08 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 03:32:08 INFO - "repository": "", 03:32:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 03:32:08 INFO - "buildid": "20160703011523", 03:32:08 INFO - "pgo_build": "False", 03:32:08 INFO - "basedir": "/builds/slave/test", 03:32:08 INFO - "buildnumber": 16, 03:32:08 INFO - "slavename": "t-yosemite-r7-0006", 03:32:08 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 03:32:08 INFO - "platform": "yosemite_r7", 03:32:08 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 03:32:08 INFO - "branch": "mozilla-beta", 03:32:08 INFO - "repo_path": "releases/mozilla-beta", 03:32:08 INFO - "revision": "13ce99eb20be5ebc3ff877ac0b1c12f88e9e4d67", 03:32:08 INFO - "stage_platform": "macosx64", 03:32:08 INFO - "builduid": "d5f10c7169954150ac34f80025790374", 03:32:08 INFO - "slavebuilddir": "test" 03:32:08 INFO - } 03:32:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 03:32:08 INFO - Running post-action listener: _resource_record_post_action 03:32:08 INFO - ##### 03:32:08 INFO - ##### Running download-and-extract step. 03:32:08 INFO - ##### 03:32:08 INFO - Running pre-action listener: _resource_record_pre_action 03:32:08 INFO - Running main action method: download_and_extract 03:32:08 INFO - mkdir: /builds/slave/test/build/tests 03:32:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:08 INFO - https://queue.taskcluster.net/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:32:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 03:32:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 03:32:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 03:32:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 03:32:13 INFO - Downloaded 1165 bytes. 03:32:13 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 03:32:13 INFO - Using the following test package requirements: 03:32:13 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 03:32:13 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 03:32:13 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 03:32:13 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 03:32:13 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 03:32:13 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 03:32:13 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 03:32:13 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 03:32:13 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 03:32:13 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 03:32:13 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 03:32:13 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 03:32:13 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 03:32:13 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 03:32:13 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 03:32:13 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 03:32:13 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 03:32:13 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 03:32:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:13 INFO - https://queue.taskcluster.net/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:32:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 03:32:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 03:32:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 03:32:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 03:32:15 INFO - Downloaded 18351398 bytes. 03:32:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:32:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:32:18 INFO - Return code: 0 03:32:18 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 03:32: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')]} 03:32:18 INFO - https://queue.taskcluster.net/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:32:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 03:32:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 03:32:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 03:32:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 03:32:44 INFO - Downloaded 18351398 bytes. 03:32:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:32:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:32:48 INFO - Return code: 0 03:32: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')]} 03:32:48 INFO - https://queue.taskcluster.net/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:32:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 03:32:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 03:32:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 03:32:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 03:32:49 INFO - Downloaded 10840145 bytes. 03:32:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:32:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:32:49 INFO - Return code: 0 03:32:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:49 INFO - https://queue.taskcluster.net/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 03:32:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 03:32:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 03:32:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 03:32:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 03:32:58 INFO - Downloaded 86196771 bytes. 03:32:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 03:32:58 INFO - mkdir: /builds/slave/test/properties 03:32:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:32:58 INFO - Writing to file /builds/slave/test/properties/build_url 03:32:58 INFO - Contents: 03:32:58 INFO - build_url:https://queue.taskcluster.net/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 03:32:59 INFO - Running post-action listener: _resource_record_post_action 03:32:59 INFO - Running post-action listener: set_extra_try_arguments 03:32:59 INFO - ##### 03:32:59 INFO - ##### Running populate-webroot step. 03:32:59 INFO - ##### 03:32:59 INFO - Running pre-action listener: _resource_record_pre_action 03:32:59 INFO - Running main action method: populate_webroot 03:32:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:32:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:32:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:32:59 INFO - '--tppagecycles', 03:32:59 INFO - '7'], 03:32:59 INFO - 'tests': ['tsvgm']}}, 03:32:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:32:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:32:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:32:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:32:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:32:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:32:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:59 INFO - 'talos_options': ['--disable-e10s'], 03:32:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:32:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:32:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:59 INFO - 'talos_options': ['--disable-e10s'], 03:32:59 INFO - 'tests': ['damp', 'tps']}, 03:32:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:59 INFO - 'tests': ['damp', 'tps']}, 03:32:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:32:59 INFO - 'tests': ['dromaeo_dom']}, 03:32:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:32:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:32:59 INFO - 'tests': ['a11yr', 03:32:59 INFO - 'ts_paint', 03:32:59 INFO - 'tpaint', 03:32:59 INFO - 'sessionrestore', 03:32:59 INFO - 'sessionrestore_no_auto_restore', 03:32:59 INFO - 'tabpaint']}, 03:32:59 INFO - 'other-e10s': {'tests': ['a11yr', 03:32:59 INFO - 'ts_paint', 03:32:59 INFO - 'tpaint', 03:32:59 INFO - 'sessionrestore', 03:32:59 INFO - 'sessionrestore_no_auto_restore', 03:32:59 INFO - 'tabpaint']}, 03:32:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:32:59 INFO - 'ts_paint', 03:32:59 INFO - 'tpaint', 03:32:59 INFO - 'sessionrestore', 03:32:59 INFO - 'sessionrestore_no_auto_restore', 03:32:59 INFO - 'tabpaint']}, 03:32:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:32:59 INFO - 'ts_paint', 03:32:59 INFO - 'tpaint', 03:32:59 INFO - 'sessionrestore', 03:32:59 INFO - 'sessionrestore_no_auto_restore', 03:32:59 INFO - 'tabpaint']}, 03:32:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:32:59 INFO - 'tests': ['a11yr', 03:32:59 INFO - 'ts_paint', 03:32:59 INFO - 'tpaint', 03:32:59 INFO - 'sessionrestore', 03:32:59 INFO - 'sessionrestore_no_auto_restore', 03:32:59 INFO - 'tabpaint']}, 03:32:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:32:59 INFO - 'tests': ['a11yr', 03:32:59 INFO - 'ts_paint', 03:32:59 INFO - 'tpaint', 03:32:59 INFO - 'sessionrestore', 03:32:59 INFO - 'sessionrestore_no_auto_restore', 03:32:59 INFO - 'tabpaint']}, 03:32:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:32:59 INFO - 'tests': ['tsvgx', 03:32:59 INFO - 'tsvgr_opacity', 03:32:59 INFO - 'tart', 03:32:59 INFO - 'tscrollx', 03:32:59 INFO - 'cart']}, 03:32:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:32:59 INFO - 'tsvgr_opacity', 03:32:59 INFO - 'tart', 03:32:59 INFO - 'tscrollx', 03:32:59 INFO - 'cart']}, 03:32:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:59 INFO - 'talos_options': ['--disable-e10s'], 03:32:59 INFO - 'tests': ['tp5o']}, 03:32:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:59 INFO - 'tests': ['tp5o']}, 03:32:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:59 INFO - 'talos_options': ['--disable-e10s', 03:32:59 INFO - '--xperf_path', 03:32:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:32:59 INFO - 'tests': ['tp5n']}, 03:32:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:59 INFO - 'talos_options': ['--xperf_path', 03:32:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:32:59 INFO - 'tests': ['tp5n']}}, 03:32:59 INFO - 'talos.zip': {'path': '', 03:32:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:32:59 INFO - Running post-action listener: _resource_record_post_action 03:32:59 INFO - ##### 03:32:59 INFO - ##### Running create-virtualenv step. 03:32:59 INFO - ##### 03:32:59 INFO - Running pre-action listener: _resource_record_pre_action 03:32:59 INFO - Running main action method: create_virtualenv 03:32:59 INFO - Creating virtualenv /builds/slave/test/build/venv 03:32:59 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:32:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:32:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:32:59 INFO - Using real prefix '/tools/python27' 03:32:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:33:00 INFO - Installing distribute.............................................................................................................................................................................................done. 03:33:02 INFO - Installing pip.................done. 03:33:02 INFO - Return code: 0 03:33:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:33: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')]} 03:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33: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')]} 03:33:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:02 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 0x104be1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bf1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd12145e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a80e00>, '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 0x1048e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7bb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:33:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:33:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render', 03:33:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:33:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:33:02 INFO - 'HOME': '/Users/cltbld', 03:33:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:33:02 INFO - 'LOGNAME': 'cltbld', 03:33:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:02 INFO - 'MOZ_NO_REMOTE': '1', 03:33:02 INFO - 'NO_EM_RESTART': '1', 03:33:02 INFO - 'PAGER': '/bin/cat', 03:33:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:33:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:02 INFO - 'PWD': '/builds/slave/test', 03:33:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:33:02 INFO - 'SHELL': '/bin/bash', 03:33:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners', 03:33:02 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:33:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:33:02 INFO - 'USER': 'cltbld', 03:33:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:33:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:33:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:02 INFO - 'XPC_FLAGS': '0x0', 03:33:02 INFO - 'XPC_SERVICE_NAME': '0', 03:33:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:33:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:03 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:33:03 INFO - Cleaning up... 03:33:03 INFO - Return code: 0 03:33:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:33:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:03 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104be1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bf1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd12145e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a80e00>, '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 0x1048e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7bb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:33:03 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>=0.7.1'] in /builds/slave/test/build 03:33:03 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>=0.7.1 03:33:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render', 03:33:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:33:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:33:03 INFO - 'HOME': '/Users/cltbld', 03:33:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:33:03 INFO - 'LOGNAME': 'cltbld', 03:33:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:03 INFO - 'MOZ_NO_REMOTE': '1', 03:33:03 INFO - 'NO_EM_RESTART': '1', 03:33:03 INFO - 'PAGER': '/bin/cat', 03:33:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:33:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:03 INFO - 'PWD': '/builds/slave/test', 03:33:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:33:03 INFO - 'SHELL': '/bin/bash', 03:33:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners', 03:33:03 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:33:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:33:03 INFO - 'USER': 'cltbld', 03:33:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:33:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:33:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:03 INFO - 'XPC_FLAGS': '0x0', 03:33:03 INFO - 'XPC_SERVICE_NAME': '0', 03:33:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:33:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:03 INFO - Downloading/unpacking psutil>=0.7.1 03:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:33:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:33:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:33:06 INFO - Installing collected packages: psutil 03:33:06 INFO - Running setup.py install for psutil 03:33:06 INFO - building 'psutil._psutil_osx' extension 03:33:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:33:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:33:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:33:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:33:07 INFO - building 'psutil._psutil_posix' extension 03:33:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:33:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:33:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:33:07 INFO - ^ 03:33:07 INFO - 1 warning generated. 03:33:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:33:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:33:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:33:07 INFO - Successfully installed psutil 03:33:07 INFO - Cleaning up... 03:33:07 INFO - Return code: 0 03:33:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:33:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:07 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104be1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bf1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd12145e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a80e00>, '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 0x1048e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7bb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:33:07 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.0.0'] in /builds/slave/test/build 03:33:07 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.0.0 03:33:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render', 03:33:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:33:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:33:07 INFO - 'HOME': '/Users/cltbld', 03:33:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:33:07 INFO - 'LOGNAME': 'cltbld', 03:33:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:07 INFO - 'MOZ_NO_REMOTE': '1', 03:33:07 INFO - 'NO_EM_RESTART': '1', 03:33:07 INFO - 'PAGER': '/bin/cat', 03:33:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:33:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:07 INFO - 'PWD': '/builds/slave/test', 03:33:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:33:07 INFO - 'SHELL': '/bin/bash', 03:33:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners', 03:33:07 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:33:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:33:07 INFO - 'USER': 'cltbld', 03:33:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:33:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:33:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:07 INFO - 'XPC_FLAGS': '0x0', 03:33:07 INFO - 'XPC_SERVICE_NAME': '0', 03:33:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:33:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:33:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:33:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:33:11 INFO - Installing collected packages: mozsystemmonitor 03:33:11 INFO - Running setup.py install for mozsystemmonitor 03:33:11 INFO - Successfully installed mozsystemmonitor 03:33:11 INFO - Cleaning up... 03:33:11 INFO - Return code: 0 03:33:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:33:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:11 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 0x104be1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bf1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd12145e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a80e00>, '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 0x1048e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7bb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:33:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:33:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:33:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render', 03:33:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:33:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:33:11 INFO - 'HOME': '/Users/cltbld', 03:33:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:33:11 INFO - 'LOGNAME': 'cltbld', 03:33:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:11 INFO - 'MOZ_NO_REMOTE': '1', 03:33:11 INFO - 'NO_EM_RESTART': '1', 03:33:11 INFO - 'PAGER': '/bin/cat', 03:33:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:33:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:11 INFO - 'PWD': '/builds/slave/test', 03:33:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:33:11 INFO - 'SHELL': '/bin/bash', 03:33:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners', 03:33:11 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:33:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:33:11 INFO - 'USER': 'cltbld', 03:33:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:33:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:33:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:11 INFO - 'XPC_FLAGS': '0x0', 03:33:11 INFO - 'XPC_SERVICE_NAME': '0', 03:33:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:33:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:11 INFO - Downloading/unpacking blobuploader==1.2.4 03:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:14 INFO - Downloading blobuploader-1.2.4.tar.gz 03:33:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:33:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:33:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:33:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:15 INFO - Downloading docopt-0.6.1.tar.gz 03:33:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:33:15 INFO - Installing collected packages: blobuploader, requests, docopt 03:33:15 INFO - Running setup.py install for blobuploader 03:33:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:33:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:33:15 INFO - Running setup.py install for requests 03:33:16 INFO - Running setup.py install for docopt 03:33:16 INFO - Successfully installed blobuploader requests docopt 03:33:16 INFO - Cleaning up... 03:33:16 INFO - Return code: 0 03:33:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:33:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104be1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bf1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd12145e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a80e00>, '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 0x1048e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7bb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:33:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:33:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:33:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render', 03:33:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:33:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:33:16 INFO - 'HOME': '/Users/cltbld', 03:33:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:33:16 INFO - 'LOGNAME': 'cltbld', 03:33:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:16 INFO - 'MOZ_NO_REMOTE': '1', 03:33:16 INFO - 'NO_EM_RESTART': '1', 03:33:16 INFO - 'PAGER': '/bin/cat', 03:33:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:33:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:16 INFO - 'PWD': '/builds/slave/test', 03:33:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:33:16 INFO - 'SHELL': '/bin/bash', 03:33:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners', 03:33:16 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:33:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:33:16 INFO - 'USER': 'cltbld', 03:33:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:33:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:33:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:16 INFO - 'XPC_FLAGS': '0x0', 03:33:16 INFO - 'XPC_SERVICE_NAME': '0', 03:33:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:33:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:33:16 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-SCL4r_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:33:16 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-117ht9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:33:17 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-jfDynf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:33:17 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-9qZuBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:33:17 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-LoP1ZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:33:17 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-5gR2JM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:33:17 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-5iAQ2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:33:17 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-P3QitU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:33:17 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-y9b6pb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:33:17 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-HQg9uC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:33:17 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-lQNVqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:33:17 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-RUQkuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:33:18 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-Xdl324-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:33:18 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-0PomW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:33:18 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-dTM4br-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:33:18 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-wqSyXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:33:18 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-zL4pf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:33:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:33:18 INFO - Running setup.py install for manifestparser 03:33:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:33:18 INFO - Running setup.py install for mozcrash 03:33:18 INFO - Running setup.py install for mozdebug 03:33:19 INFO - Running setup.py install for mozdevice 03:33:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:33:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:33:19 INFO - Running setup.py install for mozfile 03:33:19 INFO - Running setup.py install for mozhttpd 03:33:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:33:19 INFO - Running setup.py install for mozinfo 03:33:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:33:19 INFO - Running setup.py install for mozInstall 03:33:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:33:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:33:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:33:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:33:19 INFO - Running setup.py install for mozleak 03:33:19 INFO - Running setup.py install for mozlog 03:33:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:33:20 INFO - Running setup.py install for moznetwork 03:33:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:33:20 INFO - Running setup.py install for mozprocess 03:33:20 INFO - Running setup.py install for mozprofile 03:33:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:33:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:33:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:33:20 INFO - Running setup.py install for mozrunner 03:33:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:33:20 INFO - Running setup.py install for mozscreenshot 03:33:20 INFO - Running setup.py install for moztest 03:33:21 INFO - Running setup.py install for mozversion 03:33:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:33:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:33:21 INFO - Cleaning up... 03:33:21 INFO - Return code: 0 03:33:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:33:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:21 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 0x104be1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bf1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd12145e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a80e00>, '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 0x1048e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7bb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:33:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:33:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:33:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render', 03:33:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:33:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:33:21 INFO - 'HOME': '/Users/cltbld', 03:33:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:33:21 INFO - 'LOGNAME': 'cltbld', 03:33:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:21 INFO - 'MOZ_NO_REMOTE': '1', 03:33:21 INFO - 'NO_EM_RESTART': '1', 03:33:21 INFO - 'PAGER': '/bin/cat', 03:33:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:33:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:21 INFO - 'PWD': '/builds/slave/test', 03:33:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:33:21 INFO - 'SHELL': '/bin/bash', 03:33:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners', 03:33:21 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:33:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:33:21 INFO - 'USER': 'cltbld', 03:33:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:33:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:33:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:21 INFO - 'XPC_FLAGS': '0x0', 03:33:21 INFO - 'XPC_SERVICE_NAME': '0', 03:33:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:33:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:33:21 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-PBjBeq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:33:21 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:33:21 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-O_Uqr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:33:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:33:21 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-j6GbH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:33:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:33:21 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-RVPSeq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:33:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:33:21 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-nXFpZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:33:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:33:21 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-gTgwRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:33:22 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-L3PcJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:33:22 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-JXsRgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:33:22 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-63vmqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:33:22 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-zKKkfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:33:22 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-bP_K2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:33:22 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-O36d07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:33:22 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-gQwOFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:33:22 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-9T07PP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:33:22 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-Clgn3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:33:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:33:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-Sk_7Ds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:33:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:33:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-NRt726-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:33:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:33:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:33:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:33:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:33:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:33:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:33:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:33:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:25 INFO - Downloading blessings-1.6.tar.gz 03:33:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:33:25 INFO - Installing collected packages: blessings 03:33:25 INFO - Running setup.py install for blessings 03:33:25 INFO - Successfully installed blessings 03:33:25 INFO - Cleaning up... 03:33:26 INFO - Return code: 0 03:33:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:33:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:33:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:33:26 INFO - Reading from file tmpfile_stdout 03:33:26 INFO - Current package versions: 03:33:26 INFO - blessings == 1.6 03:33:26 INFO - blobuploader == 1.2.4 03:33:26 INFO - docopt == 0.6.1 03:33:26 INFO - manifestparser == 1.1 03:33:26 INFO - mozInstall == 1.12 03:33:26 INFO - mozcrash == 0.17 03:33:26 INFO - mozdebug == 0.1 03:33:26 INFO - mozdevice == 0.48 03:33:26 INFO - mozfile == 1.2 03:33:26 INFO - mozhttpd == 0.7 03:33:26 INFO - mozinfo == 0.9 03:33:26 INFO - mozleak == 0.1 03:33:26 INFO - mozlog == 3.1 03:33:26 INFO - moznetwork == 0.27 03:33:26 INFO - mozprocess == 0.22 03:33:26 INFO - mozprofile == 0.28 03:33:26 INFO - mozrunner == 6.11 03:33:26 INFO - mozscreenshot == 0.1 03:33:26 INFO - mozsystemmonitor == 0.0 03:33:26 INFO - moztest == 0.7 03:33:26 INFO - mozversion == 1.4 03:33:26 INFO - psutil == 3.1.1 03:33:26 INFO - requests == 1.2.3 03:33:26 INFO - wsgiref == 0.1.2 03:33:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:26 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 0x104be1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bf1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd12145e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a80e00>, '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 0x1048e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7bb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:33:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:33:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:33:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render', 03:33:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:33:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:33:26 INFO - 'HOME': '/Users/cltbld', 03:33:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:33:26 INFO - 'LOGNAME': 'cltbld', 03:33:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:26 INFO - 'MOZ_NO_REMOTE': '1', 03:33:26 INFO - 'NO_EM_RESTART': '1', 03:33:26 INFO - 'PAGER': '/bin/cat', 03:33:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:33:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:26 INFO - 'PWD': '/builds/slave/test', 03:33:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:33:26 INFO - 'SHELL': '/bin/bash', 03:33:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners', 03:33:26 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:33:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:33:26 INFO - 'USER': 'cltbld', 03:33:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:33:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:33:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:26 INFO - 'XPC_FLAGS': '0x0', 03:33:26 INFO - 'XPC_SERVICE_NAME': '0', 03:33:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:33:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:33:26 INFO - Cleaning up... 03:33:26 INFO - Return code: 0 03:33:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:26 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 0x104be1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bf1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd12145e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a80e00>, '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 0x1048e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7bb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:33:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:33:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:33:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render', 03:33:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:33:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:33:26 INFO - 'HOME': '/Users/cltbld', 03:33:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:33:26 INFO - 'LOGNAME': 'cltbld', 03:33:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:26 INFO - 'MOZ_NO_REMOTE': '1', 03:33:26 INFO - 'NO_EM_RESTART': '1', 03:33:26 INFO - 'PAGER': '/bin/cat', 03:33:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:33:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:26 INFO - 'PWD': '/builds/slave/test', 03:33:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:33:26 INFO - 'SHELL': '/bin/bash', 03:33:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners', 03:33:26 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:33:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:33:26 INFO - 'USER': 'cltbld', 03:33:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:33:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:33:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:26 INFO - 'XPC_FLAGS': '0x0', 03:33:26 INFO - 'XPC_SERVICE_NAME': '0', 03:33:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:33:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:26 INFO - Downloading/unpacking jsonschema 03:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:33:29 INFO - Downloading/unpacking functools32 (from jsonschema) 03:33: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 03:33: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 03:33: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 03:33: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 03:33: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 03:33: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 03:33:29 INFO - Downloading functools32-3.2.3-2.tar.gz 03:33:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:33:29 INFO - warning: no files found matching '*.txt' 03:33:29 INFO - no previously-included directories found matching 'build' 03:33:29 INFO - no previously-included directories found matching 'dist' 03:33:29 INFO - no previously-included directories found matching '.git*' 03:33:29 INFO - Installing collected packages: jsonschema, functools32 03:33:29 INFO - Running setup.py install for functools32 03:33:29 INFO - warning: no files found matching '*.txt' 03:33:29 INFO - no previously-included directories found matching 'build' 03:33:29 INFO - no previously-included directories found matching 'dist' 03:33:29 INFO - no previously-included directories found matching '.git*' 03:33:29 INFO - Successfully installed jsonschema functools32 03:33:29 INFO - Cleaning up... 03:33:30 INFO - Return code: 0 03:33:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:33:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:33:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:33:30 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104be1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bf1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd12145e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a80e00>, '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 0x1048e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7bb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:33:30 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', 'flake8'] in /builds/slave/test/build 03:33:30 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 flake8 03:33:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render', 03:33:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:33:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:33:30 INFO - 'HOME': '/Users/cltbld', 03:33:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:33:30 INFO - 'LOGNAME': 'cltbld', 03:33:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:30 INFO - 'MOZ_NO_REMOTE': '1', 03:33:30 INFO - 'NO_EM_RESTART': '1', 03:33:30 INFO - 'PAGER': '/bin/cat', 03:33:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:33:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:30 INFO - 'PWD': '/builds/slave/test', 03:33:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:33:30 INFO - 'SHELL': '/bin/bash', 03:33:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners', 03:33:30 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:33:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:33:30 INFO - 'USER': 'cltbld', 03:33:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:33:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:33:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:30 INFO - 'XPC_FLAGS': '0x0', 03:33:30 INFO - 'XPC_SERVICE_NAME': '0', 03:33:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:33:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:33:30 INFO - Downloading/unpacking flake8 03:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:33:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:33: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 03:33: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 03:33: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 03:33: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 03:33: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 03:33: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 03:33:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:33:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:33:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:33:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:33:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:33:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:33:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:33:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:33:34 INFO - no previously-included directories found matching 'docs/_build' 03:33:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:33:34 INFO - Running setup.py install for pep8 03:33:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:33:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:33:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:33:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:33:34 INFO - no previously-included directories found matching 'docs/_build' 03:33:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:33:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:33:34 INFO - Cleaning up... 03:33:34 INFO - Return code: 0 03:33:34 INFO - Running post-action listener: _resource_record_post_action 03:33:34 INFO - Running post-action listener: _start_resource_monitoring 03:33:34 INFO - Starting resource monitoring. 03:33:34 INFO - ##### 03:33:34 INFO - ##### Running install step. 03:33:34 INFO - ##### 03:33:34 INFO - Running pre-action listener: _resource_record_pre_action 03:33:34 INFO - Running main action method: install 03:33:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:33:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:33:34 INFO - Reading from file tmpfile_stdout 03:33:34 INFO - Detecting whether we're running mozinstall >=1.0... 03:33:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:33:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:33:34 INFO - Reading from file tmpfile_stdout 03:33:34 INFO - Output received: 03:33:34 INFO - Usage: mozinstall [options] installer 03:33:34 INFO - Options: 03:33:34 INFO - -h, --help show this help message and exit 03:33:34 INFO - -d DEST, --destination=DEST 03:33:34 INFO - Directory to install application into. [default: 03:33:34 INFO - "/builds/slave/test"] 03:33:34 INFO - --app=APP Application being installed. [default: firefox] 03:33:34 INFO - mkdir: /builds/slave/test/build/application 03:33:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:33:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 03:33:56 INFO - Reading from file tmpfile_stdout 03:33:56 INFO - Output received: 03:33:56 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 03:33:56 INFO - Running post-action listener: _resource_record_post_action 03:33:56 INFO - ##### 03:33:56 INFO - ##### Running run-tests step. 03:33:56 INFO - ##### 03:33:56 INFO - Running pre-action listener: _resource_record_pre_action 03:33:56 INFO - Running main action method: run_tests 03:33:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:33:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:33:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:33:56 INFO - 2016-07-03 03:33:56.421 screenresolution[1657:8461] starting screenresolution argv=screenresolution get 03:33:56 INFO - 2016-07-03 03:33:56.423 screenresolution[1657:8461] Display 0: 1600x1200x32@60 03:33:56 INFO - 2016-07-03 03:33:56.429 screenresolution[1658:8463] starting screenresolution argv=screenresolution list 03:33:56 INFO - Available Modes on Display 0 03:33:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:33:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:33:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:33:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:33:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:33:56 INFO - Intel Iris: 03:33:56 INFO - Chipset Model: Intel Iris 03:33:56 INFO - Type: GPU 03:33:56 INFO - Bus: Built-In 03:33:56 INFO - VRAM (Dynamic, Max): 1536 MB 03:33:56 INFO - Vendor: Intel (0x8086) 03:33:56 INFO - Device ID: 0x0a2e 03:33:56 INFO - Revision ID: 0x0009 03:33:56 INFO - Displays: 03:33:56 INFO - Display: 03:33:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:33:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:33:56 INFO - Display Serial Number: 1600x1200 60 03:33:56 INFO - Main Display: Yes 03:33:56 INFO - Mirror: Off 03:33:56 INFO - Online: Yes 03:33:56 INFO - Rotation: Supported 03:33:56 INFO - Return code: 0 03:33:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:33:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:33:56 INFO - Python 2.7.3 03:33:56 INFO - Return code: 0 03:33:56 INFO - grabbing minidump binary from tooltool 03:33:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:33:56 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a80e00>, '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 0x1048e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:33:56 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:33:56 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:33:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:33:56 INFO - Return code: 0 03:33:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:33:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:33:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:33:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:33:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:33:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:33:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:33:57 INFO - Return code: 0 03:33:57 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.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:33:57 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.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0006 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:33:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render', 03:33:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:33:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:33:57 INFO - 'HOME': '/Users/cltbld', 03:33:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:33:57 INFO - 'LOGNAME': 'cltbld', 03:33:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:33:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:33:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:57 INFO - 'MOZ_NO_REMOTE': '1', 03:33:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:33:57 INFO - 'NO_EM_RESTART': '1', 03:33:57 INFO - 'PAGER': '/bin/cat', 03:33:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:33:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:57 INFO - 'PWD': '/builds/slave/test', 03:33:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:33:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:33:57 INFO - 'SHELL': '/bin/bash', 03:33:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners', 03:33:57 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:33:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:33:57 INFO - 'USER': 'cltbld', 03:33:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:33:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:33:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:57 INFO - 'XPC_FLAGS': '0x0', 03:33:57 INFO - 'XPC_SERVICE_NAME': '0', 03:33:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:33:57 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.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:33:58 INFO - mozversion application_buildid: 20160703011523 03:33:58 INFO - mozversion application_changeset: 13ce99eb20be5ebc3ff877ac0b1c12f88e9e4d67 03:33:58 INFO - mozversion application_display_name: Firefox 03:33:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:33:58 INFO - mozversion application_name: Firefox 03:33:58 INFO - mozversion application_remotingname: firefox 03:33:58 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 03:33:58 INFO - mozversion application_vendor: Mozilla 03:33:58 INFO - mozversion application_version: 48.0 03:33:58 INFO - mozversion platform_buildid: 20160703011523 03:33:58 INFO - mozversion platform_changeset: 13ce99eb20be5ebc3ff877ac0b1c12f88e9e4d67 03:33:58 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 03:33:58 INFO - mozversion platform_version: 48.0 03:33:58 INFO - using testdate: 1467542038 03:33:58 INFO - actual date: 1467542038 03:33:58 INFO - starting webserver on 'localhost:49275' 03:33:58 INFO - SUITE-START | Running 2 tests 03:33:58 INFO - TEST-START | dromaeo_css 03:33:58 INFO - operating with platform_type : mac_ 03:33:58 INFO - Initialising browser for dromaeo_css test... 03:33:58 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpgrIrSU/profile http://localhost:49275/getInfo.html) 03:34:00 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 03:34:00 INFO - PROCESS | 1676 | colorDepth:24 03:34:00 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 03:34:00 INFO - PROCESS | 1676 | __metrics 03:34:00 INFO - PROCESS | 1676 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:34:00 ERROR - PROCESS | 1676 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:34:01 INFO - TEST-INFO | 1676: exit 0 03:34:01 INFO - Browser initialized. 03:34:01 INFO - Running cycle 1/1 for dromaeo_css test... 03:34:01 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpgrIrSU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:34:12 INFO - PROCESS | 1680 | RSS: Main: 161759232 03:34:12 INFO - PROCESS | 1680 | 03:35:04 INFO - PROCESS | 1680 | 1467542104065 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:37:14 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 03:37:15 INFO - PROCESS | 1680 | RSS: Main: 282517504 03:37:15 INFO - PROCESS | 1680 | 03:40:17 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 03:40:18 INFO - PROCESS | 1680 | RSS: Main: 307851264 03:40:18 INFO - PROCESS | 1680 | 03:43:20 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 03:43:21 INFO - PROCESS | 1680 | RSS: Main: 311328768 03:43:21 INFO - PROCESS | 1680 | 03:46:23 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 03:46:23 INFO - PROCESS | 1680 | RSS: Main: 305635328 03:46:23 INFO - PROCESS | 1680 | 03:49:26 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 03:49:27 INFO - PROCESS | 1680 | RSS: Main: 270290944 03:49:27 INFO - PROCESS | 1680 | 03:52:29 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 03:52:30 INFO - PROCESS | 1680 | RSS: Main: 315297792 03:52:30 INFO - PROCESS | 1680 | 03:52:30 INFO - PROCESS | 1680 | __start_tp_report 03:52:30 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 03:52:30 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 03:52:30 INFO - PROCESS | 1680 | |i|pagename|runs| 03:52:30 INFO - PROCESS | 1680 | |0;dojo.html;3540.610532841388;3581.194231297957;3590.8384122714474;3617.113807117257;3679.5768486624047;5790.04464263396;5808.970955145219;5826.388229235928;5860.179574859518;5889.057750759879;5776.566757493184;5818.650880947146;5821.912671309934;5825;5847.152162936382;5206.375234971809;5214.582833373321;5237.528622423981;5257.474252574751;5309.362876454831;5285.127953887604;5316.096263635172;5320.6275560710765;5324.015057214408;5326.973365133169;5231.529162375386;5281.867953301179;5282.603804714642;5306.522412982831;5338.199270109495;5321.201819727053;5349.43830897754;5353.277307563468;5355.116405793078;5396.622236443451;5118.539331460186;5284.260203571503;5294.100002999499;5299;5301.840944771625;5035.899282014339;5595.580331475115;5687.289088863892;5700.087985922232;5774.4802967732685;6236.251649802053;6272.686365681698;6276.215473065867;6295.968520157416;6306;6189.566730328879;6253.562250642457;6254;6275.560710750249;6308.684565771693;5876.7649294028615;5925.140854576062;5968.820935371946;5981.820545383602;5991.400859913973;6312.43188113072;6316.242050953915;6317.936820631735;6346.492280617632;6352.809415717536;6131.468419844996;6142.877142457126;6275.5920865144535;6289.40250676185;6292.119103325538;6277.372262773777;6283.59156654816;6285.308616052231;6286;6311.463525600376;6283.120363149163;6283.59156654816;6284.528660350492;6288.968555157195;6297.055441683692;6172.629642221481;6241;6273.247210334789;6285.120083188357;6285.277193122849;4963.801447942042;4967.776450059756;4968.577670897942;5009.248612708123;5030.220315851049;4508.211063063951;4514.841980530666;4519;4528.230200865861;4552.840650577214;5842.941570584239;5968.820935371946;5977.043673012297;5978.073398623221;5994.7602095916545;6148.631082135086;6187.721552530147;6233.688315584202;6245.6252624842655;6255.155554000243;5994.250718660168;5994.820155395258;5995.640261584319;5996.2504686914135;6005.099235114768;5441.374241962146;5519.530839878575;5541.085720856014;5546.417626149197;5557.944420555824;6328;6367.267764207176;6496.187976502937;6502.544821862519;6502.6748662567625;6230.968845155745;6234.750609975732;6235.220597425322;6250.062490626442;6250.531210159347;6456.903146452713;6463.903041454476;6466.612003279818;6467.708953097216;6491.902621460777;5780.335261444988;6189.81430557084;6214.627122372672;6229.159063526366;6234.096056071753;6120.90429240112;6244.219472565929;6248.4688801452385;6252.499800015897;6258.843528911813;5875.529957603296;5961.254843144607;6194.5044396449075;6200;6204.5967012144065;6073.301570319293;6128.172696686069;6196.907046394217;6203.844903877259;6232.034034724625;6042.607230530001;6078.574499784972;6103.481204097701;6114.0828875669;6124.724387402489;6218.968905155626;6230.376962303733;6234.563580549318;6243.37566243372;6244.718987645658;5977.9402205977385;5987.820365389045;6054.969725151346;6067.908981365372;6141.6007959482495 03:52:30 INFO - PROCESS | 1680 | |1;ext.html;547.6686604604214;549.6207616744446;569.4533248081841;576.7714767545134;629.6725702634631;32200.838995805017;32812;32828.83585582075;38227.80886095572;39444;44607;45460;45540.08919821602;45579.31631025537;45622.17511299544;9812.70561883143;10059.597616095347;10084.949575252114;10095.29332946694;10099.848502272473;9240.5841737122;9815.362001469917;11807.881921180764;12189.573364932228;12221.63335099944;11838.585649502269;11932.821007684892;11936.10479214058;11936.283822970607;11955.641330760047;11889.524419023186;11890.346030968294;11994.580189693406;12004.819927701092;12035.157538972275;11728;11837.171398002143;11837.526498940031;11851.407429628483;11868.347240901747;41569.79215103936;41818;41953.95115122116;41955.79022104901;42037.579624203674;3367.747418943589;3460.325236578868;3540.946885796715;3557.0654210276366;3644.4533320001947;28564.71435285662;29712.257193570116;29961.70038299633;30016.69983300161;30062.849685751433;2762.2818067302446;2816.9718302816914;2843.3460304130167;2902.782291328159;2934.8826046958097;419992;420724;421395;421454;422230;36604.450933236025;37437.43843842344;37450.812745936644;63408;64900;36079.81960090235;36264;36314;36314.8184259077;36360;16091.114988675736;16275.674486510203;16290.755638665429;16295;16321.83678163227;12805;13004.024698147488;13715.451381944611;13759.036867419385;13781;8361.247487726134;8535.615897284637;8575.914240857512;8612.526311052952;8640.444719950468;3038.9696103039128;3540.3804334241404;3542.6457354264885;3544.787312761191;3580.820958952042;1021.1524434719314;1043.6347278452633;1079.093561408405;1088.4775307852276;1118.3904771899333;29914.102576922727;30245.848770756005;30527.69472305293;30917.845410772803;31538.526922096185;1109.0351394287022;1210.6973256685828;1245.1346314311647;1270.5616562285998;1286.7812471879804;15738.685226295638;16047.518574442553;16314.347426102822;16317.918410407872;16478.42325518601;637151;644338;645838;646079;648542;61988.69005654943;63387.04919426212;63472.36527634757;63567.36432635707;63649.68175159094;1433.5412667946366;1464.5825939607162;1571.591386239586;1574.1027614259763;1591.3475475055172;25093.372665683502;29892.55161172541;30750.692493074785;31153.221169470944;31431.842840785648;51259.487405125474;51944.22083668673;52180.73909630428;52180.73909630428;52374;547511;564663;564856;565932;567151;37630;47476.287855681505;47981.52018479771;48249.552513424656;48302.55092347235;48139.75930120327;48525;49091.75454122706;49809.75095124501;50316;38170.427443588924;38992.80503597464;40075.79962100171;42599;43338.349924750524;38368.80815595904;39072.80463597664;39257.607423926536;39868;39973.60026399815;38315.808420957714;38908;39306;39935.60064399319;39960.80019599883;32836.67163328336;34837.12907177341;34953.98437655315;35115.64884351226;35130.82434587811;22661.320160395215;25558.616620751076;25799.742002580486;25992.22023339303;26128.216153515426 03:52:30 INFO - PROCESS | 1680 | |2;jquery.html;397.0411456332957;406.7579790024936;413.93583994480855;415.32095024634714;415.8523724077952;14644.560663180095;20289.391318260437;21039.2636257731;21080.472988175323;21281.57436851264;20399.592008159827;20555;21389.465263368424;21436.8928155359;21476.89261553694;5878.441548052928;5907.261592300963;6197.16338294329;6204.6587437690905;6205.9379406206035;6060.2424696912885;6143.723532441051;6173.969130154365;6175.814725558241;6176;4468.731876087429;5408.756605952722;5548.6948217848;5553.305836770403;5556.249906262663;5527.143292789624;5528.612997090206;5528.695921724304;5532.087205611069;5537.889242215153;3849.5188101487315;5819.505342045931;5999.370066143036;6003.489703375219;6005.879882402328;20702.378928632166;26500.46999060008;26614.33464163392;27095.45809083827;27108.1867543974;2040.9387718368473;2316.3861576682193;2511.698596168453;2835.758960488361;2871.4400691865094;14820.258987050713;15354.769678454832;15393.846061539465;15716.057036577728;15750.369985200578;1474.7714104313848;1595.664910368824;1756.525738050732;1779.5373202967191;1798.4604618614364;106832;115001.2402639275;116640;118997.40501297438;120132.19801702982;11066.833997490045;11260.662180134608;11404.087672986307;11485.253458525169;11563.768724625628;12862.935685321514;13382.263975481248;13422.865771342162;14457.566273011826;14469.565913022627;8589.699360522352;8948.239399650973;9042.553191489462;9146.54267286633;9154.725358239264;9430.62277508892;9450.716478505656;9457.527123643791;9511.90488095124;9529.428234305964;6628.839953008204;6673.833154171088;6789.287124851919;6809.863802723918;6849.2465828758795;1667.6914770767446;1707.7865266841645;1939.360011196302;1943.1741509858255;1953.6483432981915;891.9152680495342;936.3819878879907;1003.1723827841913;1095.1676725688535;1124.707576030238;20211.8989405052;20555.327807384943;21862.672059919114;22018.339449816533;22166.224182153546;961.9663311784194;994.2756846241247;1013.4020927652573;1031.6904928521415;1112.3326004397459;11434.371109589052;11740.471678774476;11786.823197652042;11794.88205117955;11835.467403966843;679230;688807.0589175785;690162;690814;695814;117942.82057179319;122373.38813305876;122492.55014899652;123531.38234308771;126958.09562856564;1088.5101704233127;1097.1661537231762;1099.9725006874892;1100.3672888089382;1103.431732657682;15634.374625014872;18729.531761706065;19187.328443504968;19206.327778527684;19574.021298934713;51390.201342952816;51839.74080129575;52714.20928686149;53328.46671533235;54161.72919135379;72377.91433128613;73395.63302183455;73442.63278683786;73618;74078.25921740713;8080.676772929017;8187.918120818716;8215.424920255764;8354.707585234732;8354.916450835413;11372.20394572372;12290.385480726107;13228.669283267995;13460.855827254793;13552.79670804919;11439.42802859837;11444.256123351955;11703.531858725555;11862.644120676394;11913.046956243306;8927.821443570961;9851.507424628597;11228.66314010581;11864.525418983489;12049.036077113986;11802.58690945847;11816.350100744541;11829.585964491202;12015.75968480608;12102;8309.127541608044;8377.70678026266;8423;8453.408261421642;8615.095414981588;11008.834867477155;11606.24559403636;11799.882001180222;11950.103742219195;12017.819732703842 03:52:30 INFO - PROCESS | 1680 | |3;mootools.html;1394.26800929512;1470.0738534723137;1630.5352974402297;1834.9633007339853;2004.1682701678803;8147.388945829057;8844.38089333746;9008.459492430458;9013.684521041765;9019.774505637362;8669.566521673923;8930.017698053225;8941.776455588613;8945.239654629364;8959.820803583925;2146.420466474051;2507.323022783847;2532.0884481586613;2534.6324782906468;2536.5180615683053;2467.617519284505;2540.8856601452885;2542.8092893033004;2544.5038217547585;2564.4230048239183;2514.88683009265;2541.4154744408797;2546.567083595793;2550.7959363250893;2554.067765265683;2139.3902737719754;2533.1893793985932;2546.5288921549572;2546.987265063663;2548.7960963122905;2489.55188066148;2529.2538701083226;2536.239128261514;2536.5053814506177;2543.135333986435;8230.711925082653;8420.957895210546;9027.7291681249;9051.27589792815;9090.50002249876;5476.288082549283;5586.776528938837;5628.521575666074;5640.266765320482;5640.520555752766;3581.0510214793103;3668.944965825515;3732.42147467143;3735.1409175889426;3743.8689645862264;951.7240000399876;1064.4943651765427;1105.6627728542792;1106.9335839849634;1117.1788735279563;11763.588274410355;12124.939375303067;12155.331456769874;12161.513539458323;12188.024958003338;4028.234635419261;4639.280911458662;4657.022025374641;4773.307870358779;4787.42550893888;9384.061593840697;9391.29565282593;9515.191208747334;9533.570989305359;9533.856992144985;5444.754986025639;5445.455454454523;5462.9726851365485;5476.808311709151;5490.972545137249;4545.204589196877;4726.763661816895;4902.90194196114;5088;5123.513266239701;3875.8643447479767;3962.7622342659533;4004.739691920016;4011.3782363733667;4014.2573623879675;1060.9681709548727;1208.5045131496045;1235.1354052163522;1240.2186622427812;1254.3978890132787;956.933014688979;992.2855544008303;992.7617371830709;995.2038369304528;996.0388225362415;5870.82583483305;6011.188489553854;6080.300765411946;6093;6274.152989346471;441.5231549926072;467.55644676954665;487.2934245344265;488.90466359059644;489.24656029713844;3937.901552461154;4028.436018957348;4069.7965101745385;4148.56440073794;4158.2307273154565;9826.557804898797;11110.222284440011;11190.272632278877;11248.718782030515;11266.774664506664;10858.131349492021;10888.67333979982;10963.83554246687;10994.560217591206;11025.889741102646;6696;8446.028706698686;8452.915470845335;8467.280281176169;8518.190771876662;5968.46283834457;5977.790777322773;6081.563203249278;6116.724747386289;6119.755209791559;9742;9806.901930980599;9941.701748947255;9947.154491868272;9953.104220620178;9712.660056897974;10146.594136234467;10193.337433066827;10241.9487902563;10290.536925838205;6982.68577913985;7171.4979951404985;7176.533525320838;7717.961410192913;7853.842923141391;7931.920680793349;8541.359398045295;8571.571421428778;8571.95714021426;8573.614187361709;7452.105747310357;7569.924300756922;7643.121041080347;7659.578723170279;7667.233276672288;7208.639568021473;7208.639568021683;7208.819779505554;7316.707331706672;7317.67070481819;7052.483716001085;7110.25342339047;7113.146422429341;7149.0706208192605;7210.062691850026;4959.752012399294;5029.497050294941;5040.8991820162655;5053.74731263428;5094.363204599425;4996.300517927566;5070.8225212117395;5091.236314552847;5150.433452320317;5332.840014799563 03:52:30 INFO - PROCESS | 1680 | |4;prototype.html;332.4871833159126;337.1082948183034;345.65779877920863;346.1553808706755;346.5512161750533;5589.692566908823;6474.0612611171355;6492.902606460907;6696.296888826676;6749.392554670078;6433.806985790434;6669.599824010563;6670.966000269966;6678.098456708343;6706.061151438803;4941.802327906879;4998.400191976968;5011.223260394645;5016.824411145611;5028.622853286;3802.9809850950705;3904.297226499229;4231.301835197189;4943.184374578191;4992.950070499285;4669.603083737887;4751.978324660197;4875.41495020598;4941.925871111918;4958.082754690376;4865.41615006198;4910;4911.189653707134;4914.5085491450745;4916.975415122919;3591.4612808078737;4240.215560121356;4244.938765308673;4244.978775106136;4289.785510724451;6407.263164736069;6410.1987251593555;6412.262589802139;6417.294097649255;6437.259715132773;5484.122540393558;5867.647941123546;5871.178035075093;5883.264591926009;5894.8821023579285;6973.093497845248;6994.33553812387;7025.086738723984;7036.190838053587;7042.4366050716335;4580.427446569179;4591.265397536378;4595.172868883599;4598.425196850394;4618.5843274105155;7321.048263725735;7512.849743005109;7524.849503009964;7528.661210245552;7535.095788505414;4664.253719404879;4668.95331046692;4675.3220782986955;4680.274557443602;4687.10944920464;4046.615571520701;4576.565226303496;4706.294055891643;4714.622830173578;4737.620990320766;5612.466815652507;5667.121596152603;5672.234248376499;5690.857728556819;5709.543236541067;5954.970225148846;7054.153501579741;7094.077769889881;7098.6450677466955;7112.679929403189;1573.3627880708332;1629.1528405229215;1660;1662.542800729809;1663.8502534771928;1003.8042581696645;1041.9892704077033;1056.1234175634206;1057.1067448006422;1065.3448129400363;6697.9665101675155;6718.697658605374;6758.729650814011;6762.526623136431;6776.69504872282;6576.934230657728;6927.7228910844005;7404.888926666104;7422.369098626677;7432.405407567381;5594.720263986785;6761.053452516651;6781.050652908597;6783.660816959133;6798.422134118557;3825.407061905409;3870.4581358609817;3922.529051605648;3927.9607203927594;3995.380715989026;6645.933540664628;7819;7939.404544659174;7945.245201705829;7986.241307075818;6387.329330420332;7742.380609551223;7748.070231572247;7759.379249660014;7773.416993725493;7931.682732690743;7946.40401969843;7953.920460795318;7977.242161994485;7986.960065199637;7265.309795569412;7486.251374862688;7519.43604229674;7520.811979700551;7533.623318834146;6556.8197724410065;6953.33943275388;7352.889706654511;7384.778456646309;7390.667419966222;7899.210078992055;7917.129115797143;7924.326432253323;7925.167857374892;7934.127246002819;4682.601978831837;4747.121782470254;4747.64392670558;4749.26386410107;4758.976205118952;6152.969235153795;7280.162781280221;7284.235155308616;7473.850522989618;7479.551226926402;5600.663960162403;6200.906986395298;6282.371762823681;7258.709651613876;7275.8908616371855;7228.024216730674;7234.240404757636;7240.456965727486;7244.782656520313;7252.818679533054;7072.611006394698;7078.398336141486;7085.078939737801;7090.929090709234;7117.537360071578;5623.8875222496135;6970.337817907493;7181.712731490891;7240.239774823568;7259.52813067149;6912.723491060301;6945.01364413287;7080.362767350963;7126.536775109601;7139.643017849191 03:52:30 INFO - PROCESS | 1680 | |5;yui.html;744.3821628048719;746.3469464218808;747.249014740186;765.5138986743418;775.8797386405106;14990;15359;15371.923965322432;15374;15374.154421506813;13984.790228146561;14148;14173.503927362519;14185.574432767007;14187.077839940397;772.3666593393419;793.2583034862422;951.3150531617224;968.7578105473632;970.1268858027761;961.3943215774052;968.1818863060738;972.9756756081084;975.902409759022;977.2231076294363;830.7756905635475;1031.1183937733242;1037.1650821089058;1038.8389799581078;1039.8856125826153;1023.5445226874044;1043.4156872151582;1045.8849526552074;1049.108257980718;1049.774298525813;775.6548335990486;778.4667502760627;780.6682160081999;781.1134362498548;781.1602527283137;14924.850751492455;15011.849881501264;15019.624509387244;15019.849801502063;15073;12731.045171612166;12798.936005320007;12993.350332483433;13156.539521116714;13276.86723132766;10256.333338332985;10262.43556604394;10316.432596207205;10584.735381615445;11035.172362072877;3558.5018097466377;3566.5720113586535;3610.963890361089;3667.541557305337;3836.3861782114727;82323.17676823215;82777;82963.92590185233;84559.57720211359;85377.57311213404;9635.807283854283;9700.126988571063;9758.365706229073;9776.071273229032;9983.500824958723;21563.029663665173;21884.562308753735;21903.452413689785;22486.66270005951;22495.100195992305;7021.684024218923;7569.470137090461;7657.387409007266;8007.199280072063;8125;4153.7300075494995;4199.601037901456;4264.168487144977;4265.616094551505;4472.195004899084;3722.236941427001;3762.3227818992846;3763.34141525232;3769.717271204616;3779.225258821935;674.5008693566725;703.7923812475332;736.2784471218237;739.2865884421559;739.9186089530148;529.0926061803948;554.4677109974389;586.961847479921;594.6461855196152;617.586217234454;8284.585770711441;8416.158384161536;8490.320774338039;8569;8591.742247732578;557.2226743692607;592.6473748119862;605.5579427018301;614.9877002459925;634.7714822663837;3984.0438294809037;3988.1624858779983;4033.072393349546;4095.815688294034;4182.581741825793;94286.5285673581;94432.58351124739;98535.50732246293;98542;99091.50454247683;63547;80783.59608201921;81397;81432;83173;67065;67158.99999999903;70997;71125.64437177782;71735;7786.727464538714;7840.215978402114;7950.642221099949;8089.47418417801;8289.461185022954;48987;49060.75469622629;50189.49810501848;51046;51206.74396627993;65660;65897;70302;70417.64791176011;70923.22691932743;10924;11231.831522527333;11502.482388292714;11611.6516504505;11791.528338866348;12048.397580120783;12161.148719589897;12220.144589878622;12256.571020014257;12780;7782.571958542335;11215.887841121485;11583.594574189863;12394.132410731163;12569.371531423576;10310.381377117397;11463.942680286878;11843.58547450835;11905.702357441134;11906.34515101643;9982.35114717541;11451.828222576834;11597.420128993685;11645.6506304811;11761.176717630025;9574.04259574065;9831.705048848546;9857.063578959987;9978.101970822661;10002.649907253211;9859.852102218616;10029.29794914349;10033.949830251095;10042.845072816719;10053.246006549392 03:52:30 INFO - PROCESS | 1680 | __end_tp_report 03:52:30 INFO - PROCESS | 1680 | __start_cc_report 03:52:30 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1005 03:52:30 INFO - PROCESS | 1680 | __end_cc_report 03:52:30 INFO - PROCESS | 1680 | __startTimestamp1467543150250__endTimestamp 03:52:30 INFO - PROCESS | 1680 | 03:52:30 INFO - PROCESS | 1680 | ------- Summary: start ------- 03:52:30 INFO - PROCESS | 1680 | Number of tests: 6 03:52:30 INFO - PROCESS | 1680 | 03:52:30 INFO - PROCESS | 1680 | [#0] dojo.html Cycles:165 Average:5832.42 Median:6067.91 stddev:615.15 (10.1%) stddev-sans-first:590.18 03:52:30 INFO - PROCESS | 1680 | Values: 3540.6 3581.2 3590.8 3617.1 3679.6 5790.0 5809.0 5826.4 5860.2 5889.1 5776.6 5818.7 5821.9 5825.0 5847.2 5206.4 5214.6 5237.5 5257.5 5309.4 5285.1 5316.1 5320.6 5324.0 5327.0 5231.5 5281.9 5282.6 5306.5 5338.2 5321.2 5349.4 5353.3 5355.1 5396.6 5118.5 5284.3 5294.1 5299.0 5301.8 5035.9 5595.6 5687.3 5700.1 5774.5 6236.3 6272.7 6276.2 6296.0 6306.0 6189.6 6253.6 6254.0 6275.6 6308.7 5876.8 5925.1 5968.8 5981.8 5991.4 6312.4 6316.2 6317.9 6346.5 6352.8 6131.5 6142.9 6275.6 6289.4 6292.1 6277.4 6283.6 6285.3 6286.0 6311.5 6283.1 6283.6 6284.5 6289.0 6297.1 6172.6 6241.0 6273.2 6285.1 6285.3 4963.8 4967.8 4968.6 5009.2 5030.2 4508.2 4514.8 4519.0 4528.2 4552.8 5842.9 5968.8 5977.0 5978.1 5994.8 6148.6 6187.7 6233.7 6245.6 6255.2 5994.3 5994.8 5995.6 5996.3 6005.1 5441.4 5519.5 5541.1 5546.4 5557.9 6328.0 6367.3 6496.2 6502.5 6502.7 6231.0 6234.8 6235.2 6250.1 6250.5 6456.9 6463.9 6466.6 6467.7 6491.9 5780.3 6189.8 6214.6 6229.2 6234.1 6120.9 6244.2 6248.5 6252.5 6258.8 5875.5 5961.3 6194.5 6200.0 6204.6 6073.3 6128.2 6196.9 6203.8 6232.0 6042.6 6078.6 6103.5 6114.1 6124.7 6219.0 6230.4 6234.6 6243.4 6244.7 5977.9 5987.8 6055.0 6067.9 6141.6 03:52:30 INFO - PROCESS | 1680 | 03:52:30 INFO - PROCESS | 1680 | [#1] ext.html Cycles:180 Average:67745.00 Median:3543.72 stddev:147053.98 (4149.7%) stddev-sans-first:147379.96 03:52:30 INFO - PROCESS | 1680 | Values: 547.7 549.6 569.5 576.8 629.7 32200.8 32812.0 32828.8 38227.8 39444.0 44607.0 45460.0 45540.1 45579.3 45622.2 9812.7 10059.6 10084.9 10095.3 10099.8 9240.6 9815.4 11807.9 12189.6 12221.6 11838.6 11932.8 11936.1 11936.3 11955.6 11889.5 11890.3 11994.6 12004.8 12035.2 11728.0 11837.2 11837.5 11851.4 11868.3 41569.8 41818.0 41954.0 41955.8 42037.6 3367.7 3460.3 3540.9 3557.1 3644.5 28564.7 29712.3 29961.7 30016.7 30062.8 2762.3 2817.0 2843.3 2902.8 2934.9 419992.0 420724.0 421395.0 421454.0 422230.0 36604.5 37437.4 37450.8 63408.0 64900.0 36079.8 36264.0 36314.0 36314.8 36360.0 16091.1 16275.7 16290.8 16295.0 16321.8 12805.0 13004.0 13715.5 13759.0 13781.0 8361.2 8535.6 8575.9 8612.5 8640.4 3039.0 3540.4 3542.6 3544.8 3580.8 1021.2 1043.6 1079.1 1088.5 1118.4 29914.1 30245.8 30527.7 30917.8 31538.5 1109.0 1210.7 1245.1 1270.6 1286.8 15738.7 16047.5 16314.3 16317.9 16478.4 637151.0 644338.0 645838.0 646079.0 648542.0 61988.7 63387.0 63472.4 63567.4 63649.7 1433.5 1464.6 1571.6 1574.1 1591.3 25093.4 29892.6 30750.7 31153.2 31431.8 51259.5 51944.2 52180.7 52180.7 52374.0 547511.0 564663.0 564856.0 565932.0 567151.0 37630.0 47476.3 47981.5 48249.6 48302.6 48139.8 48525.0 49091.8 49809.8 50316.0 38170.4 38992.8 40075.8 42599.0 43338.3 38368.8 39072.8 39257.6 39868.0 39973.6 38315.8 38908.0 39306.0 39935.6 39960.8 32836.7 34837.1 34954.0 35115.6 35130.8 22661.3 25558.6 25799.7 25992.2 26128.2 03:52:30 INFO - PROCESS | 1680 | 03:52:30 INFO - PROCESS | 1680 | [#2] jquery.html Cycles:180 Average:37512.49 Median:21413.18 stddev:113950.20 (532.1%) stddev-sans-first:114235.78 03:52:30 INFO - PROCESS | 1680 | Values: 397.0 406.8 413.9 415.3 415.9 14644.6 20289.4 21039.3 21080.5 21281.6 20399.6 20555.0 21389.5 21436.9 21476.9 5878.4 5907.3 6197.2 6204.7 6205.9 6060.2 6143.7 6174.0 6175.8 6176.0 4468.7 5408.8 5548.7 5553.3 5556.2 5527.1 5528.6 5528.7 5532.1 5537.9 3849.5 5819.5 5999.4 6003.5 6005.9 20702.4 26500.5 26614.3 27095.5 27108.2 2040.9 2316.4 2511.7 2835.8 2871.4 14820.3 15354.8 15393.8 15716.1 15750.4 1474.8 1595.7 1756.5 1779.5 1798.5 106832.0 115001.2 116640.0 118997.4 120132.2 11066.8 11260.7 11404.1 11485.3 11563.8 12862.9 13382.3 13422.9 14457.6 14469.6 8589.7 8948.2 9042.6 9146.5 9154.7 9430.6 9450.7 9457.5 9511.9 9529.4 6628.8 6673.8 6789.3 6809.9 6849.2 1667.7 1707.8 1939.4 1943.2 1953.6 891.9 936.4 1003.2 1095.2 1124.7 20211.9 20555.3 21862.7 22018.3 22166.2 962.0 994.3 1013.4 1031.7 1112.3 11434.4 11740.5 11786.8 11794.9 11835.5 679230.0 688807.1 690162.0 690814.0 695814.0 117942.8 122373.4 122492.6 123531.4 126958.1 1088.5 1097.2 1100.0 1100.4 1103.4 15634.4 18729.5 19187.3 19206.3 19574.0 51390.2 51839.7 52714.2 53328.5 54161.7 72377.9 73395.6 73442.6 73618.0 74078.3 8080.7 8187.9 8215.4 8354.7 8354.9 11372.2 12290.4 13228.7 13460.9 13552.8 11439.4 11444.3 11703.5 11862.6 11913.0 8927.8 9851.5 11228.7 11864.5 12049.0 11802.6 11816.4 11829.6 12015.8 12102.0 8309.1 8377.7 8423.0 8453.4 8615.1 11008.8 11606.2 11799.9 11950.1 12017.8 03:52:30 INFO - PROCESS | 1680 | 03:52:30 INFO - PROCESS | 1680 | [#3] mootools.html Cycles:180 Average:5784.31 Median:5079.41 stddev:3227.14 (63.5%) stddev-sans-first:3219.33 03:52:30 INFO - PROCESS | 1680 | Values: 1394.3 1470.1 1630.5 1835.0 2004.2 8147.4 8844.4 9008.5 9013.7 9019.8 8669.6 8930.0 8941.8 8945.2 8959.8 2146.4 2507.3 2532.1 2534.6 2536.5 2467.6 2540.9 2542.8 2544.5 2564.4 2514.9 2541.4 2546.6 2550.8 2554.1 2139.4 2533.2 2546.5 2547.0 2548.8 2489.6 2529.3 2536.2 2536.5 2543.1 8230.7 8421.0 9027.7 9051.3 9090.5 5476.3 5586.8 5628.5 5640.3 5640.5 3581.1 3668.9 3732.4 3735.1 3743.9 951.7 1064.5 1105.7 1106.9 1117.2 11763.6 12124.9 12155.3 12161.5 12188.0 4028.2 4639.3 4657.0 4773.3 4787.4 9384.1 9391.3 9515.2 9533.6 9533.9 5444.8 5445.5 5463.0 5476.8 5491.0 4545.2 4726.8 4902.9 5088.0 5123.5 3875.9 3962.8 4004.7 4011.4 4014.3 1061.0 1208.5 1235.1 1240.2 1254.4 956.9 992.3 992.8 995.2 996.0 5870.8 6011.2 6080.3 6093.0 6274.2 441.5 467.6 487.3 488.9 489.2 3937.9 4028.4 4069.8 4148.6 4158.2 9826.6 11110.2 11190.3 11248.7 11266.8 10858.1 10888.7 10963.8 10994.6 11025.9 6696.0 8446.0 8452.9 8467.3 8518.2 5968.5 5977.8 6081.6 6116.7 6119.8 9742.0 9806.9 9941.7 9947.2 9953.1 9712.7 10146.6 10193.3 10241.9 10290.5 6982.7 7171.5 7176.5 7718.0 7853.8 7931.9 8541.4 8571.6 8572.0 8573.6 7452.1 7569.9 7643.1 7659.6 7667.2 7208.6 7208.6 7208.8 7316.7 7317.7 7052.5 7110.3 7113.1 7149.1 7210.1 4959.8 5029.5 5040.9 5053.7 5094.4 4996.3 5070.8 5091.2 5150.4 5332.8 03:52:30 INFO - PROCESS | 1680 | 03:52:30 INFO - PROCESS | 1680 | [#4] prototype.html Cycles:180 Average:5804.52 Median:6483.48 stddev:1888.75 (29.1%) stddev-sans-first:1848.85 03:52:30 INFO - PROCESS | 1680 | Values: 332.5 337.1 345.7 346.2 346.6 5589.7 6474.1 6492.9 6696.3 6749.4 6433.8 6669.6 6671.0 6678.1 6706.1 4941.8 4998.4 5011.2 5016.8 5028.6 3803.0 3904.3 4231.3 4943.2 4993.0 4669.6 4752.0 4875.4 4941.9 4958.1 4865.4 4910.0 4911.2 4914.5 4917.0 3591.5 4240.2 4244.9 4245.0 4289.8 6407.3 6410.2 6412.3 6417.3 6437.3 5484.1 5867.6 5871.2 5883.3 5894.9 6973.1 6994.3 7025.1 7036.2 7042.4 4580.4 4591.3 4595.2 4598.4 4618.6 7321.0 7512.8 7524.8 7528.7 7535.1 4664.3 4669.0 4675.3 4680.3 4687.1 4046.6 4576.6 4706.3 4714.6 4737.6 5612.5 5667.1 5672.2 5690.9 5709.5 5955.0 7054.2 7094.1 7098.6 7112.7 1573.4 1629.2 1660.0 1662.5 1663.9 1003.8 1042.0 1056.1 1057.1 1065.3 6698.0 6718.7 6758.7 6762.5 6776.7 6576.9 6927.7 7404.9 7422.4 7432.4 5594.7 6761.1 6781.1 6783.7 6798.4 3825.4 3870.5 3922.5 3928.0 3995.4 6645.9 7819.0 7939.4 7945.2 7986.2 6387.3 7742.4 7748.1 7759.4 7773.4 7931.7 7946.4 7953.9 7977.2 7987.0 7265.3 7486.3 7519.4 7520.8 7533.6 6556.8 6953.3 7352.9 7384.8 7390.7 7899.2 7917.1 7924.3 7925.2 7934.1 4682.6 4747.1 4747.6 4749.3 4759.0 6153.0 7280.2 7284.2 7473.9 7479.6 5600.7 6200.9 6282.4 7258.7 7275.9 7228.0 7234.2 7240.5 7244.8 7252.8 7072.6 7078.4 7085.1 7090.9 7117.5 5623.9 6970.3 7181.7 7240.2 7259.5 6912.7 6945.0 7080.4 7126.5 7139.6 03:52:30 INFO - PROCESS | 1680 | 03:52:30 INFO - PROCESS | 1680 | [#5] yui.html Cycles:180 Average:18650.10 Median:51126.37 stddev:26294.86 (51.4%) stddev-sans-first:26334.25 03:52:30 INFO - PROCESS | 1680 | Values: 744.4 746.3 747.2 765.5 775.9 14990.0 15359.0 15371.9 15374.0 15374.2 13984.8 14148.0 14173.5 14185.6 14187.1 772.4 793.3 951.3 968.8 970.1 961.4 968.2 973.0 975.9 977.2 830.8 1031.1 1037.2 1038.8 1039.9 1023.5 1043.4 1045.9 1049.1 1049.8 775.7 778.5 780.7 781.1 781.2 14924.9 15011.8 15019.6 15019.8 15073.0 12731.0 12798.9 12993.4 13156.5 13276.9 10256.3 10262.4 10316.4 10584.7 11035.2 3558.5 3566.6 3611.0 3667.5 3836.4 82323.2 82777.0 82963.9 84559.6 85377.6 9635.8 9700.1 9758.4 9776.1 9983.5 21563.0 21884.6 21903.5 22486.7 22495.1 7021.7 7569.5 7657.4 8007.2 8125.0 4153.7 4199.6 4264.2 4265.6 4472.2 3722.2 3762.3 3763.3 3769.7 3779.2 674.5 703.8 736.3 739.3 739.9 529.1 554.5 587.0 594.6 617.6 8284.6 8416.2 8490.3 8569.0 8591.7 557.2 592.6 605.6 615.0 634.8 3984.0 3988.2 4033.1 4095.8 4182.6 94286.5 94432.6 98535.5 98542.0 99091.5 63547.0 80783.6 81397.0 81432.0 83173.0 67065.0 67159.0 70997.0 71125.6 71735.0 7786.7 7840.2 7950.6 8089.5 8289.5 48987.0 49060.8 50189.5 51046.0 51206.7 65660.0 65897.0 70302.0 70417.6 70923.2 10924.0 11231.8 11502.5 11611.7 11791.5 12048.4 12161.1 12220.1 12256.6 12780.0 7782.6 11215.9 11583.6 12394.1 12569.4 10310.4 11463.9 11843.6 11905.7 11906.3 9982.4 11451.8 11597.4 11645.7 11761.2 9574.0 9831.7 9857.1 9978.1 10002.6 9859.9 10029.3 10033.9 10042.8 10053.2 03:52:30 INFO - PROCESS | 1680 | -------- Summary: end -------- 03:52:30 INFO - PROCESS | 1680 | 03:52:30 INFO - TEST-INFO | 1680: exit 0 03:52:30 INFO - TEST-OK | dromaeo_css | took 1112730ms 03:52:30 INFO - TEST-START | kraken 03:52:30 INFO - operating with platform_type : mac_ 03:52:30 INFO - Initialising browser for kraken test... 03:52:30 INFO - TEST-INFO | started process 1712 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpzcrYVc/profile http://localhost:49275/getInfo.html) 03:52:33 INFO - PROCESS | 1712 | __metrics Screen width/height:1600/1200 03:52:33 INFO - PROCESS | 1712 | colorDepth:24 03:52:33 INFO - PROCESS | 1712 | Browser inner width/height: 1024/689 03:52:33 INFO - PROCESS | 1712 | __metrics 03:52:33 INFO - PROCESS | 1712 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:52:33 ERROR - PROCESS | 1712 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:52:33 INFO - TEST-INFO | 1712: exit 0 03:52:33 INFO - Browser initialized. 03:52:33 INFO - Running cycle 1/1 for kraken test... 03:52:33 INFO - TEST-INFO | started process 1715 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpzcrYVc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:52:45 INFO - PROCESS | 1715 | RSS: Main: 161865728 03:52:45 INFO - PROCESS | 1715 | 03:52:48 INFO - PROCESS | 1715 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 03:52:49 INFO - PROCESS | 1715 | RSS: Main: 223920128 03:52:49 INFO - PROCESS | 1715 | 03:52:51 INFO - PROCESS | 1715 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 03:52:52 INFO - PROCESS | 1715 | RSS: Main: 239370240 03:52:52 INFO - PROCESS | 1715 | 03:52:55 INFO - PROCESS | 1715 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 03:52:56 INFO - PROCESS | 1715 | RSS: Main: 324075520 03:52:56 INFO - PROCESS | 1715 | 03:52:58 INFO - PROCESS | 1715 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 03:52:58 INFO - PROCESS | 1715 | RSS: Main: 231718912 03:52:58 INFO - PROCESS | 1715 | 03:53:01 INFO - PROCESS | 1715 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:53:01 INFO - PROCESS | 1715 | RSS: Main: 232669184 03:53:01 INFO - PROCESS | 1715 | 03:53:04 INFO - PROCESS | 1715 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 03:53:05 INFO - PROCESS | 1715 | RSS: Main: 269357056 03:53:05 INFO - PROCESS | 1715 | 03:53:07 INFO - PROCESS | 1715 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 03:53:08 INFO - PROCESS | 1715 | RSS: Main: 271507456 03:53:08 INFO - PROCESS | 1715 | 03:53:11 INFO - PROCESS | 1715 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 03:53:11 INFO - PROCESS | 1715 | RSS: Main: 267563008 03:53:11 INFO - PROCESS | 1715 | 03:53:13 INFO - PROCESS | 1715 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:53:14 INFO - PROCESS | 1715 | RSS: Main: 260878336 03:53:14 INFO - PROCESS | 1715 | 03:53:16 INFO - PROCESS | 1715 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:53:16 INFO - PROCESS | 1715 | RSS: Main: 267669504 03:53:16 INFO - PROCESS | 1715 | 03:53:18 INFO - PROCESS | 1715 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:53:19 INFO - PROCESS | 1715 | RSS: Main: 271151104 03:53:19 INFO - PROCESS | 1715 | 03:53:22 INFO - PROCESS | 1715 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:53:22 INFO - PROCESS | 1715 | RSS: Main: 278544384 03:53:22 INFO - PROCESS | 1715 | 03:53:25 INFO - PROCESS | 1715 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:53:26 INFO - PROCESS | 1715 | RSS: Main: 275525632 03:53:26 INFO - PROCESS | 1715 | 03:53:27 INFO - PROCESS | 1715 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 03:53:28 INFO - PROCESS | 1715 | RSS: Main: 336588800 03:53:28 INFO - PROCESS | 1715 | 03:53:28 INFO - PROCESS | 1715 | __start_tp_report 03:53:28 INFO - PROCESS | 1715 | _x_x_mozilla_page_load 03:53:28 INFO - PROCESS | 1715 | _x_x_mozilla_page_load_details 03:53:28 INFO - PROCESS | 1715 | |i|pagename|runs| 03:53:28 INFO - PROCESS | 1715 | |0;ai-astar;113;112;120;110;111;110;113;113;113;132 03:53:28 INFO - PROCESS | 1715 | |1;audio-beat-detection;157;120;140;165;139;140;136;118;167;115 03:53:28 INFO - PROCESS | 1715 | |2;audio-dft;187;185;184;185;188;185;185;185;184;185 03:53:28 INFO - PROCESS | 1715 | |3;audio-fft;70;78;78;162;60;79;82;84;139;56 03:53:28 INFO - PROCESS | 1715 | |4;audio-oscillator;92;91;141;79;78;80;128;79;76;128 03:53:28 INFO - PROCESS | 1715 | |5;imaging-gaussian-blur;88;86;88;85;87;83;91;123;118;84 03:53:28 INFO - PROCESS | 1715 | |6;imaging-darkroom;92;92;99;133;98;134;146;93;92;92 03:53:28 INFO - PROCESS | 1715 | |7;imaging-desaturate;85;84;85;84;91;123;83;82;83;86 03:53:28 INFO - PROCESS | 1715 | |8;json-parse-financial;60;63;66;63;64;66;63;82;59;57 03:53:28 INFO - PROCESS | 1715 | |9;json-stringify-tinderbox;62;65;69;69;74;68;66;66;67;68 03:53:28 INFO - PROCESS | 1715 | |10;stanford-crypto-aes;81;79;78;79;80;78;79;79;78;83 03:53:28 INFO - PROCESS | 1715 | |11;stanford-crypto-ccm;146;138;137;127;126;125;126;137;167;163 03:53:28 INFO - PROCESS | 1715 | |12;stanford-crypto-pbkdf2;158;163;161;164;164;162;164;156;163;160 03:53:28 INFO - PROCESS | 1715 | |13;stanford-crypto-sha256-iterative;66;64;68;69;66;66;65;67;68;67 03:53:28 INFO - PROCESS | 1715 | __end_tp_report 03:53:28 INFO - PROCESS | 1715 | __start_cc_report 03:53:28 INFO - PROCESS | 1715 | _x_x_mozilla_cycle_collect,1874 03:53:28 INFO - PROCESS | 1715 | __end_cc_report 03:53:28 INFO - PROCESS | 1715 | __startTimestamp1467543208110__endTimestamp 03:53:28 INFO - PROCESS | 1715 | 03:53:28 INFO - PROCESS | 1715 | ------- Summary: start ------- 03:53:28 INFO - PROCESS | 1715 | Number of tests: 14 03:53:28 INFO - PROCESS | 1715 | 03:53:28 INFO - PROCESS | 1715 | [#0] ai-astar Cycles:10 Average:114.70 Median:113.00 stddev:6.70 (5.9%) stddev-sans-first:7.08 03:53:28 INFO - PROCESS | 1715 | Values: 113.0 112.0 120.0 110.0 111.0 110.0 113.0 113.0 113.0 132.0 03:53:28 INFO - PROCESS | 1715 | 03:53:28 INFO - PROCESS | 1715 | [#1] audio-beat-detection Cycles:10 Average:139.70 Median:140.00 stddev:18.76 (13.4%) stddev-sans-first:18.83 03:53:28 INFO - PROCESS | 1715 | Values: 157.0 120.0 140.0 165.0 139.0 140.0 136.0 118.0 167.0 115.0 03:53:28 INFO - PROCESS | 1715 | 03:53:28 INFO - PROCESS | 1715 | [#2] audio-dft Cycles:10 Average:185.30 Median:185.00 stddev:1.25 (0.7%) stddev-sans-first:1.17 03:53:28 INFO - PROCESS | 1715 | Values: 187.0 185.0 184.0 185.0 188.0 185.0 185.0 185.0 184.0 185.0 03:53:28 INFO - PROCESS | 1715 | 03:53:28 INFO - PROCESS | 1715 | [#3] audio-fft Cycles:10 Average:88.80 Median:78.00 stddev:34.21 (43.9%) stddev-sans-first:35.61 03:53:28 INFO - PROCESS | 1715 | Values: 70.0 78.0 78.0 162.0 60.0 79.0 82.0 84.0 139.0 56.0 03:53:28 INFO - PROCESS | 1715 | 03:53:28 INFO - PROCESS | 1715 | [#4] audio-oscillator Cycles:10 Average:97.20 Median:79.00 stddev:25.07 (31.7%) stddev-sans-first:26.52 03:53:28 INFO - PROCESS | 1715 | Values: 92.0 91.0 141.0 79.0 78.0 80.0 128.0 79.0 76.0 128.0 03:53:28 INFO - PROCESS | 1715 | 03:53:28 INFO - PROCESS | 1715 | [#5] imaging-gaussian-blur Cycles:10 Average:93.30 Median:86.50 stddev:14.56 (16.8%) stddev-sans-first:15.32 03:53:28 INFO - PROCESS | 1715 | Values: 88.0 86.0 88.0 85.0 87.0 83.0 91.0 123.0 118.0 84.0 03:53:28 INFO - PROCESS | 1715 | 03:53:28 INFO - PROCESS | 1715 | [#6] imaging-darkroom Cycles:10 Average:107.10 Median:92.00 stddev:21.52 (23.4%) stddev-sans-first:22.12 03:53:28 INFO - PROCESS | 1715 | Values: 92.0 92.0 99.0 133.0 98.0 134.0 146.0 93.0 92.0 92.0 03:53:28 INFO - PROCESS | 1715 | 03:53:28 INFO - PROCESS | 1715 | [#7] imaging-desaturate Cycles:10 Average:88.60 Median:84.50 stddev:12.34 (14.6%) stddev-sans-first:13.02 03:53:28 INFO - PROCESS | 1715 | Values: 85.0 84.0 85.0 84.0 91.0 123.0 83.0 82.0 83.0 86.0 03:53:28 INFO - PROCESS | 1715 | 03:53:28 INFO - PROCESS | 1715 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:63.50 stddev:6.86 (10.8%) stddev-sans-first:7.10 03:53:28 INFO - PROCESS | 1715 | Values: 60.0 63.0 66.0 63.0 64.0 66.0 63.0 82.0 59.0 57.0 03:53:28 INFO - PROCESS | 1715 | 03:53:28 INFO - PROCESS | 1715 | [#9] json-stringify-tinderbox Cycles:10 Average:67.40 Median:68.00 stddev:3.13 (4.6%) stddev-sans-first:2.65 03:53:28 INFO - PROCESS | 1715 | Values: 62.0 65.0 69.0 69.0 74.0 68.0 66.0 66.0 67.0 68.0 03:53:28 INFO - PROCESS | 1715 | 03:53:28 INFO - PROCESS | 1715 | [#10] stanford-crypto-aes Cycles:10 Average:79.40 Median:79.00 stddev:1.58 (2.0%) stddev-sans-first:1.56 03:53:28 INFO - PROCESS | 1715 | Values: 81.0 79.0 78.0 79.0 80.0 78.0 79.0 79.0 78.0 83.0 03:53:28 INFO - PROCESS | 1715 | 03:53:28 INFO - PROCESS | 1715 | [#11] stanford-crypto-ccm Cycles:10 Average:139.20 Median:137.50 stddev:15.26 (11.1%) stddev-sans-first:15.99 03:53:28 INFO - PROCESS | 1715 | Values: 146.0 138.0 137.0 127.0 126.0 125.0 126.0 137.0 167.0 163.0 03:53:28 INFO - PROCESS | 1715 | 03:53:28 INFO - PROCESS | 1715 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.50 Median:163.00 stddev:2.76 (1.7%) stddev-sans-first:2.62 03:53:28 INFO - PROCESS | 1715 | Values: 158.0 163.0 161.0 164.0 164.0 162.0 164.0 156.0 163.0 160.0 03:53:28 INFO - PROCESS | 1715 | 03:53:28 INFO - PROCESS | 1715 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:1.51 (2.2%) stddev-sans-first:1.58 03:53:28 INFO - PROCESS | 1715 | Values: 66.0 64.0 68.0 69.0 66.0 66.0 65.0 67.0 68.0 67.0 03:53:28 INFO - PROCESS | 1715 | -------- Summary: end -------- 03:53:28 INFO - PROCESS | 1715 | 03:53:28 INFO - TEST-INFO | 1715: exit 0 03:53:28 INFO - TEST-OK | kraken | took 58107ms 03:53:28 INFO - SUITE-END | took 1170s 03:53:29 INFO - Completed test suite (00:19:31) 03:53:29 INFO - javascript benchmark 03:53:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3540.610532841388, 3581.194231297957, 3590.8384122714474, 3617.113807117257, 3679.5768486624047, 5790.04464263396, 5808.970955145219, 5826.388229235928, 5860.179574859518, 5889.057750759879, 5776.566757493184, 5818.650880947146, 5821.912671309934, 5825.0, 5847.152162936382, 5206.375234971809, 5214.582833373321, 5237.528622423981, 5257.474252574751, 5309.362876454831, 5285.127953887604, 5316.096263635172, 5320.6275560710765, 5324.015057214408, 5326.973365133169, 5231.529162375386, 5281.867953301179, 5282.603804714642, 5306.522412982831, 5338.199270109495, 5321.201819727053, 5349.43830897754, 5353.277307563468, 5355.116405793078, 5396.622236443451, 5118.539331460186, 5284.260203571503, 5294.100002999499, 5299.0, 5301.840944771625, 5035.899282014339, 5595.580331475115, 5687.289088863892, 5700.087985922232, 5774.4802967732685, 6236.251649802053, 6272.686365681698, 6276.215473065867, 6295.968520157416, 6306.0, 6189.566730328879, 6253.562250642457, 6254.0, 6275.560710750249, 6308.684565771693, 5876.7649294028615, 5925.140854576062, 5968.820935371946, 5981.820545383602, 5991.400859913973, 6312.43188113072, 6316.242050953915, 6317.936820631735, 6346.492280617632, 6352.809415717536, 6131.468419844996, 6142.877142457126, 6275.5920865144535, 6289.40250676185, 6292.119103325538, 6277.372262773777, 6283.59156654816, 6285.308616052231, 6286.0, 6311.463525600376, 6283.120363149163, 6283.59156654816, 6284.528660350492, 6288.968555157195, 6297.055441683692, 6172.629642221481, 6241.0, 6273.247210334789, 6285.120083188357, 6285.277193122849, 4963.801447942042, 4967.776450059756, 4968.577670897942, 5009.248612708123, 5030.220315851049, 4508.211063063951, 4514.841980530666, 4519.0, 4528.230200865861, 4552.840650577214, 5842.941570584239, 5968.820935371946, 5977.043673012297, 5978.073398623221, 5994.7602095916545, 6148.631082135086, 6187.721552530147, 6233.688315584202, 6245.6252624842655, 6255.155554000243, 5994.250718660168, 5994.820155395258, 5995.640261584319, 5996.2504686914135, 6005.099235114768, 5441.374241962146, 5519.530839878575, 5541.085720856014, 5546.417626149197, 5557.944420555824, 6328.0, 6367.267764207176, 6496.187976502937, 6502.544821862519, 6502.6748662567625, 6230.968845155745, 6234.750609975732, 6235.220597425322, 6250.062490626442, 6250.531210159347, 6456.903146452713, 6463.903041454476, 6466.612003279818, 6467.708953097216, 6491.902621460777, 5780.335261444988, 6189.81430557084, 6214.627122372672, 6229.159063526366, 6234.096056071753, 6120.90429240112, 6244.219472565929, 6248.4688801452385, 6252.499800015897, 6258.843528911813, 5875.529957603296, 5961.254843144607, 6194.5044396449075, 6200.0, 6204.5967012144065, 6073.301570319293, 6128.172696686069, 6196.907046394217, 6203.844903877259, 6232.034034724625, 6042.607230530001, 6078.574499784972, 6103.481204097701, 6114.0828875669, 6124.724387402489, 6218.968905155626, 6230.376962303733, 6234.563580549318, 6243.37566243372, 6244.718987645658, 5977.9402205977385, 5987.820365389045, 6054.969725151346, 6067.908981365372, 6141.6007959482495], "value": 5795.207853217215, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [547.6686604604214, 549.6207616744446, 569.4533248081841, 576.7714767545134, 629.6725702634631, 32200.838995805017, 32812.0, 32828.83585582075, 38227.80886095572, 39444.0, 44607.0, 45460.0, 45540.08919821602, 45579.31631025537, 45622.17511299544, 9812.70561883143, 10059.597616095347, 10084.949575252114, 10095.29332946694, 10099.848502272473, 9240.5841737122, 9815.362001469917, 11807.881921180764, 12189.573364932228, 12221.63335099944, 11838.585649502269, 11932.821007684892, 11936.10479214058, 11936.283822970607, 11955.641330760047, 11889.524419023186, 11890.346030968294, 11994.580189693406, 12004.819927701092, 12035.157538972275, 11728.0, 11837.171398002143, 11837.526498940031, 11851.407429628483, 11868.347240901747, 41569.79215103936, 41818.0, 41953.95115122116, 41955.79022104901, 42037.579624203674, 3367.747418943589, 3460.325236578868, 3540.946885796715, 3557.0654210276366, 3644.4533320001947, 28564.71435285662, 29712.257193570116, 29961.70038299633, 30016.69983300161, 30062.849685751433, 2762.2818067302446, 2816.9718302816914, 2843.3460304130167, 2902.782291328159, 2934.8826046958097, 419992.0, 420724.0, 421395.0, 421454.0, 422230.0, 36604.450933236025, 37437.43843842344, 37450.812745936644, 63408.0, 64900.0, 36079.81960090235, 36264.0, 36314.0, 36314.8184259077, 36360.0, 16091.114988675736, 16275.674486510203, 16290.755638665429, 16295.0, 16321.83678163227, 12805.0, 13004.024698147488, 13715.451381944611, 13759.036867419385, 13781.0, 8361.247487726134, 8535.615897284637, 8575.914240857512, 8612.526311052952, 8640.444719950468, 3038.9696103039128, 3540.3804334241404, 3542.6457354264885, 3544.787312761191, 3580.820958952042, 1021.1524434719314, 1043.6347278452633, 1079.093561408405, 1088.4775307852276, 1118.3904771899333, 29914.102576922727, 30245.848770756005, 30527.69472305293, 30917.845410772803, 31538.526922096185, 1109.0351394287022, 1210.6973256685828, 1245.1346314311647, 1270.5616562285998, 1286.7812471879804, 15738.685226295638, 16047.518574442553, 16314.347426102822, 16317.918410407872, 16478.42325518601, 637151.0, 644338.0, 645838.0, 646079.0, 648542.0, 61988.69005654943, 63387.04919426212, 63472.36527634757, 63567.36432635707, 63649.68175159094, 1433.5412667946366, 1464.5825939607162, 1571.591386239586, 1574.1027614259763, 1591.3475475055172, 25093.372665683502, 29892.55161172541, 30750.692493074785, 31153.221169470944, 31431.842840785648, 51259.487405125474, 51944.22083668673, 52180.73909630428, 52180.73909630428, 52374.0, 547511.0, 564663.0, 564856.0, 565932.0, 567151.0, 37630.0, 47476.287855681505, 47981.52018479771, 48249.552513424656, 48302.55092347235, 48139.75930120327, 48525.0, 49091.75454122706, 49809.75095124501, 50316.0, 38170.427443588924, 38992.80503597464, 40075.79962100171, 42599.0, 43338.349924750524, 38368.80815595904, 39072.80463597664, 39257.607423926536, 39868.0, 39973.60026399815, 38315.808420957714, 38908.0, 39306.0, 39935.60064399319, 39960.80019599883, 32836.67163328336, 34837.12907177341, 34953.98437655315, 35115.64884351226, 35130.82434587811, 22661.320160395215, 25558.616620751076, 25799.742002580486, 25992.22023339303, 26128.216153515426], "value": 19748.562221841785, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.0411456332957, 406.7579790024936, 413.93583994480855, 415.32095024634714, 415.8523724077952, 14644.560663180095, 20289.391318260437, 21039.2636257731, 21080.472988175323, 21281.57436851264, 20399.592008159827, 20555.0, 21389.465263368424, 21436.8928155359, 21476.89261553694, 5878.441548052928, 5907.261592300963, 6197.16338294329, 6204.6587437690905, 6205.9379406206035, 6060.2424696912885, 6143.723532441051, 6173.969130154365, 6175.814725558241, 6176.0, 4468.731876087429, 5408.756605952722, 5548.6948217848, 5553.305836770403, 5556.249906262663, 5527.143292789624, 5528.612997090206, 5528.695921724304, 5532.087205611069, 5537.889242215153, 3849.5188101487315, 5819.505342045931, 5999.370066143036, 6003.489703375219, 6005.879882402328, 20702.378928632166, 26500.46999060008, 26614.33464163392, 27095.45809083827, 27108.1867543974, 2040.9387718368473, 2316.3861576682193, 2511.698596168453, 2835.758960488361, 2871.4400691865094, 14820.258987050713, 15354.769678454832, 15393.846061539465, 15716.057036577728, 15750.369985200578, 1474.7714104313848, 1595.664910368824, 1756.525738050732, 1779.5373202967191, 1798.4604618614364, 106832.0, 115001.2402639275, 116640.0, 118997.40501297438, 120132.19801702982, 11066.833997490045, 11260.662180134608, 11404.087672986307, 11485.253458525169, 11563.768724625628, 12862.935685321514, 13382.263975481248, 13422.865771342162, 14457.566273011826, 14469.565913022627, 8589.699360522352, 8948.239399650973, 9042.553191489462, 9146.54267286633, 9154.725358239264, 9430.62277508892, 9450.716478505656, 9457.527123643791, 9511.90488095124, 9529.428234305964, 6628.839953008204, 6673.833154171088, 6789.287124851919, 6809.863802723918, 6849.2465828758795, 1667.6914770767446, 1707.7865266841645, 1939.360011196302, 1943.1741509858255, 1953.6483432981915, 891.9152680495342, 936.3819878879907, 1003.1723827841913, 1095.1676725688535, 1124.707576030238, 20211.8989405052, 20555.327807384943, 21862.672059919114, 22018.339449816533, 22166.224182153546, 961.9663311784194, 994.2756846241247, 1013.4020927652573, 1031.6904928521415, 1112.3326004397459, 11434.371109589052, 11740.471678774476, 11786.823197652042, 11794.88205117955, 11835.467403966843, 679230.0, 688807.0589175785, 690162.0, 690814.0, 695814.0, 117942.82057179319, 122373.38813305876, 122492.55014899652, 123531.38234308771, 126958.09562856564, 1088.5101704233127, 1097.1661537231762, 1099.9725006874892, 1100.3672888089382, 1103.431732657682, 15634.374625014872, 18729.531761706065, 19187.328443504968, 19206.327778527684, 19574.021298934713, 51390.201342952816, 51839.74080129575, 52714.20928686149, 53328.46671533235, 54161.72919135379, 72377.91433128613, 73395.63302183455, 73442.63278683786, 73618.0, 74078.25921740713, 8080.676772929017, 8187.918120818716, 8215.424920255764, 8354.707585234732, 8354.916450835413, 11372.20394572372, 12290.385480726107, 13228.669283267995, 13460.855827254793, 13552.79670804919, 11439.42802859837, 11444.256123351955, 11703.531858725555, 11862.644120676394, 11913.046956243306, 8927.821443570961, 9851.507424628597, 11228.66314010581, 11864.525418983489, 12049.036077113986, 11802.58690945847, 11816.350100744541, 11829.585964491202, 12015.75968480608, 12102.0, 8309.127541608044, 8377.70678026266, 8423.0, 8453.408261421642, 8615.095414981588, 11008.834867477155, 11606.24559403636, 11799.882001180222, 11950.103742219195, 12017.819732703842], "value": 9963.480631771708, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1394.26800929512, 1470.0738534723137, 1630.5352974402297, 1834.9633007339853, 2004.1682701678803, 8147.388945829057, 8844.38089333746, 9008.459492430458, 9013.684521041765, 9019.774505637362, 8669.566521673923, 8930.017698053225, 8941.776455588613, 8945.239654629364, 8959.820803583925, 2146.420466474051, 2507.323022783847, 2532.0884481586613, 2534.6324782906468, 2536.5180615683053, 2467.617519284505, 2540.8856601452885, 2542.8092893033004, 2544.5038217547585, 2564.4230048239183, 2514.88683009265, 2541.4154744408797, 2546.567083595793, 2550.7959363250893, 2554.067765265683, 2139.3902737719754, 2533.1893793985932, 2546.5288921549572, 2546.987265063663, 2548.7960963122905, 2489.55188066148, 2529.2538701083226, 2536.239128261514, 2536.5053814506177, 2543.135333986435, 8230.711925082653, 8420.957895210546, 9027.7291681249, 9051.27589792815, 9090.50002249876, 5476.288082549283, 5586.776528938837, 5628.521575666074, 5640.266765320482, 5640.520555752766, 3581.0510214793103, 3668.944965825515, 3732.42147467143, 3735.1409175889426, 3743.8689645862264, 951.7240000399876, 1064.4943651765427, 1105.6627728542792, 1106.9335839849634, 1117.1788735279563, 11763.588274410355, 12124.939375303067, 12155.331456769874, 12161.513539458323, 12188.024958003338, 4028.234635419261, 4639.280911458662, 4657.022025374641, 4773.307870358779, 4787.42550893888, 9384.061593840697, 9391.29565282593, 9515.191208747334, 9533.570989305359, 9533.856992144985, 5444.754986025639, 5445.455454454523, 5462.9726851365485, 5476.808311709151, 5490.972545137249, 4545.204589196877, 4726.763661816895, 4902.90194196114, 5088.0, 5123.513266239701, 3875.8643447479767, 3962.7622342659533, 4004.739691920016, 4011.3782363733667, 4014.2573623879675, 1060.9681709548727, 1208.5045131496045, 1235.1354052163522, 1240.2186622427812, 1254.3978890132787, 956.933014688979, 992.2855544008303, 992.7617371830709, 995.2038369304528, 996.0388225362415, 5870.82583483305, 6011.188489553854, 6080.300765411946, 6093.0, 6274.152989346471, 441.5231549926072, 467.55644676954665, 487.2934245344265, 488.90466359059644, 489.24656029713844, 3937.901552461154, 4028.436018957348, 4069.7965101745385, 4148.56440073794, 4158.2307273154565, 9826.557804898797, 11110.222284440011, 11190.272632278877, 11248.718782030515, 11266.774664506664, 10858.131349492021, 10888.67333979982, 10963.83554246687, 10994.560217591206, 11025.889741102646, 6696.0, 8446.028706698686, 8452.915470845335, 8467.280281176169, 8518.190771876662, 5968.46283834457, 5977.790777322773, 6081.563203249278, 6116.724747386289, 6119.755209791559, 9742.0, 9806.901930980599, 9941.701748947255, 9947.154491868272, 9953.104220620178, 9712.660056897974, 10146.594136234467, 10193.337433066827, 10241.9487902563, 10290.536925838205, 6982.68577913985, 7171.4979951404985, 7176.533525320838, 7717.961410192913, 7853.842923141391, 7931.920680793349, 8541.359398045295, 8571.571421428778, 8571.95714021426, 8573.614187361709, 7452.105747310357, 7569.924300756922, 7643.121041080347, 7659.578723170279, 7667.233276672288, 7208.639568021473, 7208.639568021683, 7208.819779505554, 7316.707331706672, 7317.67070481819, 7052.483716001085, 7110.25342339047, 7113.146422429341, 7149.0706208192605, 7210.062691850026, 4959.752012399294, 5029.497050294941, 5040.8991820162655, 5053.74731263428, 5094.363204599425, 4996.300517927566, 5070.8225212117395, 5091.236314552847, 5150.433452320317, 5332.840014799563], "value": 4597.708173382228, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.4871833159126, 337.1082948183034, 345.65779877920863, 346.1553808706755, 346.5512161750533, 5589.692566908823, 6474.0612611171355, 6492.902606460907, 6696.296888826676, 6749.392554670078, 6433.806985790434, 6669.599824010563, 6670.966000269966, 6678.098456708343, 6706.061151438803, 4941.802327906879, 4998.400191976968, 5011.223260394645, 5016.824411145611, 5028.622853286, 3802.9809850950705, 3904.297226499229, 4231.301835197189, 4943.184374578191, 4992.950070499285, 4669.603083737887, 4751.978324660197, 4875.41495020598, 4941.925871111918, 4958.082754690376, 4865.41615006198, 4910.0, 4911.189653707134, 4914.5085491450745, 4916.975415122919, 3591.4612808078737, 4240.215560121356, 4244.938765308673, 4244.978775106136, 4289.785510724451, 6407.263164736069, 6410.1987251593555, 6412.262589802139, 6417.294097649255, 6437.259715132773, 5484.122540393558, 5867.647941123546, 5871.178035075093, 5883.264591926009, 5894.8821023579285, 6973.093497845248, 6994.33553812387, 7025.086738723984, 7036.190838053587, 7042.4366050716335, 4580.427446569179, 4591.265397536378, 4595.172868883599, 4598.425196850394, 4618.5843274105155, 7321.048263725735, 7512.849743005109, 7524.849503009964, 7528.661210245552, 7535.095788505414, 4664.253719404879, 4668.95331046692, 4675.3220782986955, 4680.274557443602, 4687.10944920464, 4046.615571520701, 4576.565226303496, 4706.294055891643, 4714.622830173578, 4737.620990320766, 5612.466815652507, 5667.121596152603, 5672.234248376499, 5690.857728556819, 5709.543236541067, 5954.970225148846, 7054.153501579741, 7094.077769889881, 7098.6450677466955, 7112.679929403189, 1573.3627880708332, 1629.1528405229215, 1660.0, 1662.542800729809, 1663.8502534771928, 1003.8042581696645, 1041.9892704077033, 1056.1234175634206, 1057.1067448006422, 1065.3448129400363, 6697.9665101675155, 6718.697658605374, 6758.729650814011, 6762.526623136431, 6776.69504872282, 6576.934230657728, 6927.7228910844005, 7404.888926666104, 7422.369098626677, 7432.405407567381, 5594.720263986785, 6761.053452516651, 6781.050652908597, 6783.660816959133, 6798.422134118557, 3825.407061905409, 3870.4581358609817, 3922.529051605648, 3927.9607203927594, 3995.380715989026, 6645.933540664628, 7819.0, 7939.404544659174, 7945.245201705829, 7986.241307075818, 6387.329330420332, 7742.380609551223, 7748.070231572247, 7759.379249660014, 7773.416993725493, 7931.682732690743, 7946.40401969843, 7953.920460795318, 7977.242161994485, 7986.960065199637, 7265.309795569412, 7486.251374862688, 7519.43604229674, 7520.811979700551, 7533.623318834146, 6556.8197724410065, 6953.33943275388, 7352.889706654511, 7384.778456646309, 7390.667419966222, 7899.210078992055, 7917.129115797143, 7924.326432253323, 7925.167857374892, 7934.127246002819, 4682.601978831837, 4747.121782470254, 4747.64392670558, 4749.26386410107, 4758.976205118952, 6152.969235153795, 7280.162781280221, 7284.235155308616, 7473.850522989618, 7479.551226926402, 5600.663960162403, 6200.906986395298, 6282.371762823681, 7258.709651613876, 7275.8908616371855, 7228.024216730674, 7234.240404757636, 7240.456965727486, 7244.782656520313, 7252.818679533054, 7072.611006394698, 7078.398336141486, 7085.078939737801, 7090.929090709234, 7117.537360071578, 5623.8875222496135, 6970.337817907493, 7181.712731490891, 7240.239774823568, 7259.52813067149, 6912.723491060301, 6945.01364413287, 7080.362767350963, 7126.536775109601, 7139.643017849191], "value": 5178.3652565401435, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.3821628048719, 746.3469464218808, 747.249014740186, 765.5138986743418, 775.8797386405106, 14990.0, 15359.0, 15371.923965322432, 15374.0, 15374.154421506813, 13984.790228146561, 14148.0, 14173.503927362519, 14185.574432767007, 14187.077839940397, 772.3666593393419, 793.2583034862422, 951.3150531617224, 968.7578105473632, 970.1268858027761, 961.3943215774052, 968.1818863060738, 972.9756756081084, 975.902409759022, 977.2231076294363, 830.7756905635475, 1031.1183937733242, 1037.1650821089058, 1038.8389799581078, 1039.8856125826153, 1023.5445226874044, 1043.4156872151582, 1045.8849526552074, 1049.108257980718, 1049.774298525813, 775.6548335990486, 778.4667502760627, 780.6682160081999, 781.1134362498548, 781.1602527283137, 14924.850751492455, 15011.849881501264, 15019.624509387244, 15019.849801502063, 15073.0, 12731.045171612166, 12798.936005320007, 12993.350332483433, 13156.539521116714, 13276.86723132766, 10256.333338332985, 10262.43556604394, 10316.432596207205, 10584.735381615445, 11035.172362072877, 3558.5018097466377, 3566.5720113586535, 3610.963890361089, 3667.541557305337, 3836.3861782114727, 82323.17676823215, 82777.0, 82963.92590185233, 84559.57720211359, 85377.57311213404, 9635.807283854283, 9700.126988571063, 9758.365706229073, 9776.071273229032, 9983.500824958723, 21563.029663665173, 21884.562308753735, 21903.452413689785, 22486.66270005951, 22495.100195992305, 7021.684024218923, 7569.470137090461, 7657.387409007266, 8007.199280072063, 8125.0, 4153.7300075494995, 4199.601037901456, 4264.168487144977, 4265.616094551505, 4472.195004899084, 3722.236941427001, 3762.3227818992846, 3763.34141525232, 3769.717271204616, 3779.225258821935, 674.5008693566725, 703.7923812475332, 736.2784471218237, 739.2865884421559, 739.9186089530148, 529.0926061803948, 554.4677109974389, 586.961847479921, 594.6461855196152, 617.586217234454, 8284.585770711441, 8416.158384161536, 8490.320774338039, 8569.0, 8591.742247732578, 557.2226743692607, 592.6473748119862, 605.5579427018301, 614.9877002459925, 634.7714822663837, 3984.0438294809037, 3988.1624858779983, 4033.072393349546, 4095.815688294034, 4182.581741825793, 94286.5285673581, 94432.58351124739, 98535.50732246293, 98542.0, 99091.50454247683, 63547.0, 80783.59608201921, 81397.0, 81432.0, 83173.0, 67065.0, 67158.99999999903, 70997.0, 71125.64437177782, 71735.0, 7786.727464538714, 7840.215978402114, 7950.642221099949, 8089.47418417801, 8289.461185022954, 48987.0, 49060.75469622629, 50189.49810501848, 51046.0, 51206.74396627993, 65660.0, 65897.0, 70302.0, 70417.64791176011, 70923.22691932743, 10924.0, 11231.831522527333, 11502.482388292714, 11611.6516504505, 11791.528338866348, 12048.397580120783, 12161.148719589897, 12220.144589878622, 12256.571020014257, 12780.0, 7782.571958542335, 11215.887841121485, 11583.594574189863, 12394.132410731163, 12569.371531423576, 10310.381377117397, 11463.942680286878, 11843.58547450835, 11905.702357441134, 11906.34515101643, 9982.35114717541, 11451.828222576834, 11597.420128993685, 11645.6506304811, 11761.176717630025, 9574.04259574065, 9831.705048848546, 9857.063578959987, 9978.101970822661, 10002.649907253211, 9859.852102218616, 10029.29794914349, 10033.949830251095, 10042.845072816719, 10053.246006549392], "value": 7113.126667596405, "unit": "score"}], "name": "dromaeo_css", "value": 7602.81740239621, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 120.0, 110.0, 111.0, 110.0, 113.0, 113.0, 113.0, 132.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 120.0, 140.0, 165.0, 139.0, 140.0, 136.0, 118.0, 167.0, 115.0], "value": 139.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 184.0, 185.0, 188.0, 185.0, 185.0, 185.0, 184.0, 185.0], "value": 185.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 78.0, 78.0, 162.0, 60.0, 79.0, 82.0, 84.0, 139.0, 56.0], "value": 88.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 141.0, 79.0, 78.0, 80.0, 128.0, 79.0, 76.0, 128.0], "value": 97.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 85.0, 87.0, 83.0, 91.0, 123.0, 118.0, 84.0], "value": 93.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 99.0, 133.0, 98.0, 134.0, 146.0, 93.0, 92.0, 92.0], "value": 107.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 84.0, 91.0, 123.0, 83.0, 82.0, 83.0, 86.0], "value": 88.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 66.0, 63.0, 64.0, 66.0, 63.0, 82.0, 59.0, 57.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 69.0, 69.0, 74.0, 68.0, 66.0, 66.0, 67.0, 68.0], "value": 67.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 78.0, 79.0, 80.0, 78.0, 79.0, 79.0, 78.0, 83.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 138.0, 137.0, 127.0, 126.0, 125.0, 126.0, 137.0, 167.0, 163.0], "value": 139.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 163.0, 161.0, 164.0, 164.0, 162.0, 164.0, 156.0, 163.0, 160.0], "value": 161.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 68.0, 69.0, 66.0, 66.0, 65.0, 67.0, 68.0, 67.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1493.1000000000001, "alertThreshold": 2.0}]} 03:53:29 INFO - Return code: 0 03:53:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:53:29 ERROR - # TBPL SUCCESS # 03:53:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:53:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:53:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:53:29 INFO - 2016-07-03 03:53:29.426 screenresolution[1719:10696] starting screenresolution argv=screenresolution get 03:53:29 INFO - 2016-07-03 03:53:29.429 screenresolution[1719:10696] Display 0: 1600x1200x32@60 03:53:29 INFO - 2016-07-03 03:53:29.435 screenresolution[1720:10699] starting screenresolution argv=screenresolution list 03:53:29 INFO - Available Modes on Display 0 03:53:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:53:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:53:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:53:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:53:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:53:29 INFO - Intel Iris: 03:53:29 INFO - Chipset Model: Intel Iris 03:53:29 INFO - Type: GPU 03:53:29 INFO - Bus: Built-In 03:53:29 INFO - VRAM (Dynamic, Max): 1536 MB 03:53:29 INFO - Vendor: Intel (0x8086) 03:53:29 INFO - Device ID: 0x0a2e 03:53:29 INFO - Revision ID: 0x0009 03:53:29 INFO - Displays: 03:53:29 INFO - Display: 03:53:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:53:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:53:29 INFO - Display Serial Number: 1600x1200 60 03:53:29 INFO - Main Display: Yes 03:53:29 INFO - Mirror: Off 03:53:29 INFO - Online: Yes 03:53:29 INFO - Rotation: Supported 03:53:29 INFO - Return code: 0 03:53:29 INFO - Running post-action listener: _resource_record_post_action 03:53:29 INFO - Running post-run listener: _resource_record_post_run 03:53:30 INFO - Total resource usage - Wall time: 1194s; CPU: 53.0%; Read bytes: 33148416; Write bytes: 494249984; Read time: 329; Write time: 1585 03:53:30 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 185037312; Write bytes: 178759680; Read time: 16564; Write time: 391 03:53:30 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 32210432; Write bytes: 297205760; Read time: 308; Write time: 1145 03:53:30 INFO - Running post-run listener: _upload_blobber_files 03:53:30 INFO - Blob upload gear active. 03:53:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:53:30 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:53:30 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'] 03:53:30 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 03:53:31 INFO - (blobuploader) - INFO - Open directory for files ... 03:53:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:53:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:53:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:53:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:53:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:53:31 INFO - (blobuploader) - INFO - Done attempting. 03:53:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:53:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:53:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:53:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:53:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:53:32 INFO - (blobuploader) - INFO - Done attempting. 03:53:32 INFO - (blobuploader) - INFO - Iteration through files over. 03:53:32 INFO - Return code: 0 03:53:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:53:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:53:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5fddf596422e3c86160939f9d9e88d164cbc05a9e097cb41a44a96cd7c461b7554ff4f4d449abb2544c7a20d6bce4468a65c463f1595cb6edcec4b92b41ed1d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c8b8d95b144ff811f21f2d543c1455fe0b3a29fecb9858ddf8102128e25bb57334dbda16151f62325623760f537b899254bb1659a44c3359ec53da8f45be0c64"} 03:53:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:53:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:53:32 INFO - Contents: 03:53:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5fddf596422e3c86160939f9d9e88d164cbc05a9e097cb41a44a96cd7c461b7554ff4f4d449abb2544c7a20d6bce4468a65c463f1595cb6edcec4b92b41ed1d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c8b8d95b144ff811f21f2d543c1455fe0b3a29fecb9858ddf8102128e25bb57334dbda16151f62325623760f537b899254bb1659a44c3359ec53da8f45be0c64"} 03:53:32 INFO - Running post-run listener: copy_logs_to_upload_dir 03:53:32 INFO - Copying logs to upload dir... 03:53:32 INFO - mkdir: /builds/slave/test/build/upload/logs 03:53:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.056164 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-07-03 03:53:32.787667) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-03 03:53:32.791375) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5fddf596422e3c86160939f9d9e88d164cbc05a9e097cb41a44a96cd7c461b7554ff4f4d449abb2544c7a20d6bce4468a65c463f1595cb6edcec4b92b41ed1d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c8b8d95b144ff811f21f2d543c1455fe0b3a29fecb9858ddf8102128e25bb57334dbda16151f62325623760f537b899254bb1659a44c3359ec53da8f45be0c64"} build_url:https://queue.taskcluster.net/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011184 build_url: 'https://queue.taskcluster.net/v1/task/N4C2xOyaQ-eZ_yvOTsKpjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5fddf596422e3c86160939f9d9e88d164cbc05a9e097cb41a44a96cd7c461b7554ff4f4d449abb2544c7a20d6bce4468a65c463f1595cb6edcec4b92b41ed1d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c8b8d95b144ff811f21f2d543c1455fe0b3a29fecb9858ddf8102128e25bb57334dbda16151f62325623760f537b899254bb1659a44c3359ec53da8f45be0c64"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-03 03:53:32.824204) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 03:53:32.824585) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YBgjFF4Qmw/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.vf6U5lR4nL/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.004533 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 03:53:32.872831) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-03 03:53:32.873126) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-03 03:53:32.873428) ========= ========= Total master_lag: 0.31 =========