builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0080 starttime: 1464367449.35 results: success (0) buildid: 20160527074648 builduid: eeeaf82fe0674eea94916a1a3f690f36 revision: acc1985e7895ab3d5c6053bc6682e20c1d41637d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 09:44:09.353570) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 09:44:09.354057) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 09:44:09.354335) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 09:44:09.537370) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 09:44:09.537909) ========= 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.q9U0YkGxVS/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.t7v6HfUcyt/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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-05-27 09:44:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 145M=0s 2016-05-27 09:44:09 (145 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.134247 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 09:44:09.696734) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 09:44:09.697058) ========= 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.q9U0YkGxVS/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.t7v6HfUcyt/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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.028245 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 09:44:09.745822) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 09:44:09.746192) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev acc1985e7895ab3d5c6053bc6682e20c1d41637d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev acc1985e7895ab3d5c6053bc6682e20c1d41637d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.q9U0YkGxVS/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.t7v6HfUcyt/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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-05-27 09:44:09,811 truncating revision to first 12 chars 2016-05-27 09:44:09,811 Setting DEBUG logging. 2016-05-27 09:44:09,811 attempt 1/10 2016-05-27 09:44:09,811 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/acc1985e7895?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 09:44:10,136 unpacking tar archive at: mozilla-aurora-acc1985e7895/testing/mozharness/ program finished with exit code 0 elapsedTime=0.824959 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 09:44:10.593941) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 09:44:10.594217) ========= script_repo_revision: acc1985e7895ab3d5c6053bc6682e20c1d41637d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 09:44:10.594574) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 09:44:10.594829) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 09:44:10.612407) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 38 secs) (at 2016-05-27 09:44:10.612674) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.q9U0YkGxVS/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.t7v6HfUcyt/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 09:44:10 INFO - MultiFileLogger online at 20160527 09:44:10 in /builds/slave/test 09:44:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:44:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:44:10 INFO - {'append_to_log': False, 09:44:10 INFO - 'base_work_dir': '/builds/slave/test', 09:44:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:44:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:44:10 INFO - 'branch': 'Mozilla-Aurora', 09:44:10 INFO - 'buildbot_json_path': 'buildprops.json', 09:44:10 INFO - 'config_files': ('talos/mac_config.py',), 09:44:10 INFO - 'default_actions': ('clobber', 09:44:10 INFO - 'read-buildbot-config', 09:44:10 INFO - 'download-and-extract', 09:44:10 INFO - 'populate-webroot', 09:44:10 INFO - 'create-virtualenv', 09:44:10 INFO - 'install', 09:44:10 INFO - 'run-tests'), 09:44:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:44:10 INFO - 'download_minidump_stackwalk': True, 09:44:10 INFO - 'download_symbols': 'ondemand', 09:44:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:44:10 INFO - 'tooltool.py': '/tools/tooltool.py', 09:44:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:44:10 INFO - '/tools/misc-python/virtualenv.py')}, 09:44:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:44:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:44:10 INFO - 'installer_path': 'installer.exe', 09:44:10 INFO - 'log_level': 'info', 09:44:10 INFO - 'log_name': 'talos', 09:44:10 INFO - 'log_to_console': True, 09:44:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:44:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:44:10 INFO - 'opt_config_files': (), 09:44:10 INFO - 'pip_index': False, 09:44:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:44:10 INFO - 'cmd': ('bash', 09:44:10 INFO - '-c', 09:44:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:44:10 INFO - 'enabled': True, 09:44:10 INFO - 'halt_on_failure': False, 09:44:10 INFO - 'name': 'check_screen_resolution'},), 09:44:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:44:10 INFO - 'cmd': ('bash', 09:44:10 INFO - '-c', 09:44:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:44:10 INFO - 'enabled': True, 09:44:10 INFO - 'halt_on_failure': False, 09:44:10 INFO - 'name': 'check_screen_resolution'},), 09:44:10 INFO - 'run_cmd_checks_enabled': True, 09:44:10 INFO - 'sps_profile': False, 09:44:10 INFO - 'sps_profile_interval': 0, 09:44:10 INFO - 'suite': 'dromaeojs-e10s', 09:44:10 INFO - 'system_bits': '32', 09:44:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:44:10 INFO - 'title': 't-yosemite-r7-0080', 09:44:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:44:10 INFO - 'use_talos_json': True, 09:44:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:44:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:44:10 INFO - 'work_dir': 'build'} 09:44:10 INFO - ##### 09:44:10 INFO - ##### Running clobber step. 09:44:10 INFO - ##### 09:44:10 INFO - Running pre-action listener: _resource_record_pre_action 09:44:10 INFO - Running main action method: clobber 09:44:10 INFO - rmtree: /builds/slave/test/build 09:44:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:44:13 INFO - Running post-action listener: _resource_record_post_action 09:44:13 INFO - ##### 09:44:13 INFO - ##### Running read-buildbot-config step. 09:44:13 INFO - ##### 09:44:13 INFO - Running pre-action listener: _resource_record_pre_action 09:44:13 INFO - Running main action method: read_buildbot_config 09:44:13 INFO - Using buildbot properties: 09:44:13 INFO - { 09:44:13 INFO - "project": "", 09:44:13 INFO - "product": "firefox", 09:44:13 INFO - "script_repo_revision": "production", 09:44:13 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:44:13 INFO - "repository": "", 09:44:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:44:13 INFO - "buildid": "20160527074648", 09:44:13 INFO - "pgo_build": "False", 09:44:13 INFO - "basedir": "/builds/slave/test", 09:44:13 INFO - "buildnumber": 11, 09:44:13 INFO - "slavename": "t-yosemite-r7-0080", 09:44:13 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:44:13 INFO - "platform": "yosemite_r7", 09:44:13 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:44:13 INFO - "branch": "mozilla-aurora", 09:44:13 INFO - "repo_path": "releases/mozilla-aurora", 09:44:13 INFO - "revision": "acc1985e7895ab3d5c6053bc6682e20c1d41637d", 09:44:13 INFO - "stage_platform": "macosx64", 09:44:13 INFO - "builduid": "eeeaf82fe0674eea94916a1a3f690f36", 09:44:13 INFO - "slavebuilddir": "test" 09:44:13 INFO - } 09:44:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 09:44:13 INFO - Running post-action listener: _resource_record_post_action 09:44:13 INFO - ##### 09:44:13 INFO - ##### Running download-and-extract step. 09:44:13 INFO - ##### 09:44:13 INFO - Running pre-action listener: _resource_record_pre_action 09:44:13 INFO - Running main action method: download_and_extract 09:44:13 INFO - mkdir: /builds/slave/test/build/tests 09:44: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')]} 09:44:13 INFO - https://queue.taskcluster.net/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:44:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 09:44:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 09:44:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 09:44:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 09:44:43 INFO - Downloaded 1199 bytes. 09:44:43 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 09:44:43 INFO - Using the following test package requirements: 09:44:43 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 09:44:43 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:44:43 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 09:44:43 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:44:43 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 09:44:43 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:44:43 INFO - u'jsshell-mac.zip'], 09:44:43 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:44:43 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 09:44:43 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 09:44:43 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:44:43 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 09:44:43 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:44:43 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 09:44:43 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:44:43 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 09:44:43 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:44:43 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 09:44:43 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:44:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:43 INFO - https://queue.taskcluster.net/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:44:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:44:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:44:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:44:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:44:44 INFO - Downloaded 18425606 bytes. 09:44:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:44:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:44:47 INFO - Return code: 0 09:44:47 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:44:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:47 INFO - https://queue.taskcluster.net/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:44:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:44:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:44:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:44:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:44:49 INFO - Downloaded 18425606 bytes. 09:44:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:44:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:44:52 INFO - Return code: 0 09:44:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:52 INFO - https://queue.taskcluster.net/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:44:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:44:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:44:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:44:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:44:53 INFO - Downloaded 10840145 bytes. 09:44:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:44:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:44:54 INFO - Return code: 0 09:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:54 INFO - https://queue.taskcluster.net/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 09:44:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:44:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:44:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 09:44:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 09:45:02 INFO - Downloaded 114142075 bytes. 09:45:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:45:02 INFO - mkdir: /builds/slave/test/properties 09:45:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:45:02 INFO - Writing to file /builds/slave/test/properties/build_url 09:45:02 INFO - Contents: 09:45:02 INFO - build_url:https://queue.taskcluster.net/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:45:03 INFO - Running post-action listener: _resource_record_post_action 09:45:03 INFO - Running post-action listener: set_extra_try_arguments 09:45:03 INFO - ##### 09:45:03 INFO - ##### Running populate-webroot step. 09:45:03 INFO - ##### 09:45:03 INFO - Running pre-action listener: _resource_record_pre_action 09:45:03 INFO - Running main action method: populate_webroot 09:45:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:45:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:45:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:45:03 INFO - '--tppagecycles', 09:45:03 INFO - '7'], 09:45:03 INFO - 'tests': ['tsvgm']}}, 09:45:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:45:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:45:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:45:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:45:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:45:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:45:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:03 INFO - 'talos_options': ['--disable-e10s'], 09:45:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:45:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:45:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:03 INFO - 'talos_options': ['--disable-e10s'], 09:45:03 INFO - 'tests': ['damp', 'tps']}, 09:45:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:03 INFO - 'tests': ['damp', 'tps']}, 09:45:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:45:03 INFO - 'tests': ['dromaeo_dom']}, 09:45:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:45:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:45:03 INFO - 'tests': ['a11yr', 09:45:03 INFO - 'ts_paint', 09:45:03 INFO - 'tpaint', 09:45:03 INFO - 'sessionrestore', 09:45:03 INFO - 'sessionrestore_no_auto_restore', 09:45:03 INFO - 'tabpaint']}, 09:45:03 INFO - 'other-e10s': {'tests': ['a11yr', 09:45:03 INFO - 'ts_paint', 09:45:03 INFO - 'tpaint', 09:45:03 INFO - 'sessionrestore', 09:45:03 INFO - 'sessionrestore_no_auto_restore', 09:45:03 INFO - 'tabpaint']}, 09:45:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:45:03 INFO - 'ts_paint', 09:45:03 INFO - 'tpaint', 09:45:03 INFO - 'sessionrestore', 09:45:03 INFO - 'sessionrestore_no_auto_restore', 09:45:03 INFO - 'tabpaint']}, 09:45:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:45:03 INFO - 'ts_paint', 09:45:03 INFO - 'tpaint', 09:45:03 INFO - 'sessionrestore', 09:45:03 INFO - 'sessionrestore_no_auto_restore', 09:45:03 INFO - 'tabpaint']}, 09:45:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:45:03 INFO - 'tests': ['a11yr', 09:45:03 INFO - 'ts_paint', 09:45:03 INFO - 'tpaint', 09:45:03 INFO - 'sessionrestore', 09:45:03 INFO - 'sessionrestore_no_auto_restore', 09:45:03 INFO - 'tabpaint']}, 09:45:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:45:03 INFO - 'tests': ['a11yr', 09:45:03 INFO - 'ts_paint', 09:45:03 INFO - 'tpaint', 09:45:03 INFO - 'sessionrestore', 09:45:03 INFO - 'sessionrestore_no_auto_restore', 09:45:03 INFO - 'tabpaint']}, 09:45:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:45:03 INFO - 'tests': ['tsvgx', 09:45:03 INFO - 'tsvgr_opacity', 09:45:03 INFO - 'tart', 09:45:03 INFO - 'tscrollx', 09:45:03 INFO - 'cart']}, 09:45:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:45:03 INFO - 'tsvgr_opacity', 09:45:03 INFO - 'tart', 09:45:03 INFO - 'tscrollx', 09:45:03 INFO - 'cart']}, 09:45:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:03 INFO - 'talos_options': ['--disable-e10s'], 09:45:03 INFO - 'tests': ['tp5o']}, 09:45:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:03 INFO - 'tests': ['tp5o']}, 09:45:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:03 INFO - 'talos_options': ['--disable-e10s', 09:45:03 INFO - '--xperf_path', 09:45:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:45:03 INFO - 'tests': ['tp5n']}, 09:45:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:03 INFO - 'talos_options': ['--xperf_path', 09:45:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:45:03 INFO - 'tests': ['tp5n']}}, 09:45:03 INFO - 'talos.zip': {'path': '', 09:45:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:45:03 INFO - Running post-action listener: _resource_record_post_action 09:45:03 INFO - ##### 09:45:03 INFO - ##### Running create-virtualenv step. 09:45:03 INFO - ##### 09:45:03 INFO - Running pre-action listener: _resource_record_pre_action 09:45:03 INFO - Running main action method: create_virtualenv 09:45:03 INFO - Creating virtualenv /builds/slave/test/build/venv 09:45:03 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 09:45:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:45:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:45:03 INFO - Using real prefix '/tools/python27' 09:45:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:45:03 INFO - Installing distribute.............................................................................................................................................................................................done. 09:45:06 INFO - Installing pip.................done. 09:45:06 INFO - Return code: 0 09:45:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:45:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1076d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076e7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdb356f870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107576e00>, '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 0x1073dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107571b20>, '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.q9U0YkGxVS/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.t7v6HfUcyt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 09:45:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:45:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:45:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9U0YkGxVS/Render', 09:45:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:06 INFO - 'HOME': '/Users/cltbld', 09:45:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:06 INFO - 'LOGNAME': 'cltbld', 09:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:06 INFO - 'MOZ_NO_REMOTE': '1', 09:45:06 INFO - 'NO_EM_RESTART': '1', 09:45:06 INFO - 'PAGER': '/bin/cat', 09:45:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:06 INFO - 'PWD': '/builds/slave/test', 09:45:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:06 INFO - 'SHELL': '/bin/bash', 09:45:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7v6HfUcyt/Listeners', 09:45:06 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 09:45:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:06 INFO - 'USER': 'cltbld', 09:45:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:06 INFO - 'XPC_FLAGS': '0x0', 09:45:06 INFO - 'XPC_SERVICE_NAME': '0', 09:45:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:07 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 09:45:07 INFO - Cleaning up... 09:45:07 INFO - Return code: 0 09:45:07 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:45: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')]} 09:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45: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')]} 09:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45: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', '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 0x1076d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076e7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdb356f870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107576e00>, '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 0x1073dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107571b20>, '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.q9U0YkGxVS/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.t7v6HfUcyt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 09:45: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', 'psutil>=0.7.1'] in /builds/slave/test/build 09:45: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 psutil>=0.7.1 09:45:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9U0YkGxVS/Render', 09:45:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:07 INFO - 'HOME': '/Users/cltbld', 09:45:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:07 INFO - 'LOGNAME': 'cltbld', 09:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:07 INFO - 'MOZ_NO_REMOTE': '1', 09:45:07 INFO - 'NO_EM_RESTART': '1', 09:45:07 INFO - 'PAGER': '/bin/cat', 09:45:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:07 INFO - 'PWD': '/builds/slave/test', 09:45:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:07 INFO - 'SHELL': '/bin/bash', 09:45:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7v6HfUcyt/Listeners', 09:45:07 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 09:45:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:07 INFO - 'USER': 'cltbld', 09:45:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:07 INFO - 'XPC_FLAGS': '0x0', 09:45:07 INFO - 'XPC_SERVICE_NAME': '0', 09:45:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:07 INFO - Downloading/unpacking psutil>=0.7.1 09:45:07 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 09:45:07 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 09:45:07 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 09:45:07 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 09:45:07 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 09:45:07 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 09:45:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:45:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:45:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:45:10 INFO - Installing collected packages: psutil 09:45:10 INFO - Running setup.py install for psutil 09:45:11 INFO - building 'psutil._psutil_osx' extension 09:45:11 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 09:45:11 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 09:45:11 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 09:45:11 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 09:45:11 INFO - building 'psutil._psutil_posix' extension 09:45:11 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 09:45:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:45:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:45:11 INFO - ^ 09:45:11 INFO - 1 warning generated. 09:45:11 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 09:45:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:45:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:45:12 INFO - Successfully installed psutil 09:45:12 INFO - Cleaning up... 09:45:12 INFO - Return code: 0 09:45:12 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:45:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1076d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076e7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdb356f870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107576e00>, '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 0x1073dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107571b20>, '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.q9U0YkGxVS/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.t7v6HfUcyt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 09:45:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:45:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:45:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9U0YkGxVS/Render', 09:45:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:12 INFO - 'HOME': '/Users/cltbld', 09:45:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:12 INFO - 'LOGNAME': 'cltbld', 09:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:12 INFO - 'MOZ_NO_REMOTE': '1', 09:45:12 INFO - 'NO_EM_RESTART': '1', 09:45:12 INFO - 'PAGER': '/bin/cat', 09:45:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:12 INFO - 'PWD': '/builds/slave/test', 09:45:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:12 INFO - 'SHELL': '/bin/bash', 09:45:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7v6HfUcyt/Listeners', 09:45:12 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 09:45:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:12 INFO - 'USER': 'cltbld', 09:45:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:12 INFO - 'XPC_FLAGS': '0x0', 09:45:12 INFO - 'XPC_SERVICE_NAME': '0', 09:45:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:12 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:15 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:45:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:45:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:45:15 INFO - Installing collected packages: mozsystemmonitor 09:45:15 INFO - Running setup.py install for mozsystemmonitor 09:45:15 INFO - Successfully installed mozsystemmonitor 09:45:15 INFO - Cleaning up... 09:45:15 INFO - Return code: 0 09:45:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:45:15 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')]} 09:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:15 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')]} 09:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:15 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 0x1076d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076e7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdb356f870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107576e00>, '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 0x1073dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107571b20>, '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.q9U0YkGxVS/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.t7v6HfUcyt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 09:45:15 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 09:45:15 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 09:45:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9U0YkGxVS/Render', 09:45:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:15 INFO - 'HOME': '/Users/cltbld', 09:45:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:15 INFO - 'LOGNAME': 'cltbld', 09:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:15 INFO - 'MOZ_NO_REMOTE': '1', 09:45:15 INFO - 'NO_EM_RESTART': '1', 09:45:15 INFO - 'PAGER': '/bin/cat', 09:45:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:15 INFO - 'PWD': '/builds/slave/test', 09:45:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:15 INFO - 'SHELL': '/bin/bash', 09:45:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7v6HfUcyt/Listeners', 09:45:15 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 09:45:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:15 INFO - 'USER': 'cltbld', 09:45:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:15 INFO - 'XPC_FLAGS': '0x0', 09:45:15 INFO - 'XPC_SERVICE_NAME': '0', 09:45:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:16 INFO - Downloading/unpacking blobuploader==1.2.4 09:45:16 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 09:45:16 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 09:45:16 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 09:45:16 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 09:45:16 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 09:45:16 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 09:45:18 INFO - Downloading blobuploader-1.2.4.tar.gz 09:45:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:45:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:45:18 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 09:45:18 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 09:45:18 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 09:45:18 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 09:45:18 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 09:45:18 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 09:45:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:45:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:45:19 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 09:45:19 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 09:45:19 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 09:45:19 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 09:45:19 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 09:45:19 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 09:45:19 INFO - Downloading docopt-0.6.1.tar.gz 09:45:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:45:19 INFO - Installing collected packages: blobuploader, requests, docopt 09:45:19 INFO - Running setup.py install for blobuploader 09:45:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:45:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:45:20 INFO - Running setup.py install for requests 09:45:20 INFO - Running setup.py install for docopt 09:45:20 INFO - Successfully installed blobuploader requests docopt 09:45:20 INFO - Cleaning up... 09:45:20 INFO - Return code: 0 09:45:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:45:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:20 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 0x1076d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076e7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdb356f870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107576e00>, '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 0x1073dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107571b20>, '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.q9U0YkGxVS/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.t7v6HfUcyt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 09:45:20 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 09:45:20 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 09:45:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9U0YkGxVS/Render', 09:45:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:20 INFO - 'HOME': '/Users/cltbld', 09:45:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:20 INFO - 'LOGNAME': 'cltbld', 09:45:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:20 INFO - 'MOZ_NO_REMOTE': '1', 09:45:20 INFO - 'NO_EM_RESTART': '1', 09:45:20 INFO - 'PAGER': '/bin/cat', 09:45:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:20 INFO - 'PWD': '/builds/slave/test', 09:45:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:20 INFO - 'SHELL': '/bin/bash', 09:45:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7v6HfUcyt/Listeners', 09:45:20 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 09:45:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:20 INFO - 'USER': 'cltbld', 09:45:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:20 INFO - 'XPC_FLAGS': '0x0', 09:45:20 INFO - 'XPC_SERVICE_NAME': '0', 09:45:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:45:21 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-vXXoE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:45:21 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-ej6G9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:45:21 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-PXV0M0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:45:21 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-Uk7iHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:45:21 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-Tbz2v3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:45:21 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-kGRixi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:45:21 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-Lr9Y7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:45:21 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-AW9rvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:45:21 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-ufmaEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:45:22 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-R5SKMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:45:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:45:22 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-fROfhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:45:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:45:22 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-petJ3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:45:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:45:22 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-VUs9Ga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:45:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:45:22 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-uuM4Rv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:45:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:45:22 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-LdcF5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:45:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:45:22 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-LATQEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:45:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:45:22 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-Zv7_s4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:45:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:45:22 INFO - Running setup.py install for manifestparser 09:45:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:45:23 INFO - Running setup.py install for mozcrash 09:45:23 INFO - Running setup.py install for mozdebug 09:45:23 INFO - Running setup.py install for mozdevice 09:45:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:45:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:45:23 INFO - Running setup.py install for mozfile 09:45:23 INFO - Running setup.py install for mozhttpd 09:45:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:45:23 INFO - Running setup.py install for mozinfo 09:45:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:45:23 INFO - Running setup.py install for mozInstall 09:45:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:45:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:45:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:45:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:45:24 INFO - Running setup.py install for mozleak 09:45:24 INFO - Running setup.py install for mozlog 09:45:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:45:24 INFO - Running setup.py install for moznetwork 09:45:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:45:24 INFO - Running setup.py install for mozprocess 09:45:24 INFO - Running setup.py install for mozprofile 09:45:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:45:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:45:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:45:24 INFO - Running setup.py install for mozrunner 09:45:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:45:24 INFO - Running setup.py install for mozscreenshot 09:45:25 INFO - Running setup.py install for moztest 09:45:25 INFO - Running setup.py install for mozversion 09:45:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:45:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:45:25 INFO - Cleaning up... 09:45:25 INFO - Return code: 0 09:45:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:45:25 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')]} 09:45:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:25 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')]} 09:45:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:25 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 0x1076d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076e7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdb356f870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107576e00>, '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 0x1073dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107571b20>, '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.q9U0YkGxVS/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.t7v6HfUcyt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 09:45:25 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 09:45:25 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 09:45:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9U0YkGxVS/Render', 09:45:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:25 INFO - 'HOME': '/Users/cltbld', 09:45:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:25 INFO - 'LOGNAME': 'cltbld', 09:45:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:25 INFO - 'MOZ_NO_REMOTE': '1', 09:45:25 INFO - 'NO_EM_RESTART': '1', 09:45:25 INFO - 'PAGER': '/bin/cat', 09:45:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:25 INFO - 'PWD': '/builds/slave/test', 09:45:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:25 INFO - 'SHELL': '/bin/bash', 09:45:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7v6HfUcyt/Listeners', 09:45:25 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 09:45:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:25 INFO - 'USER': 'cltbld', 09:45:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:25 INFO - 'XPC_FLAGS': '0x0', 09:45:25 INFO - 'XPC_SERVICE_NAME': '0', 09:45:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:45:25 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-erdkqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:45:25 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)) 09:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:45:25 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-Gg97x8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:45:25 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)) 09:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:45:25 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-Oz1H4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:45:26 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)) 09:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:45:26 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-JpVe8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:45:26 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)) 09:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:45:26 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-tCUi2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:45:26 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)) 09:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:45:26 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-842jpt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:45:26 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)) 09:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:45:26 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-x_KTFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:45:26 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)) 09:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:45:26 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-Xm1LRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:45:26 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)) 09:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:45:26 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-uEtneB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:45:26 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)) 09:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:45:26 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-xJxneo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:45:26 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)) 09:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:45:26 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-zWRdzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:45:26 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)) 09:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:45:26 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-t8YWw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:45:26 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)) 09:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:45:26 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-mr3svr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:45:27 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)) 09:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:45:27 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-PvyrkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:45:27 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)) 09:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:45:27 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-2oyvhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:45:27 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)) 09:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:45:27 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-X7iVtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:45:27 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)) 09:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:45:27 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-X5NEqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:45:27 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)) 09:45:27 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)) 09:45:27 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)) 09:45:27 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)) 09:45:27 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)) 09:45:27 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)) 09:45:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:45:27 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 09:45:27 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 09:45:27 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 09:45:27 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 09:45:27 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 09:45:27 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 09:45:29 INFO - Downloading blessings-1.6.tar.gz 09:45:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:45:30 INFO - Installing collected packages: blessings 09:45:30 INFO - Running setup.py install for blessings 09:45:30 INFO - Successfully installed blessings 09:45:30 INFO - Cleaning up... 09:45:30 INFO - Return code: 0 09:45:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:45:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:45:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:45:30 INFO - Reading from file tmpfile_stdout 09:45:30 INFO - Current package versions: 09:45:30 INFO - blessings == 1.6 09:45:30 INFO - blobuploader == 1.2.4 09:45:30 INFO - docopt == 0.6.1 09:45:30 INFO - manifestparser == 1.1 09:45:30 INFO - mozInstall == 1.12 09:45:30 INFO - mozcrash == 0.17 09:45:30 INFO - mozdebug == 0.1 09:45:30 INFO - mozdevice == 0.48 09:45:30 INFO - mozfile == 1.2 09:45:30 INFO - mozhttpd == 0.7 09:45:30 INFO - mozinfo == 0.9 09:45:30 INFO - mozleak == 0.1 09:45:30 INFO - mozlog == 3.1 09:45:30 INFO - moznetwork == 0.27 09:45:30 INFO - mozprocess == 0.22 09:45:30 INFO - mozprofile == 0.28 09:45:30 INFO - mozrunner == 6.11 09:45:30 INFO - mozscreenshot == 0.1 09:45:30 INFO - mozsystemmonitor == 0.0 09:45:30 INFO - moztest == 0.7 09:45:30 INFO - mozversion == 1.4 09:45:30 INFO - psutil == 3.1.1 09:45:30 INFO - requests == 1.2.3 09:45:30 INFO - wsgiref == 0.1.2 09:45:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:45: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')]} 09:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45: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')]} 09:45:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:30 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 0x1076d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076e7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdb356f870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107576e00>, '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 0x1073dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107571b20>, '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.q9U0YkGxVS/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.t7v6HfUcyt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 09:45:30 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 09:45:30 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 09:45:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9U0YkGxVS/Render', 09:45:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:30 INFO - 'HOME': '/Users/cltbld', 09:45:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:30 INFO - 'LOGNAME': 'cltbld', 09:45:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:30 INFO - 'MOZ_NO_REMOTE': '1', 09:45:30 INFO - 'NO_EM_RESTART': '1', 09:45:30 INFO - 'PAGER': '/bin/cat', 09:45:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:30 INFO - 'PWD': '/builds/slave/test', 09:45:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:30 INFO - 'SHELL': '/bin/bash', 09:45:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7v6HfUcyt/Listeners', 09:45:30 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 09:45:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:30 INFO - 'USER': 'cltbld', 09:45:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:30 INFO - 'XPC_FLAGS': '0x0', 09:45:30 INFO - 'XPC_SERVICE_NAME': '0', 09:45:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:30 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)) 09:45:30 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)) 09:45:30 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)) 09:45:30 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)) 09:45:30 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)) 09:45:30 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)) 09:45:30 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)) 09:45:30 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)) 09:45:30 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)) 09:45:30 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)) 09:45:30 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)) 09:45:30 INFO - Cleaning up... 09:45:30 INFO - Return code: 0 09:45:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:45: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')]} 09:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45: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')]} 09:45:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1076d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076e7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdb356f870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107576e00>, '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 0x1073dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107571b20>, '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.q9U0YkGxVS/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.t7v6HfUcyt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 09:45: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', 'jsonschema'] in /builds/slave/test/build 09:45: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 jsonschema 09:45:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9U0YkGxVS/Render', 09:45:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:30 INFO - 'HOME': '/Users/cltbld', 09:45:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:30 INFO - 'LOGNAME': 'cltbld', 09:45:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:30 INFO - 'MOZ_NO_REMOTE': '1', 09:45:30 INFO - 'NO_EM_RESTART': '1', 09:45:30 INFO - 'PAGER': '/bin/cat', 09:45:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:30 INFO - 'PWD': '/builds/slave/test', 09:45:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:30 INFO - 'SHELL': '/bin/bash', 09:45:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7v6HfUcyt/Listeners', 09:45:30 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 09:45:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:30 INFO - 'USER': 'cltbld', 09:45:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:30 INFO - 'XPC_FLAGS': '0x0', 09:45:30 INFO - 'XPC_SERVICE_NAME': '0', 09:45:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:31 INFO - Downloading/unpacking jsonschema 09:45:31 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 09:45:31 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 09:45:31 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 09:45:31 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 09:45:31 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 09:45:31 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 09:45:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:45:33 INFO - Downloading/unpacking functools32 (from jsonschema) 09:45: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 09:45: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 09:45: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 09:45: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 09:45: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 09:45: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 09:45:34 INFO - Downloading functools32-3.2.3-2.tar.gz 09:45:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:45:34 INFO - warning: no files found matching '*.txt' 09:45:34 INFO - no previously-included directories found matching 'build' 09:45:34 INFO - no previously-included directories found matching 'dist' 09:45:34 INFO - no previously-included directories found matching '.git*' 09:45:34 INFO - Installing collected packages: jsonschema, functools32 09:45:34 INFO - Running setup.py install for functools32 09:45:34 INFO - warning: no files found matching '*.txt' 09:45:34 INFO - no previously-included directories found matching 'build' 09:45:34 INFO - no previously-included directories found matching 'dist' 09:45:34 INFO - no previously-included directories found matching '.git*' 09:45:34 INFO - Successfully installed jsonschema functools32 09:45:34 INFO - Cleaning up... 09:45:34 INFO - Return code: 0 09:45:34 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:45:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1076d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076e7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdb356f870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107576e00>, '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 0x1073dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107571b20>, '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.q9U0YkGxVS/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.t7v6HfUcyt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 09:45:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:45:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:45:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9U0YkGxVS/Render', 09:45:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:34 INFO - 'HOME': '/Users/cltbld', 09:45:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:34 INFO - 'LOGNAME': 'cltbld', 09:45:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:34 INFO - 'MOZ_NO_REMOTE': '1', 09:45:34 INFO - 'NO_EM_RESTART': '1', 09:45:34 INFO - 'PAGER': '/bin/cat', 09:45:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:34 INFO - 'PWD': '/builds/slave/test', 09:45:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:34 INFO - 'SHELL': '/bin/bash', 09:45:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7v6HfUcyt/Listeners', 09:45:34 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 09:45:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:34 INFO - 'USER': 'cltbld', 09:45:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:34 INFO - 'XPC_FLAGS': '0x0', 09:45:34 INFO - 'XPC_SERVICE_NAME': '0', 09:45:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:34 INFO - Downloading/unpacking flake8 09:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:37 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:45:37 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:37 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:45:37 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:37 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:45:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:45:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:45:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:45:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:45:38 INFO - no previously-included directories found matching 'docs/_build' 09:45:38 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:45:38 INFO - Running setup.py install for pep8 09:45:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:45:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:45:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:45:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:45:38 INFO - no previously-included directories found matching 'docs/_build' 09:45:38 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:45:38 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:45:38 INFO - Cleaning up... 09:45:38 INFO - Return code: 0 09:45:38 INFO - Running post-action listener: _resource_record_post_action 09:45:38 INFO - Running post-action listener: _start_resource_monitoring 09:45:38 INFO - Starting resource monitoring. 09:45:38 INFO - ##### 09:45:38 INFO - ##### Running install step. 09:45:38 INFO - ##### 09:45:38 INFO - Running pre-action listener: _resource_record_pre_action 09:45:38 INFO - Running main action method: install 09:45:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:45:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:45:39 INFO - Reading from file tmpfile_stdout 09:45:39 INFO - Detecting whether we're running mozinstall >=1.0... 09:45:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:45:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:45:39 INFO - Reading from file tmpfile_stdout 09:45:39 INFO - Output received: 09:45:39 INFO - Usage: mozinstall [options] installer 09:45:39 INFO - Options: 09:45:39 INFO - -h, --help show this help message and exit 09:45:39 INFO - -d DEST, --destination=DEST 09:45:39 INFO - Directory to install application into. [default: 09:45:39 INFO - "/builds/slave/test"] 09:45:39 INFO - --app=APP Application being installed. [default: firefox] 09:45:39 INFO - mkdir: /builds/slave/test/build/application 09:45:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:45:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:46:10 INFO - Reading from file tmpfile_stdout 09:46:10 INFO - Output received: 09:46:10 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:46:10 INFO - Running post-action listener: _resource_record_post_action 09:46:10 INFO - ##### 09:46:10 INFO - ##### Running run-tests step. 09:46:10 INFO - ##### 09:46:10 INFO - Running pre-action listener: _resource_record_pre_action 09:46:10 INFO - Running main action method: run_tests 09:46:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:46:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:46:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:46:10 INFO - 2016-05-27 09:46:10.749 screenresolution[1650:7950] starting screenresolution argv=screenresolution get 09:46:10 INFO - 2016-05-27 09:46:10.751 screenresolution[1650:7950] Display 0: 1600x1200x32@60 09:46:10 INFO - 2016-05-27 09:46:10.757 screenresolution[1651:7952] starting screenresolution argv=screenresolution list 09:46:10 INFO - Available Modes on Display 0 09:46:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:46:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:46:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:46:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:46:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:46:10 INFO - Intel Iris: 09:46:10 INFO - Chipset Model: Intel Iris 09:46:10 INFO - Type: GPU 09:46:10 INFO - Bus: Built-In 09:46:10 INFO - VRAM (Dynamic, Max): 1536 MB 09:46:10 INFO - Vendor: Intel (0x8086) 09:46:10 INFO - Device ID: 0x0a2e 09:46:10 INFO - Revision ID: 0x0009 09:46:10 INFO - Displays: 09:46:10 INFO - Display: 09:46:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:46:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:46:10 INFO - Display Serial Number: 1600x1200 60 09:46:10 INFO - Main Display: Yes 09:46:10 INFO - Mirror: Off 09:46:10 INFO - Online: Yes 09:46:10 INFO - Rotation: Supported 09:46:10 INFO - Return code: 0 09:46:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:46:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:46:10 INFO - Python 2.7.3 09:46:10 INFO - Return code: 0 09:46:10 INFO - grabbing minidump binary from tooltool 09:46:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:46:10 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 0x107576e00>, '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 0x1073dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107571b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:46:10 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 09:46:10 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 09:46:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:46:11 INFO - Return code: 0 09:46:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:46:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:46:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:46:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:46:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:46:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:46:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:46:12 INFO - Return code: 0 09:46:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0080', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:46:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0080 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:46:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9U0YkGxVS/Render', 09:46:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:12 INFO - 'HOME': '/Users/cltbld', 09:46:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:12 INFO - 'LOGNAME': 'cltbld', 09:46:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:46:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:46:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:12 INFO - 'MOZ_NO_REMOTE': '1', 09:46:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:46:12 INFO - 'NO_EM_RESTART': '1', 09:46:12 INFO - 'PAGER': '/bin/cat', 09:46:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:12 INFO - 'PWD': '/builds/slave/test', 09:46:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:46:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:12 INFO - 'SHELL': '/bin/bash', 09:46:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7v6HfUcyt/Listeners', 09:46:12 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 09:46:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:12 INFO - 'USER': 'cltbld', 09:46:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:12 INFO - 'XPC_FLAGS': '0x0', 09:46:12 INFO - 'XPC_SERVICE_NAME': '0', 09:46:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0080', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:46:12 INFO - mozversion application_buildid: 20160527074648 09:46:12 INFO - mozversion application_changeset: acc1985e7895ab3d5c6053bc6682e20c1d41637d 09:46:12 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:46:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:46:12 INFO - mozversion application_name: Firefox 09:46:12 INFO - mozversion application_remotingname: firefox-dev 09:46:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:46:12 INFO - mozversion application_vendor: Mozilla 09:46:12 INFO - mozversion application_version: 48.0a2 09:46:12 INFO - mozversion platform_buildid: 20160527074648 09:46:12 INFO - mozversion platform_changeset: acc1985e7895ab3d5c6053bc6682e20c1d41637d 09:46:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:46:12 INFO - mozversion platform_version: 48.0a2 09:46:12 INFO - using testdate: 1464367572 09:46:12 INFO - actual date: 1464367572 09:46:12 INFO - starting webserver on 'localhost:49277' 09:46:12 INFO - SUITE-START | Running 2 tests 09:46:12 INFO - TEST-START | dromaeo_css 09:46:12 INFO - operating with platform_type : mac_ 09:46:12 INFO - Initialising browser for dromaeo_css test... 09:46:12 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmpJLFYNb/profile http://localhost:49277/getInfo.html) 09:46:15 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 09:46:15 INFO - PROCESS | 1669 | colorDepth:24 09:46:15 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 09:46:15 INFO - PROCESS | 1669 | __metrics 09:46:15 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 09:46:15 INFO - TEST-INFO | 1669: exit 0 09:46:15 INFO - Browser initialized. 09:46:15 INFO - Running cycle 1/1 for dromaeo_css test... 09:46:15 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmpJLFYNb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:46:27 INFO - PROCESS | 1672 | RSS: Main: 220823552 09:46:27 INFO - PROCESS | 1672 | 09:47:17 INFO - PROCESS | 1672 | 1464367637676 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] 09:49:28 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 09:49:29 INFO - PROCESS | 1672 | RSS: Main: 231538688 09:49:29 INFO - PROCESS | 1672 | 09:52:31 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 09:52:32 INFO - PROCESS | 1672 | RSS: Main: 243798016 09:52:32 INFO - PROCESS | 1672 | 09:55:33 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 09:55:34 INFO - PROCESS | 1672 | RSS: Main: 244924416 09:55:34 INFO - PROCESS | 1672 | 09:58:36 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 09:58:36 INFO - PROCESS | 1672 | RSS: Main: 247570432 09:58:36 INFO - PROCESS | 1672 | 10:01:39 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 10:01:40 INFO - PROCESS | 1672 | RSS: Main: 249499648 10:01:40 INFO - PROCESS | 1672 | 10:04:42 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 10:04:42 INFO - PROCESS | 1672 | RSS: Main: 251584512 10:04:42 INFO - PROCESS | 1672 | 10:04:42 INFO - PROCESS | 1672 | __start_tp_report 10:04:42 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 10:04:42 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 10:04:42 INFO - PROCESS | 1672 | |i|pagename|runs| 10:04:42 INFO - PROCESS | 1672 | |0;dojo.html;2720.6599175103115;3426.6230714621397;3468.7225021998242;3487.7384196185276;3499.3176330615524;5652.123920792279;5661.943380566193;5664.773409063643;5676.035074037413;5729;5650.74571644276;5698.6865722385355;5701.543876489871;5713.771449142039;5723.027085395483;5177;5195.896082078356;5227.059129356714;5260.710660913648;5308.362996440433;5150.690958542499;5207.999999999981;5292.338457692788;5341.092014357569;5349.170878513836;5240.187770895517;5297.523222909937;5308.389535203443;5322.0154271459905;5341;5254.054270231357;5272.024675435037;5284.5772338212855;5286.550643195333;5335.706536140511;5017.473165317662;5059.3169922060415;5186.351706036746;5192.402873669538;5249.632525723201;4529.501754806968;5541.750621222055;5603.495685388335;5617.185508101344;5633.57748168889;5308;6201.937980620181;6212.502999760053;6225.968870155621;6274.027525733519;6133.478654314388;6155.969220153916;6164.3835616438;6245.000799872044;6245.094461303131;5797.565182611237;5799.855003624901;5801.564882633777;5805.82582522525;5817.7091145442555;5320.52115309624;6259.624422534663;6285.622862628165;6288.339724328971;6302.4958003359625;5049.873753156127;5215.608829337739;5889.852753681193;5925.822225333247;6146.416090471398;6236.781712640036;6245.718942647592;6248.187735594343;6257.155284036688;6265.248170219603;5049.924251136236;6150.50795936324;6217.782377616762;6221.377862213742;6227.657478838707;6079.787207447718;6141.815745527641;6167.599106058092;6232.844178895564;6237.09562113491;4860.9513904861205;4910.238912968495;4918.704877707278;4928.901421971541;4950.8267210647455;4361.127774445124;4449.199144154083;4451.599356057905;4454.821807127679;4470;5554.750036248298;5679.261695979496;5713.143028545752;5719.142128680648;5790.797322093791;6150.231221097363;6226.377362263828;6229.065640153923;6231.781887633911;6233.1273621693;5769.509591684669;5777.653340799566;5787.305523337226;5803.7968671096305;5816.970915145397;5270.288511050959;5328.626996110313;5345.812896548602;5394.568434525307;5403.2165336026055;5848.853778655568;6137.938620613826;6247;6275.152854364694;6306.432421082125;5955.791547295824;6188.566800324114;6239.594426362272;6254.937450625617;6272.8118156455375;5269.89460210786;5962.314333851663;6198.535109866688;6325.905111423241;6347.587406818542;6151.661658608819;6194.225721784777;6194.907076393948;6195.318514963441;6215.81352559424;5865.677387743715;6236.688165591793;6246.5002799775;6248.156498872776;6249.468795152281;5933.732982015733;6062.666553339609;6138.480361557057;6150.169727086786;6154.230721159855;6062.939370606237;6063.484603808549;6092;6094.2077529920825;6097.146399503984;5893.263342082239;5923.08192230205;5932.991391463548;5985.102234664835;5998;5949.196858424058;5994.700264986646;6082.118092876596;6087.391260873877;6103.267607887082;4969.47820478859;5969.70151492415;6015.72929218195;6017.638941663514;6022.578419510767 10:04:42 INFO - PROCESS | 1672 | |1;ext.html;507.11508417810916;507.4342108548969;565.2905603467648;585.9979435165866;611.4038812158146;38144.80927595365;38899;39061.82814515562;39096.609033909655;39188;40137;40801.387979180334;40802.59197408025;40910.977225569375;40943.18113637733;9462.384944978572;9637.373570717917;9689.418634881895;9692.030796920322;9718.757031074245;7430.962845185766;9064.818703625924;9473.147416732492;9670.564824582876;9693.3699309545;9519.619215231382;9666.275029372826;9711.611535538605;9712.125908668218;9717.46553939533;8339.791505212388;9529.666461673844;9593.232541396672;9728.41629502225;9731.902680973171;8163.755087347389;8167.550784706838;9661.420314781135;9661.420314781135;9670.70987870365;38919.41620875689;39004.80497597494;39196.80401598002;39297.80351098226;39391.8030409849;2504.8371855829314;2885.5960165576835;3066.84665766713;3225.4355487789776;3268.1176082457605;26184.34539136518;26196.21411357662;26367.73632263672;27250.45499090007;27823.165305040882;2676.2372723773633;2694.1648089092446;2729.56326987683;2732.2076597786618;2744.5745909384077;367392;368498.4725229124;369451.1527442373;371670;375429;34989.47515787265;35216.471752923724;35222;36175;37054;37030;37164.25671486609;37192;37325.06687332838;37360.62639373626;15731.764023539656;15783.842161578466;15893.443729469413;15960;16000.839991599934;13300.800487992688;13317.400716967759;13345.332733363293;13380;13391.129576577492;8269.62786674601;8283.792905177419;8309.750707478664;8381.245687888118;8438.53588052663;2976.940461190764;3405.6764607362757;3429.4341433663167;3451.861925523001;3504.229069604683;916.1845957098188;936.9672061477816;993.60752502761;1004.0260946881514;1058.6771034834296;27434.45131097407;29102;29136.41727165487;30112.091462656892;30196.54705179424;906.1391677906016;1111.9388433636066;1150.6950658075616;1158.5539567266662;1251.7371352016178;14152.938529610321;15425.614359640875;15501.844981550266;15693.764593531107;15929.961554550642;586278.1372186309;593212;621822;626291;631329;59323.70338148282;60201.397986020456;60968.695156523936;61357.69321153365;61804;1500.534834201401;1512.6974605078808;1512.7730840374031;1527.5722797616686;1586.6588683432885;29040.709592904645;30069.3986120272;30093.247668808453;30502;30582.08253752391;52346.95306093933;52627.473725263786;53136.468635313155;53141.7342913283;53376.73311633417;570815;575952;579052;579278;581235;26718.064867729536;26863.597046043935;27219.727802721718;27408.588871167347;42854.35718464288;43406;43654.126917462105;44242.77878610586;44280;44923.550764491934;35900.461493078146;36375.45436818503;37149.88550343494;37866.43200351942;38234;36572.81713591415;37287.44068839024;37299.62700372961;37386.43920341251;37642.247155057295;32634.34731305313;36695.81652091722;37773.81113094417;38280.04299892525;38812.41781373225;31280.843595782786;32988;33108.834455827564;33265.168370790925;33446.66553334533;22260;22395.664065038713;24785.752142478344;24939.750602493743;25110.49779004446 10:04:42 INFO - PROCESS | 1672 | |2;jquery.html;373.42492561455344;380.69354169893245;403.37275536540665;404.45802624483196;405.3656028315686;15144.848551514482;15496.845031549681;19878.60242795143;20518.794812051874;20529.486762830937;16495;20320.085596148172;20615.690764638504;20617;21003.684944725843;5889.793857215009;6071.3625069367745;6120.20437343147;6131.693415329238;6156.230471191101;6167.907481387782;6863.039174515572;6865.725370985155;6879.140107486564;6898.310168983112;5677.772889084432;6534.117894084286;6685.097511835913;6747.156605424322;6796.966015169917;6686.899696504556;6720.3279672032895;6728.226253980782;6753.223379311393;6759.898601520981;5690.715464226813;5861.443162899518;5946.256717910262;6034.1853849730605;6034.547408944303;13497.325133743274;13727.107737997098;13783.448662053506;14243.501477448239;26211;1979.8416126709972;2216.6342553478657;2230.364346161336;2366.077229880348;2784.874680639376;9716.854247186298;9795.118439340422;10061.89938100617;10359.27485076045;10408.063274305348;1747.6417334446407;1795.6049669072784;1805.4132406967788;1826.6164105537853;1843.1521500109966;105123.47438262837;112137.87862121356;112723;124643.88390290225;126399.36800316031;10411.375317480975;11660.650180494598;11730.477947241385;11892.643220703392;12225.449854756558;15347.07917524952;15383.00010499314;15387.769183462256;15414.537563873102;15432.537023889301;8808.25129863956;9102.90897091034;9123.680671176477;9136.406133601295;9190.908090919005;9122.635094596275;9151.496667683208;9153.405028673114;9159.633614655473;9192.080791920887;4744.95255047452;4785.641076919245;4853.369062021915;4873.31773551703;4890.462049174588;1633.55893908644;1994.2621230144648;2044.7220487195502;2052.548439343342;2054.3939537836372;836.7489753074053;974.936629119105;993;1047.3297089862494;1057.7937302225992;17738.91130544339;19611.411657650293;20163.798362016485;20555;21022;883.0066175552504;946.6210886142535;1002.6691191906654;1006.5269323417982;1043.8538604595362;10830.837537436944;11109.008892385256;11145.944270278596;11165.665030049111;11301.378424186585;523444;617944;628919;653047;657181;125664;127327;130591;132316;133795;1024.9692509224735;1039.7660526381655;1053.8577292065627;1063;1063.361982810323;18226;18622.255109796;18854.811451885307;18866.434006979813;19093;49913.500864990885;51865.7406712964;51951.74024130006;52989.470105299995;53786.731066344415;66730;66748;77644.22355776596;78528.2147178521;80270.5986470064;7834.373250139861;8079.474834135763;8232.259096681328;8274.213949674771;8346.540940248102;8915.73252802417;11319.71700707456;12213.572524961584;12254.38728063611;12355.69110772202;10529.262951593315;10636.361818290927;10767.138628909512;10837.945810270898;11007.229493935347;10247.641332553623;10502;10555.841662374916;10685.145188385066;10735.785284294427;10707.250492465453;10854.511546980248;10886.401247931122;10914.017738403265;11383.032442242502;8053.154418785943;8106;8140.59297035134;8397.328213742763;8422;9053.909460905306;9802.215822734022;10176;10537.9473102634;10691.465426728477 10:04:42 INFO - PROCESS | 1672 | |3;mootools.html;1495.446684726651;1553.207863989365;1830.4600142957827;1954.794746551613;1981.9801801981976;7863.252990965863;8542;8576.69981550646;8667.349948753854;8696.652133914644;8805.559722013906;8857.512836793974;8937.195652391283;8950.194482496574;9033.864492032624;2411.987940060306;2421.1767998880423;2423.3699238198105;2440.548498527778;2474.925752227427;2332.7084114485688;2441.4872876695913;2447.669564608782;2489.2034548944343;2491.0533997081084;2173.597884391385;2298.7241531016302;2457.2259738182506;2477.256822953107;2483.0316176691103;2428.3200703802963;2449.326435230308;2457.4347899983013;2459.274514018351;2538.834975726581;2215.900284487202;2335.8131349492;2468.1114798672465;2490.203134996802;2495.0144692846225;9002.504862232574;9009.864852027225;9014.504202268872;9047.728568142966;9061.456312621263;5305.495977882095;5471;5492.890142197173;5500.724963751796;5514.338279406456;3417.794932304045;3453.9309213815836;3464.5842498900292;3488.4418493041244;3501.9649803501893;931.9003575780577;1003.4731765823001;1042.3016578892161;1059.941703206331;1076.4025965589094;11483.598074067368;11595.478203480863;11679.182457227997;11758.235714678518;11790.882091179064;4562.384078149407;4604.470485894165;4616.3537104805355;4632.745199014086;4652.487941419053;9250.629974801068;9331.580078896466;9338.673146439842;9350.90649093514;9354.579043943038;5215.426303106655;5253.605979551549;5304.549113325411;5343.973280133574;5376.086065368897;4409.889752756207;4823.962847987699;4892.0705066037335;4949.010197960422;4993.926305844188;3851.2490064437166;3896.980515097406;3910.1397692507603;3955.4660120883314;3961.1087505311075;1151.5278735718482;1222.1078612612841;1230.4340003598265;1237.4493350458959;1246.5512415530402;758.7154816943646;866.5277423804042;871.4509858788922;934.3319526538558;943.6461327002374;5888.882222355614;5904.586678932434;5906.527477801766;6008.369121242294;6017;876.8377850097752;910.4901255297054;932.4545141092536;935.1863878425813;936.0826390137703;3877.6316249956208;3935.74417662851;3938;3939.2318497892634;4031.1534577739003;10597.523111460003;11068.28056176362;11121.666350009513;11128.109751219883;11173.944130279458;10847.783044339227;10868.945655271673;10926.344419334866;10946.671599852018;10962.013418792347;8160.959195203986;8454.661813527513;8559.914400855912;8579.485230886043;8598.613062412207;5767.365589785204;5832.270966129234;5854.590178687451;5868.765249390148;5917.378675239038;9576.23390128802;9711.27165462579;9777.85333219988;9939.403635781877;10001.799964000826;9675.758106047406;9819.803603928025;9981.650642227487;10024.548895299875;10180.083792458714;6380.9361906380345;6916.861662766616;7091.82270443243;7111.751088711871;7176.246494118042;8206.83586328282;8314.792130196793;8318.209770071833;8396.412251142361;8403.41176117666;6352.174217351715;6421.8073457796345;7370.152432470334;7453.888191677238;7455.291747283999;6410.967945160244;7221.241769614115;7300.087489063867;7352.301531354513;7380.114386273682;7015.193252775996;7125.144982601913;7141.750038748619;7167.964160179165;7169.283071692789;4301.290287102593;4846.975765121152;4959.578435832995;4983.451820299837;5074.797008119634;5008.724520151427;5037.924431133462;5121.07820592297;5169.819056333161;5237.450067742832 10:04:42 INFO - PROCESS | 1672 | |4;prototype.html;325.55724215067505;330.64125423915067;332.7354752971388;337.14365511600533;342.34441045398063;6731.192256929169;6794.286599907006;6849.794506164817;6857.485688573352;6870.793876183722;6725.092112564802;6756.966215168917;6763.9661801691045;6764.898526522106;6765.255821859604;4793.5925446337105;4861.027794441108;5089.51649593288;5095.898082038357;5097.490250974892;5010.7995680172935;5061.44324124346;5076.690106001779;5084.771185296652;5098.439171691111;5005.699658020512;5011.473795251501;5023.497650234984;5033.068882256776;5040.59675225981;5017.673851199678;5031.924521132186;5032.188412171616;5039.697618142924;5055.4186268579215;4308.482982042144;4313.676474264443;4316.115196384733;4317.028668549583;4321.783910804447;6228.283747369065;6553.3446655334565;6557.639329836857;6569.178852643419;6576.605403675795;5781.421857814227;5810.302763668386;5824.417558244142;5827.242458480413;5855.238818953551;7227.638618069075;7241.203467618558;7256.7460138894885;7281.381082607984;7285.0165227694115;4079.6736261099336;4621.7920193591035;4636.211843986531;4639.6752227344095;4640.257558790611;7451.478396512192;7516.887246691304;7632.160462349138;7714.07431108265;7731.299114194868;4722.598579120745;4726.71639701619;4730.52694730531;4734.100520901086;4742.73914934675;4767.261074533453;4806.182948898696;4810.518948105231;4821.5178482152205;4847.75761211938;4669.575779387252;5339.145736682112;5791.015527360359;5799.130130480462;5808.680522571299;7298.4891057625455;7310.378617817439;7341.862011388243;7344.714674931865;7349.522281051714;1643.3755173034172;1668.3410053028986;1671.3147609480054;1674.2884274183666;1677.8238284980146;1019.9694009179736;1052.9312747561232;1064.1539975719322;1068.8984546468073;1069.5454431866426;5486.725663716798;5524.613277070566;6509.153810004669;6590.901136482957;6748.358905903931;6194.473469755031;7210.639468026578;7378.96310518444;7427.62861856905;7497.137829149718;6832.350926662059;6852.794416167523;6866.622335771481;6867;6880.8967865482055;3988.282109220368;4022.1352409232154;4024.577419370924;4037.1925614876554;4040.2323558523785;7998.800029999296;8002.639881205359;8038.758837234892;8039.397045221632;8082.838343233102;7504.212057733859;7784.455088143775;7790.610469476504;7873.897654328399;7925.484843485155;7036.663034023519;7385.187629360658;7982.121966583672;8014.198580142056;8068.9596552016865;6416.262129855127;7356.44826638015;7582.848343033219;7619.314261716472;7651.846963060596;7459.664315105725;7477.027986361738;7488.326050655467;7502.812429689301;7507.174210836913;7651.3496352810635;7950.761477155695;7987.281144697005;8003.719869804529;8009.99875015623;4681.438227412732;4715.929261061156;4731.479537250831;4741.596964257938;4750.358701575243;7506.774796756106;7522.134954480086;7533.849323013618;7540.509866858637;7563.432742544222;7267.200607933018;7269.945857850687;7276.854462910818;7297.087864016998;7299.452541059548;6992.475564332797;7300.853982920418;7314.634268286671;7322.96338518304;7341.192468828318;6367.586106903036;6657.63518478701;7072.681729322296;7159.8568028640175;7161.928380716126;7307.086614173229;7325.523840950321;7337.0828646419195;7342;7356.742514011984;5492.5605951524585;6950.687219075255;7022.894656580258;7139.571625702474;7146.678399471933 10:04:42 INFO - PROCESS | 1672 | |5;yui.html;677.5189615373085;702.280162833096;743.6133210730417;744.4007573903006;778.1751343575809;12675.366231688413;14370.568882933516;14393.71212575749;14478.42086316546;14539.854601453982;13489.73020539591;13506.18962862229;13534.932325338385;13543.051986360959;15383.692326153498;921.9953900230506;950.7052813627797;953.7091187187905;958.1041725986225;959.7648576098866;920.5995392004467;931.1898648176096;934.2712684106408;939.4410325856139;940.8071345374182;938.85917112433;994.4083270454068;996.7807082441851;998.7103739915416;1003.3628645809927;933.5239028095687;960.8030353777493;962.6678795815433;988.8516722491612;1008.1581879130935;699.1330749870175;701.8701540215026;710.733474946895;742.8365759532894;749.35181068376;12475.688107797287;14897.77653335201;14997.625059373493;15103.5468935931;15213.163276019815;12310.630681079581;12362.505499779998;12460.75078498434;12558;12591.181573197713;8248;10127.816668408093;10218.437985910772;10226.642067527675;10398.584056637725;3461.549998500204;3473.4442489201606;3476.540558811413;3494.755367124302;3572.214112895159;60851.695741521005;80167.79748303721;81687.18312816856;82688;83128;8208.46644968075;8952;9309.487978161265;9477.336586439022;10175;20885.582288354148;20950.47623809417;21141.365759027252;21164.36506904795;21188.894055529625;7342.48602597813;7456.40348772097;7493.775186744407;7542.886856697154;7812.617151768765;3862.2854771867296;4004.01901534126;4061.431399604058;4113.835446582163;4122.793860306972;3585.032041348821;3666.2850744105176;3667.211549516867;3667.7249206309098;3669.321175582526;670.2292363781593;672.3075232510522;676.2594958520413;681.3969636871404;682.8770821252123;532.858792420009;588.5027152056506;591.6716222496469;602.8613418913675;607.2470137030139;6134.754609815646;6708.530402871752;8137.145599712064;8225.588720563948;8399.748007559783;479.9856004319876;592.6799528254694;599.9040153575494;608.7534548507862;650.3723906430317;3373.308471763283;3825.770453772727;3888.7666739995125;3893.1629699614155;4052.8378864845076;92756.60865087029;94222;95255;96110;96275;78171.60914195393;79457;79892.60053699695;80161.19838801654;80601;67612;68366;69142;69693.65153174303;70670;8288.50268983863;8307.667693292442;8581.528015959182;8595.65617375298;8797.208251257418;48372.274415884494;49456;49613.12686856349;50065.74967125141;50424.747876260386;66165;67404;73935.63032184805;74965.62517187379;78289.2171078282;11472.770544588895;11489.310641361544;11532.538698452303;11787.057035437316;12065.638030858738;11902.583409580624;11953.701157471132;12093.879061209274;12111.454984525893;12592.685182870502;11194.888051119711;11282.266652667551;11482.253653512495;11979.04167666602;12076.275423474619;11389.088872889983;11609.129315301218;11617.244879082833;11640;11644.76710465769;9689.951550242204;9715;11226.60706875288;11253.831192532283;11532.019778318936;9468.849840255536;9613.567389467631;9659.46872921996;9900.752481188028;9960.601575936882;9843.557039933077;9880.752981175528;9985.650502232387;9985.850212246676;10006.299559030798 10:04:42 INFO - PROCESS | 1672 | __end_tp_report 10:04:42 INFO - PROCESS | 1672 | __start_cc_report 10:04:42 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,431 10:04:42 INFO - PROCESS | 1672 | __end_cc_report 10:04:42 INFO - PROCESS | 1672 | __startTimestamp1464368682544__endTimestamp 10:04:42 INFO - PROCESS | 1672 | 10:04:42 INFO - PROCESS | 1672 | ------- Summary: start ------- 10:04:42 INFO - PROCESS | 1672 | Number of tests: 6 10:04:42 INFO - PROCESS | 1672 | 10:04:42 INFO - PROCESS | 1672 | [#0] dojo.html Cycles:165 Average:5683.73 Median:5848.85 stddev:639.96 (10.9%) stddev-sans-first:598.22 10:04:42 INFO - PROCESS | 1672 | Values: 2720.7 3426.6 3468.7 3487.7 3499.3 5652.1 5661.9 5664.8 5676.0 5729.0 5650.7 5698.7 5701.5 5713.8 5723.0 5177.0 5195.9 5227.1 5260.7 5308.4 5150.7 5208.0 5292.3 5341.1 5349.2 5240.2 5297.5 5308.4 5322.0 5341.0 5254.1 5272.0 5284.6 5286.6 5335.7 5017.5 5059.3 5186.4 5192.4 5249.6 4529.5 5541.8 5603.5 5617.2 5633.6 5308.0 6201.9 6212.5 6226.0 6274.0 6133.5 6156.0 6164.4 6245.0 6245.1 5797.6 5799.9 5801.6 5805.8 5817.7 5320.5 6259.6 6285.6 6288.3 6302.5 5049.9 5215.6 5889.9 5925.8 6146.4 6236.8 6245.7 6248.2 6257.2 6265.2 5049.9 6150.5 6217.8 6221.4 6227.7 6079.8 6141.8 6167.6 6232.8 6237.1 4861.0 4910.2 4918.7 4928.9 4950.8 4361.1 4449.2 4451.6 4454.8 4470.0 5554.8 5679.3 5713.1 5719.1 5790.8 6150.2 6226.4 6229.1 6231.8 6233.1 5769.5 5777.7 5787.3 5803.8 5817.0 5270.3 5328.6 5345.8 5394.6 5403.2 5848.9 6137.9 6247.0 6275.2 6306.4 5955.8 6188.6 6239.6 6254.9 6272.8 5269.9 5962.3 6198.5 6325.9 6347.6 6151.7 6194.2 6194.9 6195.3 6215.8 5865.7 6236.7 6246.5 6248.2 6249.5 5933.7 6062.7 6138.5 6150.2 6154.2 6062.9 6063.5 6092.0 6094.2 6097.1 5893.3 5923.1 5933.0 5985.1 5998.0 5949.2 5994.7 6082.1 6087.4 6103.3 4969.5 5969.7 6015.7 6017.6 6022.6 10:04:42 INFO - PROCESS | 1672 | 10:04:42 INFO - PROCESS | 1672 | [#1] ext.html Cycles:180 Average:64058.17 Median:37342.85 stddev:142172.75 (380.7%) stddev-sans-first:142491.51 10:04:42 INFO - PROCESS | 1672 | Values: 507.1 507.4 565.3 586.0 611.4 38144.8 38899.0 39061.8 39096.6 39188.0 40137.0 40801.4 40802.6 40911.0 40943.2 9462.4 9637.4 9689.4 9692.0 9718.8 7431.0 9064.8 9473.1 9670.6 9693.4 9519.6 9666.3 9711.6 9712.1 9717.5 8339.8 9529.7 9593.2 9728.4 9731.9 8163.8 8167.6 9661.4 9661.4 9670.7 38919.4 39004.8 39196.8 39297.8 39391.8 2504.8 2885.6 3066.8 3225.4 3268.1 26184.3 26196.2 26367.7 27250.5 27823.2 2676.2 2694.2 2729.6 2732.2 2744.6 367392.0 368498.5 369451.2 371670.0 375429.0 34989.5 35216.5 35222.0 36175.0 37054.0 37030.0 37164.3 37192.0 37325.1 37360.6 15731.8 15783.8 15893.4 15960.0 16000.8 13300.8 13317.4 13345.3 13380.0 13391.1 8269.6 8283.8 8309.8 8381.2 8438.5 2976.9 3405.7 3429.4 3451.9 3504.2 916.2 937.0 993.6 1004.0 1058.7 27434.5 29102.0 29136.4 30112.1 30196.5 906.1 1111.9 1150.7 1158.6 1251.7 14152.9 15425.6 15501.8 15693.8 15930.0 586278.1 593212.0 621822.0 626291.0 631329.0 59323.7 60201.4 60968.7 61357.7 61804.0 1500.5 1512.7 1512.8 1527.6 1586.7 29040.7 30069.4 30093.2 30502.0 30582.1 52347.0 52627.5 53136.5 53141.7 53376.7 570815.0 575952.0 579052.0 579278.0 581235.0 26718.1 26863.6 27219.7 27408.6 42854.4 43406.0 43654.1 44242.8 44280.0 44923.6 35900.5 36375.5 37149.9 37866.4 38234.0 36572.8 37287.4 37299.6 37386.4 37642.2 32634.3 36695.8 37773.8 38280.0 38812.4 31280.8 32988.0 33108.8 33265.2 33446.7 22260.0 22395.7 24785.8 24939.8 25110.5 10:04:42 INFO - PROCESS | 1672 | 10:04:42 INFO - PROCESS | 1672 | [#2] jquery.html Cycles:180 Average:35111.81 Median:20585.35 stddev:102992.57 (500.3%) stddev-sans-first:103248.46 10:04:42 INFO - PROCESS | 1672 | Values: 373.4 380.7 403.4 404.5 405.4 15144.8 15496.8 19878.6 20518.8 20529.5 16495.0 20320.1 20615.7 20617.0 21003.7 5889.8 6071.4 6120.2 6131.7 6156.2 6167.9 6863.0 6865.7 6879.1 6898.3 5677.8 6534.1 6685.1 6747.2 6797.0 6686.9 6720.3 6728.2 6753.2 6759.9 5690.7 5861.4 5946.3 6034.2 6034.5 13497.3 13727.1 13783.4 14243.5 26211.0 1979.8 2216.6 2230.4 2366.1 2784.9 9716.9 9795.1 10061.9 10359.3 10408.1 1747.6 1795.6 1805.4 1826.6 1843.2 105123.5 112137.9 112723.0 124643.9 126399.4 10411.4 11660.7 11730.5 11892.6 12225.4 15347.1 15383.0 15387.8 15414.5 15432.5 8808.3 9102.9 9123.7 9136.4 9190.9 9122.6 9151.5 9153.4 9159.6 9192.1 4745.0 4785.6 4853.4 4873.3 4890.5 1633.6 1994.3 2044.7 2052.5 2054.4 836.7 974.9 993.0 1047.3 1057.8 17738.9 19611.4 20163.8 20555.0 21022.0 883.0 946.6 1002.7 1006.5 1043.9 10830.8 11109.0 11145.9 11165.7 11301.4 523444.0 617944.0 628919.0 653047.0 657181.0 125664.0 127327.0 130591.0 132316.0 133795.0 1025.0 1039.8 1053.9 1063.0 1063.4 18226.0 18622.3 18854.8 18866.4 19093.0 49913.5 51865.7 51951.7 52989.5 53786.7 66730.0 66748.0 77644.2 78528.2 80270.6 7834.4 8079.5 8232.3 8274.2 8346.5 8915.7 11319.7 12213.6 12254.4 12355.7 10529.3 10636.4 10767.1 10837.9 11007.2 10247.6 10502.0 10555.8 10685.1 10735.8 10707.3 10854.5 10886.4 10914.0 11383.0 8053.2 8106.0 8140.6 8397.3 8422.0 9053.9 9802.2 10176.0 10537.9 10691.5 10:04:42 INFO - PROCESS | 1672 | 10:04:42 INFO - PROCESS | 1672 | [#3] mootools.html Cycles:180 Average:5700.94 Median:5192.62 stddev:3183.11 (61.3%) stddev-sans-first:3176.34 10:04:42 INFO - PROCESS | 1672 | Values: 1495.4 1553.2 1830.5 1954.8 1982.0 7863.3 8542.0 8576.7 8667.3 8696.7 8805.6 8857.5 8937.2 8950.2 9033.9 2412.0 2421.2 2423.4 2440.5 2474.9 2332.7 2441.5 2447.7 2489.2 2491.1 2173.6 2298.7 2457.2 2477.3 2483.0 2428.3 2449.3 2457.4 2459.3 2538.8 2215.9 2335.8 2468.1 2490.2 2495.0 9002.5 9009.9 9014.5 9047.7 9061.5 5305.5 5471.0 5492.9 5500.7 5514.3 3417.8 3453.9 3464.6 3488.4 3502.0 931.9 1003.5 1042.3 1059.9 1076.4 11483.6 11595.5 11679.2 11758.2 11790.9 4562.4 4604.5 4616.4 4632.7 4652.5 9250.6 9331.6 9338.7 9350.9 9354.6 5215.4 5253.6 5304.5 5344.0 5376.1 4409.9 4824.0 4892.1 4949.0 4993.9 3851.2 3897.0 3910.1 3955.5 3961.1 1151.5 1222.1 1230.4 1237.4 1246.6 758.7 866.5 871.5 934.3 943.6 5888.9 5904.6 5906.5 6008.4 6017.0 876.8 910.5 932.5 935.2 936.1 3877.6 3935.7 3938.0 3939.2 4031.2 10597.5 11068.3 11121.7 11128.1 11173.9 10847.8 10868.9 10926.3 10946.7 10962.0 8161.0 8454.7 8559.9 8579.5 8598.6 5767.4 5832.3 5854.6 5868.8 5917.4 9576.2 9711.3 9777.9 9939.4 10001.8 9675.8 9819.8 9981.7 10024.5 10180.1 6380.9 6916.9 7091.8 7111.8 7176.2 8206.8 8314.8 8318.2 8396.4 8403.4 6352.2 6421.8 7370.2 7453.9 7455.3 6411.0 7221.2 7300.1 7352.3 7380.1 7015.2 7125.1 7141.8 7168.0 7169.3 4301.3 4847.0 4959.6 4983.5 5074.8 5008.7 5037.9 5121.1 5169.8 5237.5 10:04:42 INFO - PROCESS | 1672 | 10:04:42 INFO - PROCESS | 1672 | [#4] prototype.html Cycles:180 Average:5925.46 Median:6739.78 stddev:1909.99 (28.3%) stddev-sans-first:1868.53 10:04:42 INFO - PROCESS | 1672 | Values: 325.6 330.6 332.7 337.1 342.3 6731.2 6794.3 6849.8 6857.5 6870.8 6725.1 6757.0 6764.0 6764.9 6765.3 4793.6 4861.0 5089.5 5095.9 5097.5 5010.8 5061.4 5076.7 5084.8 5098.4 5005.7 5011.5 5023.5 5033.1 5040.6 5017.7 5031.9 5032.2 5039.7 5055.4 4308.5 4313.7 4316.1 4317.0 4321.8 6228.3 6553.3 6557.6 6569.2 6576.6 5781.4 5810.3 5824.4 5827.2 5855.2 7227.6 7241.2 7256.7 7281.4 7285.0 4079.7 4621.8 4636.2 4639.7 4640.3 7451.5 7516.9 7632.2 7714.1 7731.3 4722.6 4726.7 4730.5 4734.1 4742.7 4767.3 4806.2 4810.5 4821.5 4847.8 4669.6 5339.1 5791.0 5799.1 5808.7 7298.5 7310.4 7341.9 7344.7 7349.5 1643.4 1668.3 1671.3 1674.3 1677.8 1020.0 1052.9 1064.2 1068.9 1069.5 5486.7 5524.6 6509.2 6590.9 6748.4 6194.5 7210.6 7379.0 7427.6 7497.1 6832.4 6852.8 6866.6 6867.0 6880.9 3988.3 4022.1 4024.6 4037.2 4040.2 7998.8 8002.6 8038.8 8039.4 8082.8 7504.2 7784.5 7790.6 7873.9 7925.5 7036.7 7385.2 7982.1 8014.2 8069.0 6416.3 7356.4 7582.8 7619.3 7651.8 7459.7 7477.0 7488.3 7502.8 7507.2 7651.3 7950.8 7987.3 8003.7 8010.0 4681.4 4715.9 4731.5 4741.6 4750.4 7506.8 7522.1 7533.8 7540.5 7563.4 7267.2 7269.9 7276.9 7297.1 7299.5 6992.5 7300.9 7314.6 7323.0 7341.2 6367.6 6657.6 7072.7 7159.9 7161.9 7307.1 7325.5 7337.1 7342.0 7356.7 5492.6 6950.7 7022.9 7139.6 7146.7 10:04:42 INFO - PROCESS | 1672 | 10:04:42 INFO - PROCESS | 1672 | [#5] yui.html Cycles:180 Average:18368.00 Median:33493.23 stddev:26042.96 (77.8%) stddev-sans-first:26082.14 10:04:42 INFO - PROCESS | 1672 | Values: 677.5 702.3 743.6 744.4 778.2 12675.4 14370.6 14393.7 14478.4 14539.9 13489.7 13506.2 13534.9 13543.1 15383.7 922.0 950.7 953.7 958.1 959.8 920.6 931.2 934.3 939.4 940.8 938.9 994.4 996.8 998.7 1003.4 933.5 960.8 962.7 988.9 1008.2 699.1 701.9 710.7 742.8 749.4 12475.7 14897.8 14997.6 15103.5 15213.2 12310.6 12362.5 12460.8 12558.0 12591.2 8248.0 10127.8 10218.4 10226.6 10398.6 3461.5 3473.4 3476.5 3494.8 3572.2 60851.7 80167.8 81687.2 82688.0 83128.0 8208.5 8952.0 9309.5 9477.3 10175.0 20885.6 20950.5 21141.4 21164.4 21188.9 7342.5 7456.4 7493.8 7542.9 7812.6 3862.3 4004.0 4061.4 4113.8 4122.8 3585.0 3666.3 3667.2 3667.7 3669.3 670.2 672.3 676.3 681.4 682.9 532.9 588.5 591.7 602.9 607.2 6134.8 6708.5 8137.1 8225.6 8399.7 480.0 592.7 599.9 608.8 650.4 3373.3 3825.8 3888.8 3893.2 4052.8 92756.6 94222.0 95255.0 96110.0 96275.0 78171.6 79457.0 79892.6 80161.2 80601.0 67612.0 68366.0 69142.0 69693.7 70670.0 8288.5 8307.7 8581.5 8595.7 8797.2 48372.3 49456.0 49613.1 50065.7 50424.7 66165.0 67404.0 73935.6 74965.6 78289.2 11472.8 11489.3 11532.5 11787.1 12065.6 11902.6 11953.7 12093.9 12111.5 12592.7 11194.9 11282.3 11482.3 11979.0 12076.3 11389.1 11609.1 11617.2 11640.0 11644.8 9690.0 9715.0 11226.6 11253.8 11532.0 9468.8 9613.6 9659.5 9900.8 9960.6 9843.6 9880.8 9985.7 9985.9 10006.3 10:04:42 INFO - PROCESS | 1672 | -------- Summary: end -------- 10:04:42 INFO - PROCESS | 1672 | 10:04:43 INFO - TEST-INFO | 1672: exit 0 10:04:43 INFO - TEST-OK | dromaeo_css | took 1110707ms 10:04:43 INFO - TEST-START | kraken 10:04:43 INFO - operating with platform_type : mac_ 10:04:43 INFO - Initialising browser for kraken test... 10:04:43 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmp7Vu6Wx/profile http://localhost:49277/getInfo.html) 10:04:45 INFO - PROCESS | 1681 | __metrics Screen width/height:1600/1200 10:04:45 INFO - PROCESS | 1681 | colorDepth:24 10:04:45 INFO - PROCESS | 1681 | Browser inner width/height: 1024/689 10:04:45 INFO - PROCESS | 1681 | __metrics 10:04:45 ERROR - PROCESS | 1681 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 10:04:46 INFO - TEST-INFO | 1681: exit 0 10:04:46 INFO - Browser initialized. 10:04:46 INFO - Running cycle 1/1 for kraken test... 10:04:46 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmp7Vu6Wx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:04:58 INFO - PROCESS | 1684 | RSS: Main: 221769728 10:04:58 INFO - PROCESS | 1684 | 10:05:01 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 10:05:02 INFO - PROCESS | 1684 | RSS: Main: 233308160 10:05:02 INFO - PROCESS | 1684 | 10:05:04 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 10:05:05 INFO - PROCESS | 1684 | RSS: Main: 242262016 10:05:05 INFO - PROCESS | 1684 | 10:05:08 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 10:05:09 INFO - PROCESS | 1684 | RSS: Main: 246599680 10:05:09 INFO - PROCESS | 1684 | 10:05:11 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 10:05:11 INFO - PROCESS | 1684 | RSS: Main: 247283712 10:05:11 INFO - PROCESS | 1684 | 10:05:14 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:05:14 INFO - PROCESS | 1684 | RSS: Main: 248242176 10:05:14 INFO - PROCESS | 1684 | 10:05:17 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 10:05:18 INFO - PROCESS | 1684 | RSS: Main: 248107008 10:05:18 INFO - PROCESS | 1684 | 10:05:21 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 10:05:21 INFO - PROCESS | 1684 | RSS: Main: 248115200 10:05:21 INFO - PROCESS | 1684 | 10:05:24 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 10:05:25 INFO - PROCESS | 1684 | RSS: Main: 248156160 10:05:25 INFO - PROCESS | 1684 | 10:05:26 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:05:27 INFO - PROCESS | 1684 | RSS: Main: 247865344 10:05:27 INFO - PROCESS | 1684 | 10:05:29 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:05:29 INFO - PROCESS | 1684 | RSS: Main: 247877632 10:05:29 INFO - PROCESS | 1684 | 10:05:32 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:05:32 INFO - PROCESS | 1684 | RSS: Main: 248139776 10:05:32 INFO - PROCESS | 1684 | 10:05:35 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:05:36 INFO - PROCESS | 1684 | RSS: Main: 248283136 10:05:36 INFO - PROCESS | 1684 | 10:05:39 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:05:39 INFO - PROCESS | 1684 | RSS: Main: 248238080 10:05:39 INFO - PROCESS | 1684 | 10:05:41 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 10:05:41 INFO - PROCESS | 1684 | RSS: Main: 250044416 10:05:41 INFO - PROCESS | 1684 | 10:05:41 INFO - PROCESS | 1684 | __start_tp_report 10:05:41 INFO - PROCESS | 1684 | _x_x_mozilla_page_load 10:05:41 INFO - PROCESS | 1684 | _x_x_mozilla_page_load_details 10:05:41 INFO - PROCESS | 1684 | |i|pagename|runs| 10:05:41 INFO - PROCESS | 1684 | |0;ai-astar;115;121;110;117;119;113;104;118;118;117 10:05:41 INFO - PROCESS | 1684 | |1;audio-beat-detection;151;128;159;154;125;139;123;154;156;138 10:05:41 INFO - PROCESS | 1684 | |2;audio-dft;200;200;199;229;228;199;198;202;198;196 10:05:41 INFO - PROCESS | 1684 | |3;audio-fft;76;78;71;147;57;60;56;62;134;57 10:05:41 INFO - PROCESS | 1684 | |4;audio-oscillator;81;81;126;77;81;87;124;100;114;109 10:05:41 INFO - PROCESS | 1684 | |5;imaging-gaussian-blur;89;86;133;129;88;86;89;86;90;86 10:05:41 INFO - PROCESS | 1684 | |6;imaging-darkroom;95;96;152;94;110;116;97;94;95;95 10:05:41 INFO - PROCESS | 1684 | |7;imaging-desaturate;92;93;90;92;93;92;91;92;96;90 10:05:41 INFO - PROCESS | 1684 | |8;json-parse-financial;61;64;67;67;65;65;65;85;64;61 10:05:41 INFO - PROCESS | 1684 | |9;json-stringify-tinderbox;63;68;66;70;71;84;71;63;69;72 10:05:41 INFO - PROCESS | 1684 | |10;stanford-crypto-aes;83;80;83;82;82;79;80;81;95;108 10:05:41 INFO - PROCESS | 1684 | |11;stanford-crypto-ccm;153;137;143;140;154;143;151;142;175;172 10:05:41 INFO - PROCESS | 1684 | |12;stanford-crypto-pbkdf2;188;221;180;173;189;160;217;167;164;184 10:05:41 INFO - PROCESS | 1684 | |13;stanford-crypto-sha256-iterative;68;87;79;65;85;96;61;63;69;97 10:05:41 INFO - PROCESS | 1684 | __end_tp_report 10:05:41 INFO - PROCESS | 1684 | __start_cc_report 10:05:41 INFO - PROCESS | 1684 | _x_x_mozilla_cycle_collect,1106 10:05:41 INFO - PROCESS | 1684 | __end_cc_report 10:05:41 INFO - PROCESS | 1684 | __startTimestamp1464368741950__endTimestamp 10:05:41 INFO - PROCESS | 1684 | 10:05:41 INFO - PROCESS | 1684 | ------- Summary: start ------- 10:05:41 INFO - PROCESS | 1684 | Number of tests: 14 10:05:41 INFO - PROCESS | 1684 | 10:05:41 INFO - PROCESS | 1684 | [#0] ai-astar Cycles:10 Average:115.20 Median:117.50 stddev:5.03 (4.3%) stddev-sans-first:5.33 10:05:41 INFO - PROCESS | 1684 | Values: 115.0 121.0 110.0 117.0 119.0 113.0 104.0 118.0 118.0 117.0 10:05:41 INFO - PROCESS | 1684 | 10:05:41 INFO - PROCESS | 1684 | [#1] audio-beat-detection Cycles:10 Average:142.70 Median:152.50 stddev:13.82 (9.1%) stddev-sans-first:14.33 10:05:41 INFO - PROCESS | 1684 | Values: 151.0 128.0 159.0 154.0 125.0 139.0 123.0 154.0 156.0 138.0 10:05:41 INFO - PROCESS | 1684 | 10:05:41 INFO - PROCESS | 1684 | [#2] audio-dft Cycles:10 Average:204.90 Median:200.00 stddev:12.54 (6.3%) stddev-sans-first:13.17 10:05:41 INFO - PROCESS | 1684 | Values: 200.0 200.0 199.0 229.0 228.0 199.0 198.0 202.0 198.0 196.0 10:05:41 INFO - PROCESS | 1684 | 10:05:41 INFO - PROCESS | 1684 | [#3] audio-fft Cycles:10 Average:79.80 Median:61.00 stddev:33.11 (54.3%) stddev-sans-first:35.08 10:05:41 INFO - PROCESS | 1684 | Values: 76.0 78.0 71.0 147.0 57.0 60.0 56.0 62.0 134.0 57.0 10:05:41 INFO - PROCESS | 1684 | 10:05:41 INFO - PROCESS | 1684 | [#4] audio-oscillator Cycles:10 Average:98.00 Median:79.00 stddev:19.06 (24.1%) stddev-sans-first:19.20 10:05:41 INFO - PROCESS | 1684 | Values: 81.0 81.0 126.0 77.0 81.0 87.0 124.0 100.0 114.0 109.0 10:05:41 INFO - PROCESS | 1684 | 10:05:41 INFO - PROCESS | 1684 | [#5] imaging-gaussian-blur Cycles:10 Average:96.20 Median:87.00 stddev:18.43 (21.2%) stddev-sans-first:19.36 10:05:41 INFO - PROCESS | 1684 | Values: 89.0 86.0 133.0 129.0 88.0 86.0 89.0 86.0 90.0 86.0 10:05:41 INFO - PROCESS | 1684 | 10:05:41 INFO - PROCESS | 1684 | [#6] imaging-darkroom Cycles:10 Average:104.40 Median:95.00 stddev:18.37 (19.3%) stddev-sans-first:19.17 10:05:41 INFO - PROCESS | 1684 | Values: 95.0 96.0 152.0 94.0 110.0 116.0 97.0 94.0 95.0 95.0 10:05:41 INFO - PROCESS | 1684 | 10:05:41 INFO - PROCESS | 1684 | [#7] imaging-desaturate Cycles:10 Average:92.10 Median:92.00 stddev:1.73 (1.9%) stddev-sans-first:1.83 10:05:41 INFO - PROCESS | 1684 | Values: 92.0 93.0 90.0 92.0 93.0 92.0 91.0 92.0 96.0 90.0 10:05:41 INFO - PROCESS | 1684 | 10:05:41 INFO - PROCESS | 1684 | [#8] json-parse-financial Cycles:10 Average:66.40 Median:65.00 stddev:6.85 (10.5%) stddev-sans-first:6.98 10:05:41 INFO - PROCESS | 1684 | Values: 61.0 64.0 67.0 67.0 65.0 65.0 65.0 85.0 64.0 61.0 10:05:41 INFO - PROCESS | 1684 | 10:05:41 INFO - PROCESS | 1684 | [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:70.50 stddev:5.96 (8.5%) stddev-sans-first:5.81 10:05:41 INFO - PROCESS | 1684 | Values: 63.0 68.0 66.0 70.0 71.0 84.0 71.0 63.0 69.0 72.0 10:05:41 INFO - PROCESS | 1684 | 10:05:41 INFO - PROCESS | 1684 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:82.00 stddev:9.17 (11.2%) stddev-sans-first:9.68 10:05:41 INFO - PROCESS | 1684 | Values: 83.0 80.0 83.0 82.0 82.0 79.0 80.0 81.0 95.0 108.0 10:05:41 INFO - PROCESS | 1684 | 10:05:41 INFO - PROCESS | 1684 | [#11] stanford-crypto-ccm Cycles:10 Average:151.00 Median:152.00 stddev:13.15 (8.7%) stddev-sans-first:13.93 10:05:41 INFO - PROCESS | 1684 | Values: 153.0 137.0 143.0 140.0 154.0 143.0 151.0 142.0 175.0 172.0 10:05:41 INFO - PROCESS | 1684 | 10:05:41 INFO - PROCESS | 1684 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.30 Median:186.00 stddev:20.82 (11.2%) stddev-sans-first:22.04 10:05:41 INFO - PROCESS | 1684 | Values: 188.0 221.0 180.0 173.0 189.0 160.0 217.0 167.0 164.0 184.0 10:05:41 INFO - PROCESS | 1684 | 10:05:41 INFO - PROCESS | 1684 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.00 Median:82.00 stddev:13.62 (16.6%) stddev-sans-first:14.05 10:05:41 INFO - PROCESS | 1684 | Values: 68.0 87.0 79.0 65.0 85.0 96.0 61.0 63.0 69.0 97.0 10:05:41 INFO - PROCESS | 1684 | -------- Summary: end -------- 10:05:41 INFO - PROCESS | 1684 | 10:05:42 INFO - TEST-INFO | 1684: exit 0 10:05:42 INFO - TEST-OK | kraken | took 59610ms 10:05:42 INFO - SUITE-END | took 1170s 10:05:43 INFO - Completed test suite (00:19:30) 10:05:43 INFO - javascript benchmark 10:05:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2720.6599175103115, 3426.6230714621397, 3468.7225021998242, 3487.7384196185276, 3499.3176330615524, 5652.123920792279, 5661.943380566193, 5664.773409063643, 5676.035074037413, 5729.0, 5650.74571644276, 5698.6865722385355, 5701.543876489871, 5713.771449142039, 5723.027085395483, 5177.0, 5195.896082078356, 5227.059129356714, 5260.710660913648, 5308.362996440433, 5150.690958542499, 5207.999999999981, 5292.338457692788, 5341.092014357569, 5349.170878513836, 5240.187770895517, 5297.523222909937, 5308.389535203443, 5322.0154271459905, 5341.0, 5254.054270231357, 5272.024675435037, 5284.5772338212855, 5286.550643195333, 5335.706536140511, 5017.473165317662, 5059.3169922060415, 5186.351706036746, 5192.402873669538, 5249.632525723201, 4529.501754806968, 5541.750621222055, 5603.495685388335, 5617.185508101344, 5633.57748168889, 5308.0, 6201.937980620181, 6212.502999760053, 6225.968870155621, 6274.027525733519, 6133.478654314388, 6155.969220153916, 6164.3835616438, 6245.000799872044, 6245.094461303131, 5797.565182611237, 5799.855003624901, 5801.564882633777, 5805.82582522525, 5817.7091145442555, 5320.52115309624, 6259.624422534663, 6285.622862628165, 6288.339724328971, 6302.4958003359625, 5049.873753156127, 5215.608829337739, 5889.852753681193, 5925.822225333247, 6146.416090471398, 6236.781712640036, 6245.718942647592, 6248.187735594343, 6257.155284036688, 6265.248170219603, 5049.924251136236, 6150.50795936324, 6217.782377616762, 6221.377862213742, 6227.657478838707, 6079.787207447718, 6141.815745527641, 6167.599106058092, 6232.844178895564, 6237.09562113491, 4860.9513904861205, 4910.238912968495, 4918.704877707278, 4928.901421971541, 4950.8267210647455, 4361.127774445124, 4449.199144154083, 4451.599356057905, 4454.821807127679, 4470.0, 5554.750036248298, 5679.261695979496, 5713.143028545752, 5719.142128680648, 5790.797322093791, 6150.231221097363, 6226.377362263828, 6229.065640153923, 6231.781887633911, 6233.1273621693, 5769.509591684669, 5777.653340799566, 5787.305523337226, 5803.7968671096305, 5816.970915145397, 5270.288511050959, 5328.626996110313, 5345.812896548602, 5394.568434525307, 5403.2165336026055, 5848.853778655568, 6137.938620613826, 6247.0, 6275.152854364694, 6306.432421082125, 5955.791547295824, 6188.566800324114, 6239.594426362272, 6254.937450625617, 6272.8118156455375, 5269.89460210786, 5962.314333851663, 6198.535109866688, 6325.905111423241, 6347.587406818542, 6151.661658608819, 6194.225721784777, 6194.907076393948, 6195.318514963441, 6215.81352559424, 5865.677387743715, 6236.688165591793, 6246.5002799775, 6248.156498872776, 6249.468795152281, 5933.732982015733, 6062.666553339609, 6138.480361557057, 6150.169727086786, 6154.230721159855, 6062.939370606237, 6063.484603808549, 6092.0, 6094.2077529920825, 6097.146399503984, 5893.263342082239, 5923.08192230205, 5932.991391463548, 5985.102234664835, 5998.0, 5949.196858424058, 5994.700264986646, 6082.118092876596, 6087.391260873877, 6103.267607887082, 4969.47820478859, 5969.70151492415, 6015.72929218195, 6017.638941663514, 6022.578419510767], "value": 5645.101998599881, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.11508417810916, 507.4342108548969, 565.2905603467648, 585.9979435165866, 611.4038812158146, 38144.80927595365, 38899.0, 39061.82814515562, 39096.609033909655, 39188.0, 40137.0, 40801.387979180334, 40802.59197408025, 40910.977225569375, 40943.18113637733, 9462.384944978572, 9637.373570717917, 9689.418634881895, 9692.030796920322, 9718.757031074245, 7430.962845185766, 9064.818703625924, 9473.147416732492, 9670.564824582876, 9693.3699309545, 9519.619215231382, 9666.275029372826, 9711.611535538605, 9712.125908668218, 9717.46553939533, 8339.791505212388, 9529.666461673844, 9593.232541396672, 9728.41629502225, 9731.902680973171, 8163.755087347389, 8167.550784706838, 9661.420314781135, 9661.420314781135, 9670.70987870365, 38919.41620875689, 39004.80497597494, 39196.80401598002, 39297.80351098226, 39391.8030409849, 2504.8371855829314, 2885.5960165576835, 3066.84665766713, 3225.4355487789776, 3268.1176082457605, 26184.34539136518, 26196.21411357662, 26367.73632263672, 27250.45499090007, 27823.165305040882, 2676.2372723773633, 2694.1648089092446, 2729.56326987683, 2732.2076597786618, 2744.5745909384077, 367392.0, 368498.4725229124, 369451.1527442373, 371670.0, 375429.0, 34989.47515787265, 35216.471752923724, 35222.0, 36175.0, 37054.0, 37030.0, 37164.25671486609, 37192.0, 37325.06687332838, 37360.62639373626, 15731.764023539656, 15783.842161578466, 15893.443729469413, 15960.0, 16000.839991599934, 13300.800487992688, 13317.400716967759, 13345.332733363293, 13380.0, 13391.129576577492, 8269.62786674601, 8283.792905177419, 8309.750707478664, 8381.245687888118, 8438.53588052663, 2976.940461190764, 3405.6764607362757, 3429.4341433663167, 3451.861925523001, 3504.229069604683, 916.1845957098188, 936.9672061477816, 993.60752502761, 1004.0260946881514, 1058.6771034834296, 27434.45131097407, 29102.0, 29136.41727165487, 30112.091462656892, 30196.54705179424, 906.1391677906016, 1111.9388433636066, 1150.6950658075616, 1158.5539567266662, 1251.7371352016178, 14152.938529610321, 15425.614359640875, 15501.844981550266, 15693.764593531107, 15929.961554550642, 586278.1372186309, 593212.0, 621822.0, 626291.0, 631329.0, 59323.70338148282, 60201.397986020456, 60968.695156523936, 61357.69321153365, 61804.0, 1500.534834201401, 1512.6974605078808, 1512.7730840374031, 1527.5722797616686, 1586.6588683432885, 29040.709592904645, 30069.3986120272, 30093.247668808453, 30502.0, 30582.08253752391, 52346.95306093933, 52627.473725263786, 53136.468635313155, 53141.7342913283, 53376.73311633417, 570815.0, 575952.0, 579052.0, 579278.0, 581235.0, 26718.064867729536, 26863.597046043935, 27219.727802721718, 27408.588871167347, 42854.35718464288, 43406.0, 43654.126917462105, 44242.77878610586, 44280.0, 44923.550764491934, 35900.461493078146, 36375.45436818503, 37149.88550343494, 37866.43200351942, 38234.0, 36572.81713591415, 37287.44068839024, 37299.62700372961, 37386.43920341251, 37642.247155057295, 32634.34731305313, 36695.81652091722, 37773.81113094417, 38280.04299892525, 38812.41781373225, 31280.843595782786, 32988.0, 33108.834455827564, 33265.168370790925, 33446.66553334533, 22260.0, 22395.664065038713, 24785.752142478344, 24939.750602493743, 25110.49779004446], "value": 18163.984426479758, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.42492561455344, 380.69354169893245, 403.37275536540665, 404.45802624483196, 405.3656028315686, 15144.848551514482, 15496.845031549681, 19878.60242795143, 20518.794812051874, 20529.486762830937, 16495.0, 20320.085596148172, 20615.690764638504, 20617.0, 21003.684944725843, 5889.793857215009, 6071.3625069367745, 6120.20437343147, 6131.693415329238, 6156.230471191101, 6167.907481387782, 6863.039174515572, 6865.725370985155, 6879.140107486564, 6898.310168983112, 5677.772889084432, 6534.117894084286, 6685.097511835913, 6747.156605424322, 6796.966015169917, 6686.899696504556, 6720.3279672032895, 6728.226253980782, 6753.223379311393, 6759.898601520981, 5690.715464226813, 5861.443162899518, 5946.256717910262, 6034.1853849730605, 6034.547408944303, 13497.325133743274, 13727.107737997098, 13783.448662053506, 14243.501477448239, 26211.0, 1979.8416126709972, 2216.6342553478657, 2230.364346161336, 2366.077229880348, 2784.874680639376, 9716.854247186298, 9795.118439340422, 10061.89938100617, 10359.27485076045, 10408.063274305348, 1747.6417334446407, 1795.6049669072784, 1805.4132406967788, 1826.6164105537853, 1843.1521500109966, 105123.47438262837, 112137.87862121356, 112723.0, 124643.88390290225, 126399.36800316031, 10411.375317480975, 11660.650180494598, 11730.477947241385, 11892.643220703392, 12225.449854756558, 15347.07917524952, 15383.00010499314, 15387.769183462256, 15414.537563873102, 15432.537023889301, 8808.25129863956, 9102.90897091034, 9123.680671176477, 9136.406133601295, 9190.908090919005, 9122.635094596275, 9151.496667683208, 9153.405028673114, 9159.633614655473, 9192.080791920887, 4744.95255047452, 4785.641076919245, 4853.369062021915, 4873.31773551703, 4890.462049174588, 1633.55893908644, 1994.2621230144648, 2044.7220487195502, 2052.548439343342, 2054.3939537836372, 836.7489753074053, 974.936629119105, 993.0, 1047.3297089862494, 1057.7937302225992, 17738.91130544339, 19611.411657650293, 20163.798362016485, 20555.0, 21022.0, 883.0066175552504, 946.6210886142535, 1002.6691191906654, 1006.5269323417982, 1043.8538604595362, 10830.837537436944, 11109.008892385256, 11145.944270278596, 11165.665030049111, 11301.378424186585, 523444.0, 617944.0, 628919.0, 653047.0, 657181.0, 125664.0, 127327.0, 130591.0, 132316.0, 133795.0, 1024.9692509224735, 1039.7660526381655, 1053.8577292065627, 1063.0, 1063.361982810323, 18226.0, 18622.255109796, 18854.811451885307, 18866.434006979813, 19093.0, 49913.500864990885, 51865.7406712964, 51951.74024130006, 52989.470105299995, 53786.731066344415, 66730.0, 66748.0, 77644.22355776596, 78528.2147178521, 80270.5986470064, 7834.373250139861, 8079.474834135763, 8232.259096681328, 8274.213949674771, 8346.540940248102, 8915.73252802417, 11319.71700707456, 12213.572524961584, 12254.38728063611, 12355.69110772202, 10529.262951593315, 10636.361818290927, 10767.138628909512, 10837.945810270898, 11007.229493935347, 10247.641332553623, 10502.0, 10555.841662374916, 10685.145188385066, 10735.785284294427, 10707.250492465453, 10854.511546980248, 10886.401247931122, 10914.017738403265, 11383.032442242502, 8053.154418785943, 8106.0, 8140.59297035134, 8397.328213742763, 8422.0, 9053.909460905306, 9802.215822734022, 10176.0, 10537.9473102634, 10691.465426728477], "value": 9571.416803773993, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1495.446684726651, 1553.207863989365, 1830.4600142957827, 1954.794746551613, 1981.9801801981976, 7863.252990965863, 8542.0, 8576.69981550646, 8667.349948753854, 8696.652133914644, 8805.559722013906, 8857.512836793974, 8937.195652391283, 8950.194482496574, 9033.864492032624, 2411.987940060306, 2421.1767998880423, 2423.3699238198105, 2440.548498527778, 2474.925752227427, 2332.7084114485688, 2441.4872876695913, 2447.669564608782, 2489.2034548944343, 2491.0533997081084, 2173.597884391385, 2298.7241531016302, 2457.2259738182506, 2477.256822953107, 2483.0316176691103, 2428.3200703802963, 2449.326435230308, 2457.4347899983013, 2459.274514018351, 2538.834975726581, 2215.900284487202, 2335.8131349492, 2468.1114798672465, 2490.203134996802, 2495.0144692846225, 9002.504862232574, 9009.864852027225, 9014.504202268872, 9047.728568142966, 9061.456312621263, 5305.495977882095, 5471.0, 5492.890142197173, 5500.724963751796, 5514.338279406456, 3417.794932304045, 3453.9309213815836, 3464.5842498900292, 3488.4418493041244, 3501.9649803501893, 931.9003575780577, 1003.4731765823001, 1042.3016578892161, 1059.941703206331, 1076.4025965589094, 11483.598074067368, 11595.478203480863, 11679.182457227997, 11758.235714678518, 11790.882091179064, 4562.384078149407, 4604.470485894165, 4616.3537104805355, 4632.745199014086, 4652.487941419053, 9250.629974801068, 9331.580078896466, 9338.673146439842, 9350.90649093514, 9354.579043943038, 5215.426303106655, 5253.605979551549, 5304.549113325411, 5343.973280133574, 5376.086065368897, 4409.889752756207, 4823.962847987699, 4892.0705066037335, 4949.010197960422, 4993.926305844188, 3851.2490064437166, 3896.980515097406, 3910.1397692507603, 3955.4660120883314, 3961.1087505311075, 1151.5278735718482, 1222.1078612612841, 1230.4340003598265, 1237.4493350458959, 1246.5512415530402, 758.7154816943646, 866.5277423804042, 871.4509858788922, 934.3319526538558, 943.6461327002374, 5888.882222355614, 5904.586678932434, 5906.527477801766, 6008.369121242294, 6017.0, 876.8377850097752, 910.4901255297054, 932.4545141092536, 935.1863878425813, 936.0826390137703, 3877.6316249956208, 3935.74417662851, 3938.0, 3939.2318497892634, 4031.1534577739003, 10597.523111460003, 11068.28056176362, 11121.666350009513, 11128.109751219883, 11173.944130279458, 10847.783044339227, 10868.945655271673, 10926.344419334866, 10946.671599852018, 10962.013418792347, 8160.959195203986, 8454.661813527513, 8559.914400855912, 8579.485230886043, 8598.613062412207, 5767.365589785204, 5832.270966129234, 5854.590178687451, 5868.765249390148, 5917.378675239038, 9576.23390128802, 9711.27165462579, 9777.85333219988, 9939.403635781877, 10001.799964000826, 9675.758106047406, 9819.803603928025, 9981.650642227487, 10024.548895299875, 10180.083792458714, 6380.9361906380345, 6916.861662766616, 7091.82270443243, 7111.751088711871, 7176.246494118042, 8206.83586328282, 8314.792130196793, 8318.209770071833, 8396.412251142361, 8403.41176117666, 6352.174217351715, 6421.8073457796345, 7370.152432470334, 7453.888191677238, 7455.291747283999, 6410.967945160244, 7221.241769614115, 7300.087489063867, 7352.301531354513, 7380.114386273682, 7015.193252775996, 7125.144982601913, 7141.750038748619, 7167.964160179165, 7169.283071692789, 4301.290287102593, 4846.975765121152, 4959.578435832995, 4983.451820299837, 5074.797008119634, 5008.724520151427, 5037.924431133462, 5121.07820592297, 5169.819056333161, 5237.450067742832], "value": 4588.58465404573, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.55724215067505, 330.64125423915067, 332.7354752971388, 337.14365511600533, 342.34441045398063, 6731.192256929169, 6794.286599907006, 6849.794506164817, 6857.485688573352, 6870.793876183722, 6725.092112564802, 6756.966215168917, 6763.9661801691045, 6764.898526522106, 6765.255821859604, 4793.5925446337105, 4861.027794441108, 5089.51649593288, 5095.898082038357, 5097.490250974892, 5010.7995680172935, 5061.44324124346, 5076.690106001779, 5084.771185296652, 5098.439171691111, 5005.699658020512, 5011.473795251501, 5023.497650234984, 5033.068882256776, 5040.59675225981, 5017.673851199678, 5031.924521132186, 5032.188412171616, 5039.697618142924, 5055.4186268579215, 4308.482982042144, 4313.676474264443, 4316.115196384733, 4317.028668549583, 4321.783910804447, 6228.283747369065, 6553.3446655334565, 6557.639329836857, 6569.178852643419, 6576.605403675795, 5781.421857814227, 5810.302763668386, 5824.417558244142, 5827.242458480413, 5855.238818953551, 7227.638618069075, 7241.203467618558, 7256.7460138894885, 7281.381082607984, 7285.0165227694115, 4079.6736261099336, 4621.7920193591035, 4636.211843986531, 4639.6752227344095, 4640.257558790611, 7451.478396512192, 7516.887246691304, 7632.160462349138, 7714.07431108265, 7731.299114194868, 4722.598579120745, 4726.71639701619, 4730.52694730531, 4734.100520901086, 4742.73914934675, 4767.261074533453, 4806.182948898696, 4810.518948105231, 4821.5178482152205, 4847.75761211938, 4669.575779387252, 5339.145736682112, 5791.015527360359, 5799.130130480462, 5808.680522571299, 7298.4891057625455, 7310.378617817439, 7341.862011388243, 7344.714674931865, 7349.522281051714, 1643.3755173034172, 1668.3410053028986, 1671.3147609480054, 1674.2884274183666, 1677.8238284980146, 1019.9694009179736, 1052.9312747561232, 1064.1539975719322, 1068.8984546468073, 1069.5454431866426, 5486.725663716798, 5524.613277070566, 6509.153810004669, 6590.901136482957, 6748.358905903931, 6194.473469755031, 7210.639468026578, 7378.96310518444, 7427.62861856905, 7497.137829149718, 6832.350926662059, 6852.794416167523, 6866.622335771481, 6867.0, 6880.8967865482055, 3988.282109220368, 4022.1352409232154, 4024.577419370924, 4037.1925614876554, 4040.2323558523785, 7998.800029999296, 8002.639881205359, 8038.758837234892, 8039.397045221632, 8082.838343233102, 7504.212057733859, 7784.455088143775, 7790.610469476504, 7873.897654328399, 7925.484843485155, 7036.663034023519, 7385.187629360658, 7982.121966583672, 8014.198580142056, 8068.9596552016865, 6416.262129855127, 7356.44826638015, 7582.848343033219, 7619.314261716472, 7651.846963060596, 7459.664315105725, 7477.027986361738, 7488.326050655467, 7502.812429689301, 7507.174210836913, 7651.3496352810635, 7950.761477155695, 7987.281144697005, 8003.719869804529, 8009.99875015623, 4681.438227412732, 4715.929261061156, 4731.479537250831, 4741.596964257938, 4750.358701575243, 7506.774796756106, 7522.134954480086, 7533.849323013618, 7540.509866858637, 7563.432742544222, 7267.200607933018, 7269.945857850687, 7276.854462910818, 7297.087864016998, 7299.452541059548, 6992.475564332797, 7300.853982920418, 7314.634268286671, 7322.96338518304, 7341.192468828318, 6367.586106903036, 6657.63518478701, 7072.681729322296, 7159.8568028640175, 7161.928380716126, 7307.086614173229, 7325.523840950321, 7337.0828646419195, 7342.0, 7356.742514011984, 5492.5605951524585, 6950.687219075255, 7022.894656580258, 7139.571625702474, 7146.678399471933], "value": 5282.403286020103, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.5189615373085, 702.280162833096, 743.6133210730417, 744.4007573903006, 778.1751343575809, 12675.366231688413, 14370.568882933516, 14393.71212575749, 14478.42086316546, 14539.854601453982, 13489.73020539591, 13506.18962862229, 13534.932325338385, 13543.051986360959, 15383.692326153498, 921.9953900230506, 950.7052813627797, 953.7091187187905, 958.1041725986225, 959.7648576098866, 920.5995392004467, 931.1898648176096, 934.2712684106408, 939.4410325856139, 940.8071345374182, 938.85917112433, 994.4083270454068, 996.7807082441851, 998.7103739915416, 1003.3628645809927, 933.5239028095687, 960.8030353777493, 962.6678795815433, 988.8516722491612, 1008.1581879130935, 699.1330749870175, 701.8701540215026, 710.733474946895, 742.8365759532894, 749.35181068376, 12475.688107797287, 14897.77653335201, 14997.625059373493, 15103.5468935931, 15213.163276019815, 12310.630681079581, 12362.505499779998, 12460.75078498434, 12558.0, 12591.181573197713, 8248.0, 10127.816668408093, 10218.437985910772, 10226.642067527675, 10398.584056637725, 3461.549998500204, 3473.4442489201606, 3476.540558811413, 3494.755367124302, 3572.214112895159, 60851.695741521005, 80167.79748303721, 81687.18312816856, 82688.0, 83128.0, 8208.46644968075, 8952.0, 9309.487978161265, 9477.336586439022, 10175.0, 20885.582288354148, 20950.47623809417, 21141.365759027252, 21164.36506904795, 21188.894055529625, 7342.48602597813, 7456.40348772097, 7493.775186744407, 7542.886856697154, 7812.617151768765, 3862.2854771867296, 4004.01901534126, 4061.431399604058, 4113.835446582163, 4122.793860306972, 3585.032041348821, 3666.2850744105176, 3667.211549516867, 3667.7249206309098, 3669.321175582526, 670.2292363781593, 672.3075232510522, 676.2594958520413, 681.3969636871404, 682.8770821252123, 532.858792420009, 588.5027152056506, 591.6716222496469, 602.8613418913675, 607.2470137030139, 6134.754609815646, 6708.530402871752, 8137.145599712064, 8225.588720563948, 8399.748007559783, 479.9856004319876, 592.6799528254694, 599.9040153575494, 608.7534548507862, 650.3723906430317, 3373.308471763283, 3825.770453772727, 3888.7666739995125, 3893.1629699614155, 4052.8378864845076, 92756.60865087029, 94222.0, 95255.0, 96110.0, 96275.0, 78171.60914195393, 79457.0, 79892.60053699695, 80161.19838801654, 80601.0, 67612.0, 68366.0, 69142.0, 69693.65153174303, 70670.0, 8288.50268983863, 8307.667693292442, 8581.528015959182, 8595.65617375298, 8797.208251257418, 48372.274415884494, 49456.0, 49613.12686856349, 50065.74967125141, 50424.747876260386, 66165.0, 67404.0, 73935.63032184805, 74965.62517187379, 78289.2171078282, 11472.770544588895, 11489.310641361544, 11532.538698452303, 11787.057035437316, 12065.638030858738, 11902.583409580624, 11953.701157471132, 12093.879061209274, 12111.454984525893, 12592.685182870502, 11194.888051119711, 11282.266652667551, 11482.253653512495, 11979.04167666602, 12076.275423474619, 11389.088872889983, 11609.129315301218, 11617.244879082833, 11640.0, 11644.76710465769, 9689.951550242204, 9715.0, 11226.60706875288, 11253.831192532283, 11532.019778318936, 9468.849840255536, 9613.567389467631, 9659.46872921996, 9900.752481188028, 9960.601575936882, 9843.557039933077, 9880.752981175528, 9985.650502232387, 9985.850212246676, 10006.299559030798], "value": 6936.141492492279, "unit": "score"}], "name": "dromaeo_css", "value": 7406.05395535798, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 121.0, 110.0, 117.0, 119.0, 113.0, 104.0, 118.0, 118.0, 117.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 128.0, 159.0, 154.0, 125.0, 139.0, 123.0, 154.0, 156.0, 138.0], "value": 142.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 200.0, 199.0, 229.0, 228.0, 199.0, 198.0, 202.0, 198.0, 196.0], "value": 204.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 71.0, 147.0, 57.0, 60.0, 56.0, 62.0, 134.0, 57.0], "value": 79.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 126.0, 77.0, 81.0, 87.0, 124.0, 100.0, 114.0, 109.0], "value": 98.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 133.0, 129.0, 88.0, 86.0, 89.0, 86.0, 90.0, 86.0], "value": 96.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 152.0, 94.0, 110.0, 116.0, 97.0, 94.0, 95.0, 95.0], "value": 104.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 90.0, 92.0, 93.0, 92.0, 91.0, 92.0, 96.0, 90.0], "value": 92.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 67.0, 67.0, 65.0, 65.0, 65.0, 85.0, 64.0, 61.0], "value": 66.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 68.0, 66.0, 70.0, 71.0, 84.0, 71.0, 63.0, 69.0, 72.0], "value": 69.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 80.0, 83.0, 82.0, 82.0, 79.0, 80.0, 81.0, 95.0, 108.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 137.0, 143.0, 140.0, 154.0, 143.0, 151.0, 142.0, 175.0, 172.0], "value": 151.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 221.0, 180.0, 173.0, 189.0, 160.0, 217.0, 167.0, 164.0, 184.0], "value": 184.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 87.0, 79.0, 65.0, 85.0, 96.0, 61.0, 63.0, 69.0, 97.0], "value": 77.0, "unit": "score"}], "name": "kraken", "value": 1566.9999999999998, "alertThreshold": 2.0}]} 10:05:43 INFO - Return code: 0 10:05:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:05:43 ERROR - # TBPL SUCCESS # 10:05:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:05:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:05:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:05:43 INFO - 2016-05-27 10:05:43.245 screenresolution[1689:10978] starting screenresolution argv=screenresolution get 10:05:43 INFO - 2016-05-27 10:05:43.247 screenresolution[1689:10978] Display 0: 1600x1200x32@60 10:05:43 INFO - 2016-05-27 10:05:43.253 screenresolution[1690:10981] starting screenresolution argv=screenresolution list 10:05:43 INFO - Available Modes on Display 0 10:05:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:05:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:05:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:05:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:05:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:05:43 INFO - Intel Iris: 10:05:43 INFO - Chipset Model: Intel Iris 10:05:43 INFO - Type: GPU 10:05:43 INFO - Bus: Built-In 10:05:43 INFO - VRAM (Dynamic, Max): 1536 MB 10:05:43 INFO - Vendor: Intel (0x8086) 10:05:43 INFO - Device ID: 0x0a2e 10:05:43 INFO - Revision ID: 0x0009 10:05:43 INFO - Displays: 10:05:43 INFO - Display: 10:05:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:05:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:05:43 INFO - Display Serial Number: 1600x1200 60 10:05:43 INFO - Main Display: Yes 10:05:43 INFO - Mirror: Off 10:05:43 INFO - Online: Yes 10:05:43 INFO - Rotation: Supported 10:05:43 INFO - Return code: 0 10:05:43 INFO - Running post-action listener: _resource_record_post_action 10:05:43 INFO - Running post-run listener: _resource_record_post_run 10:05:44 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 32673280; Write bytes: 655261184; Read time: 315; Write time: 3080 10:05:44 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344736256; Write bytes: 338343424; Read time: 26606; Write time: 681 10:05:44 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 31751680; Write bytes: 299677696; Read time: 301; Write time: 2347 10:05:44 INFO - Running post-run listener: _upload_blobber_files 10:05:44 INFO - Blob upload gear active. 10:05:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:05:44 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 10:05:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:05:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:05:44 INFO - (blobuploader) - INFO - Open directory for files ... 10:05:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:05:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:05:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:05:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:05:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:05:46 INFO - (blobuploader) - INFO - Done attempting. 10:05:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:05:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:05:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:05:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:05:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:05:47 INFO - (blobuploader) - INFO - Done attempting. 10:05:47 INFO - (blobuploader) - INFO - Iteration through files over. 10:05:47 INFO - Return code: 0 10:05:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:05:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:05:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e902560308c74dd11f8dcee74effe61ead2b38a7528e5b5d3c70da2fd44d59267b6883940fcaa59ec0b8e4ea2c68f26b829ceda4175c6703d38dde90c6664d82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/239ace64fe4383a6abe163ab2d8edfb786cda3a1e79bbead2e8874594b344ac4bb8230516eeabd5f56c96755309d164a3ff1ed29553f7a30750d84d37240a39a"} 10:05:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:05:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:05:47 INFO - Contents: 10:05:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e902560308c74dd11f8dcee74effe61ead2b38a7528e5b5d3c70da2fd44d59267b6883940fcaa59ec0b8e4ea2c68f26b829ceda4175c6703d38dde90c6664d82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/239ace64fe4383a6abe163ab2d8edfb786cda3a1e79bbead2e8874594b344ac4bb8230516eeabd5f56c96755309d164a3ff1ed29553f7a30750d84d37240a39a"} 10:05:47 INFO - Running post-run listener: copy_logs_to_upload_dir 10:05:47 INFO - Copying logs to upload dir... 10:05:47 INFO - mkdir: /builds/slave/test/build/upload/logs 10:05:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1296.912818 ========= master_lag: 1.94 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 38 secs) (at 2016-05-27 10:05:49.468700) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 10:05:49.472494) ========= 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.q9U0YkGxVS/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.t7v6HfUcyt/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e902560308c74dd11f8dcee74effe61ead2b38a7528e5b5d3c70da2fd44d59267b6883940fcaa59ec0b8e4ea2c68f26b829ceda4175c6703d38dde90c6664d82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/239ace64fe4383a6abe163ab2d8edfb786cda3a1e79bbead2e8874594b344ac4bb8230516eeabd5f56c96755309d164a3ff1ed29553f7a30750d84d37240a39a"} build_url:https://queue.taskcluster.net/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011152 build_url: 'https://queue.taskcluster.net/v1/task/O31rYN54SxGuBJxAuX0Qhw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e902560308c74dd11f8dcee74effe61ead2b38a7528e5b5d3c70da2fd44d59267b6883940fcaa59ec0b8e4ea2c68f26b829ceda4175c6703d38dde90c6664d82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/239ace64fe4383a6abe163ab2d8edfb786cda3a1e79bbead2e8874594b344ac4bb8230516eeabd5f56c96755309d164a3ff1ed29553f7a30750d84d37240a39a"}' ========= master_lag: 0.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 10:05:49.548302) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 10:05:49.548747) ========= 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.q9U0YkGxVS/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.t7v6HfUcyt/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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.005294 ========= master_lag: 0.50 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 10:05:50.050632) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 10:05:50.054033) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 10:05:50.054686) ========= ========= Total master_lag: 2.57 =========