builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0029 starttime: 1460650197.7 results: success (0) buildid: 20160414072048 builduid: 4bc212b1ceae4d1aa097dd9dd0d5658a revision: 10f66b3164570b2183333262fa91a16004cbb908 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 09:09:57.698655) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 09:09:57.699078) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-14 09:09:57.699365) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-14 09:09:57.721392) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 09:09:57.721651) ========= 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.DewLQix8WK/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.KIDZFe6IR8/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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-04-14 09:09:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 41.2M=0s 2016-04-14 09:09:58 (41.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.556939 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 09:09:58.294730) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 09:09:58.295039) ========= 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.DewLQix8WK/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.KIDZFe6IR8/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.020097 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 09:09:58.346608) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-14 09:09:58.346927) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 10f66b3164570b2183333262fa91a16004cbb908 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 10f66b3164570b2183333262fa91a16004cbb908 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DewLQix8WK/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.KIDZFe6IR8/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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-04-14 09:09:58,423 truncating revision to first 12 chars 2016-04-14 09:09:58,423 Setting DEBUG logging. 2016-04-14 09:09:58,423 attempt 1/10 2016-04-14 09:09:58,423 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/10f66b316457?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-14 09:09:59,425 unpacking tar archive at: mozilla-central-10f66b316457/testing/mozharness/ program finished with exit code 0 elapsedTime=1.253753 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-14 09:09:59.623691) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 09:09:59.624107) ========= script_repo_revision: 10f66b3164570b2183333262fa91a16004cbb908 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 09:09:59.624576) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 09:09:59.624856) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 09:09:59.641778) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-04-14 09:09:59.642178) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DewLQix8WK/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.KIDZFe6IR8/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:09:59 INFO - MultiFileLogger online at 20160414 09:09:59 in /builds/slave/test 09:09:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:09:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:09:59 INFO - {'append_to_log': False, 09:09:59 INFO - 'base_work_dir': '/builds/slave/test', 09:09:59 INFO - 'blob_upload_branch': 'Firefox', 09:09:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:09:59 INFO - 'branch': 'Firefox', 09:09:59 INFO - 'buildbot_json_path': 'buildprops.json', 09:09:59 INFO - 'config_files': ('talos/mac_config.py',), 09:09:59 INFO - 'default_actions': ('clobber', 09:09:59 INFO - 'read-buildbot-config', 09:09:59 INFO - 'download-and-extract', 09:09:59 INFO - 'populate-webroot', 09:09:59 INFO - 'create-virtualenv', 09:09:59 INFO - 'install', 09:09:59 INFO - 'run-tests'), 09:09:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:09:59 INFO - 'download_minidump_stackwalk': True, 09:09:59 INFO - 'download_symbols': 'ondemand', 09:09:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:09:59 INFO - 'tooltool.py': '/tools/tooltool.py', 09:09:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:09:59 INFO - '/tools/misc-python/virtualenv.py')}, 09:09:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:09:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:09:59 INFO - 'installer_path': 'installer.exe', 09:09:59 INFO - 'log_level': 'info', 09:09:59 INFO - 'log_name': 'talos', 09:09:59 INFO - 'log_to_console': True, 09:09:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:09:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:09:59 INFO - 'opt_config_files': (), 09:09:59 INFO - 'pip_index': False, 09:09:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:09:59 INFO - 'cmd': ('bash', 09:09:59 INFO - '-c', 09:09:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:09:59 INFO - 'enabled': True, 09:09:59 INFO - 'halt_on_failure': False, 09:09:59 INFO - 'name': 'check_screen_resolution'},), 09:09:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:09:59 INFO - 'cmd': ('bash', 09:09:59 INFO - '-c', 09:09:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:09:59 INFO - 'enabled': True, 09:09:59 INFO - 'halt_on_failure': False, 09:09:59 INFO - 'name': 'check_screen_resolution'},), 09:09:59 INFO - 'run_cmd_checks_enabled': True, 09:09:59 INFO - 'sps_profile': False, 09:09:59 INFO - 'sps_profile_interval': 0, 09:09:59 INFO - 'suite': 'dromaeojs-e10s', 09:09:59 INFO - 'system_bits': '32', 09:09:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:09:59 INFO - 'title': 't-yosemite-r7-0029', 09:09:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:09:59 INFO - 'use_talos_json': True, 09:09:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:09:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:09:59 INFO - 'work_dir': 'build'} 09:09:59 INFO - ##### 09:09:59 INFO - ##### Running clobber step. 09:09:59 INFO - ##### 09:09:59 INFO - Running pre-action listener: _resource_record_pre_action 09:09:59 INFO - Running main action method: clobber 09:09:59 INFO - rmtree: /builds/slave/test/build 09:09:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:10:01 INFO - Running post-action listener: _resource_record_post_action 09:10:01 INFO - ##### 09:10:01 INFO - ##### Running read-buildbot-config step. 09:10:01 INFO - ##### 09:10:01 INFO - Running pre-action listener: _resource_record_pre_action 09:10:01 INFO - Running main action method: read_buildbot_config 09:10:01 INFO - Using buildbot properties: 09:10:01 INFO - { 09:10:01 INFO - "project": "", 09:10:01 INFO - "product": "firefox", 09:10:01 INFO - "script_repo_revision": "production", 09:10:01 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:10:01 INFO - "repository": "", 09:10:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:10:01 INFO - "buildid": "20160414072048", 09:10:01 INFO - "pgo_build": "False", 09:10:01 INFO - "basedir": "/builds/slave/test", 09:10:01 INFO - "buildnumber": 23, 09:10:01 INFO - "slavename": "t-yosemite-r7-0029", 09:10:01 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:10:01 INFO - "platform": "yosemite_r7", 09:10:01 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:10:01 INFO - "branch": "mozilla-central", 09:10:01 INFO - "repo_path": "mozilla-central", 09:10:01 INFO - "revision": "10f66b3164570b2183333262fa91a16004cbb908", 09:10:01 INFO - "stage_platform": "macosx64", 09:10:01 INFO - "builduid": "4bc212b1ceae4d1aa097dd9dd0d5658a", 09:10:01 INFO - "slavebuilddir": "test" 09:10:01 INFO - } 09:10:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 09:10:01 INFO - Running post-action listener: _resource_record_post_action 09:10:01 INFO - ##### 09:10:01 INFO - ##### Running download-and-extract step. 09:10:01 INFO - ##### 09:10:01 INFO - Running pre-action listener: _resource_record_pre_action 09:10:01 INFO - Running main action method: download_and_extract 09:10:01 INFO - mkdir: /builds/slave/test/build/tests 09:10:01 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:10:01 INFO - https://queue.taskcluster.net/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:10:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 09:10:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 09:10:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:10:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 09:10:02 INFO - Downloaded 1199 bytes. 09:10:02 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:10:02 INFO - Using the following test package requirements: 09:10:02 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:10:02 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:10:02 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 09:10:02 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:10:02 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 09:10:02 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:10:02 INFO - u'jsshell-mac.zip'], 09:10:02 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:10:02 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 09:10:02 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:10:02 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:10:02 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 09:10:02 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:10:02 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 09:10:02 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:10:02 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 09:10:02 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:10:02 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 09:10:02 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:10:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:02 INFO - https://queue.taskcluster.net/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:10:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:10:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:10:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:10:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:10:04 INFO - Downloaded 18028907 bytes. 09:10:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:10:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:10:07 INFO - Return code: 0 09:10:07 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:10: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:10:07 INFO - https://queue.taskcluster.net/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:10:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:10:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:10:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:10:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:10:08 INFO - Downloaded 18028907 bytes. 09:10:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:10:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:10:11 INFO - Return code: 0 09:10:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:11 INFO - https://queue.taskcluster.net/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:10:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 09:10:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 09:10:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 09:10:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:10:13 INFO - Downloaded 10841076 bytes. 09:10:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:10:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:10:13 INFO - Return code: 0 09:10: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:10:13 INFO - https://queue.taskcluster.net/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:10:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:10:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:10:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 09:10:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 09:10:16 INFO - Downloaded 109851218 bytes. 09:10:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:10:16 INFO - mkdir: /builds/slave/test/properties 09:10:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:10:16 INFO - Writing to file /builds/slave/test/properties/build_url 09:10:16 INFO - Contents: 09:10:16 INFO - build_url:https://queue.taskcluster.net/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:10:18 INFO - Running post-action listener: _resource_record_post_action 09:10:18 INFO - Running post-action listener: set_extra_try_arguments 09:10:18 INFO - ##### 09:10:18 INFO - ##### Running populate-webroot step. 09:10:18 INFO - ##### 09:10:18 INFO - Running pre-action listener: _resource_record_pre_action 09:10:18 INFO - Running main action method: populate_webroot 09:10:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:10:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:10:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:10:18 INFO - '--tppagecycles', 09:10:18 INFO - '7'], 09:10:18 INFO - 'tests': ['tsvgm']}}, 09:10:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:10:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:10:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:10:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:10:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:10:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:10:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:10:18 INFO - 'talos_options': ['--disable-e10s'], 09:10:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:10:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:10:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:10:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:10:18 INFO - 'talos_options': ['--disable-e10s'], 09:10:18 INFO - 'tests': ['damp', 'tps']}, 09:10:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:10:18 INFO - 'tests': ['damp', 'tps']}, 09:10:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:10:18 INFO - 'tests': ['dromaeo_dom']}, 09:10:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:10:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:10:18 INFO - 'tests': ['a11yr', 09:10:18 INFO - 'ts_paint', 09:10:18 INFO - 'tpaint', 09:10:18 INFO - 'sessionrestore', 09:10:18 INFO - 'sessionrestore_no_auto_restore', 09:10:18 INFO - 'tabpaint']}, 09:10:18 INFO - 'other-e10s': {'tests': ['a11yr', 09:10:18 INFO - 'ts_paint', 09:10:18 INFO - 'tpaint', 09:10:18 INFO - 'sessionrestore', 09:10:18 INFO - 'sessionrestore_no_auto_restore', 09:10:18 INFO - 'tabpaint']}, 09:10:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:10:18 INFO - 'ts_paint', 09:10:18 INFO - 'tpaint', 09:10:18 INFO - 'sessionrestore', 09:10:18 INFO - 'sessionrestore_no_auto_restore', 09:10:18 INFO - 'tabpaint']}, 09:10:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:10:18 INFO - 'ts_paint', 09:10:18 INFO - 'tpaint', 09:10:18 INFO - 'sessionrestore', 09:10:18 INFO - 'sessionrestore_no_auto_restore', 09:10:18 INFO - 'tabpaint']}, 09:10:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:10:18 INFO - 'tests': ['a11yr', 09:10:18 INFO - 'ts_paint', 09:10:18 INFO - 'tpaint', 09:10:18 INFO - 'sessionrestore', 09:10:18 INFO - 'sessionrestore_no_auto_restore', 09:10:18 INFO - 'tabpaint']}, 09:10:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:10:18 INFO - 'tests': ['a11yr', 09:10:18 INFO - 'ts_paint', 09:10:18 INFO - 'tpaint', 09:10:18 INFO - 'sessionrestore', 09:10:18 INFO - 'sessionrestore_no_auto_restore', 09:10:18 INFO - 'tabpaint']}, 09:10:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:10:18 INFO - 'tests': ['tsvgx', 09:10:18 INFO - 'tsvgr_opacity', 09:10:18 INFO - 'tart', 09:10:18 INFO - 'tscrollx', 09:10:18 INFO - 'cart']}, 09:10:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:10:18 INFO - 'tsvgr_opacity', 09:10:18 INFO - 'tart', 09:10:18 INFO - 'tscrollx', 09:10:18 INFO - 'cart']}, 09:10:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:10:18 INFO - 'talos_options': ['--disable-e10s'], 09:10:18 INFO - 'tests': ['tp5o']}, 09:10:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:10:18 INFO - 'tests': ['tp5o']}, 09:10:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:10:18 INFO - 'talos_options': ['--disable-e10s', 09:10:18 INFO - '--xperf_path', 09:10:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:10:18 INFO - 'tests': ['tp5n']}, 09:10:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:10:18 INFO - 'talos_options': ['--xperf_path', 09:10:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:10:18 INFO - 'tests': ['tp5n']}}, 09:10:18 INFO - 'talos.zip': {'path': '', 09:10:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:10:18 INFO - Running post-action listener: _resource_record_post_action 09:10:18 INFO - ##### 09:10:18 INFO - ##### Running create-virtualenv step. 09:10:18 INFO - ##### 09:10:18 INFO - Running pre-action listener: _resource_record_pre_action 09:10:18 INFO - Running main action method: create_virtualenv 09:10:18 INFO - Creating virtualenv /builds/slave/test/build/venv 09:10:18 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:10:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:10:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:10:18 INFO - Using real prefix '/tools/python27' 09:10:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:10:19 INFO - Installing distribute.............................................................................................................................................................................................done. 09:10:22 INFO - Installing pip.................done. 09:10:22 INFO - Return code: 0 09:10:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:10:22 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:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:22 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:10:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:22 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 0x10d23fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d24f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a05a5920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0dee00>, '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 0x10cf47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0d9b20>, '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.DewLQix8WK/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.KIDZFe6IR8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:10:22 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:10:22 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:10:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DewLQix8WK/Render', 09:10:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:22 INFO - 'HOME': '/Users/cltbld', 09:10:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:22 INFO - 'LOGNAME': 'cltbld', 09:10:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:22 INFO - 'MOZ_NO_REMOTE': '1', 09:10:22 INFO - 'NO_EM_RESTART': '1', 09:10:22 INFO - 'PAGER': '/bin/cat', 09:10:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:22 INFO - 'PWD': '/builds/slave/test', 09:10:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:22 INFO - 'SHELL': '/bin/bash', 09:10:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KIDZFe6IR8/Listeners', 09:10:22 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 09:10:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:22 INFO - 'USER': 'cltbld', 09:10:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:22 INFO - 'XPC_FLAGS': '0x0', 09:10:22 INFO - 'XPC_SERVICE_NAME': '0', 09:10:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:22 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:10:22 INFO - Cleaning up... 09:10:22 INFO - Return code: 0 09:10:22 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:10:22 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:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:22 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:10:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:22 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 0x10d23fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d24f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a05a5920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0dee00>, '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 0x10cf47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0d9b20>, '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.DewLQix8WK/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.KIDZFe6IR8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:10:22 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:10:22 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:10:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DewLQix8WK/Render', 09:10:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:22 INFO - 'HOME': '/Users/cltbld', 09:10:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:22 INFO - 'LOGNAME': 'cltbld', 09:10:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:22 INFO - 'MOZ_NO_REMOTE': '1', 09:10:22 INFO - 'NO_EM_RESTART': '1', 09:10:22 INFO - 'PAGER': '/bin/cat', 09:10:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:22 INFO - 'PWD': '/builds/slave/test', 09:10:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:22 INFO - 'SHELL': '/bin/bash', 09:10:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KIDZFe6IR8/Listeners', 09:10:22 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 09:10:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:22 INFO - 'USER': 'cltbld', 09:10:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:22 INFO - 'XPC_FLAGS': '0x0', 09:10:22 INFO - 'XPC_SERVICE_NAME': '0', 09:10:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:22 INFO - Downloading/unpacking psutil>=0.7.1 09:10:22 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:10:22 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:10:22 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:10:22 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:10:22 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:10:22 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:10:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:10:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:10:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:10:25 INFO - Installing collected packages: psutil 09:10:25 INFO - Running setup.py install for psutil 09:10:25 INFO - building 'psutil._psutil_osx' extension 09:10:25 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:10:25 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:10:25 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:10:26 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:10:26 INFO - building 'psutil._psutil_posix' extension 09:10:26 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:10:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:10:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:10:26 INFO - ^ 09:10:26 INFO - 1 warning generated. 09:10:26 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:10:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:10:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:10:26 INFO - Successfully installed psutil 09:10:26 INFO - Cleaning up... 09:10:26 INFO - Return code: 0 09:10:26 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d23fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d24f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a05a5920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0dee00>, '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 0x10cf47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0d9b20>, '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.DewLQix8WK/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.KIDZFe6IR8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:10:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:10:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:10:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DewLQix8WK/Render', 09:10:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:26 INFO - 'HOME': '/Users/cltbld', 09:10:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:26 INFO - 'LOGNAME': 'cltbld', 09:10:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:26 INFO - 'MOZ_NO_REMOTE': '1', 09:10:26 INFO - 'NO_EM_RESTART': '1', 09:10:26 INFO - 'PAGER': '/bin/cat', 09:10:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:26 INFO - 'PWD': '/builds/slave/test', 09:10:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:26 INFO - 'SHELL': '/bin/bash', 09:10:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KIDZFe6IR8/Listeners', 09:10:26 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 09:10:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:26 INFO - 'USER': 'cltbld', 09:10:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:26 INFO - 'XPC_FLAGS': '0x0', 09:10:26 INFO - 'XPC_SERVICE_NAME': '0', 09:10:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:26 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:29 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:10:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:10:29 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:10:29 INFO - Installing collected packages: mozsystemmonitor 09:10:29 INFO - Running setup.py install for mozsystemmonitor 09:10:29 INFO - Successfully installed mozsystemmonitor 09:10:29 INFO - Cleaning up... 09:10:29 INFO - Return code: 0 09:10:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:10:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d23fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d24f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a05a5920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0dee00>, '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 0x10cf47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0d9b20>, '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.DewLQix8WK/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.KIDZFe6IR8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:10:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:10:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:10:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DewLQix8WK/Render', 09:10:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:29 INFO - 'HOME': '/Users/cltbld', 09:10:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:29 INFO - 'LOGNAME': 'cltbld', 09:10:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:29 INFO - 'MOZ_NO_REMOTE': '1', 09:10:29 INFO - 'NO_EM_RESTART': '1', 09:10:29 INFO - 'PAGER': '/bin/cat', 09:10:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:29 INFO - 'PWD': '/builds/slave/test', 09:10:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:29 INFO - 'SHELL': '/bin/bash', 09:10:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KIDZFe6IR8/Listeners', 09:10:29 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 09:10:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:29 INFO - 'USER': 'cltbld', 09:10:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:29 INFO - 'XPC_FLAGS': '0x0', 09:10:29 INFO - 'XPC_SERVICE_NAME': '0', 09:10:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:29 INFO - Downloading/unpacking blobuploader==1.2.4 09:10:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:32 INFO - Downloading blobuploader-1.2.4.tar.gz 09:10:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:10:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:10:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:10:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:10:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:33 INFO - Downloading docopt-0.6.1.tar.gz 09:10:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:10:33 INFO - Installing collected packages: blobuploader, requests, docopt 09:10:33 INFO - Running setup.py install for blobuploader 09:10:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:10:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:10:33 INFO - Running setup.py install for requests 09:10:33 INFO - Running setup.py install for docopt 09:10:34 INFO - Successfully installed blobuploader requests docopt 09:10:34 INFO - Cleaning up... 09:10:34 INFO - Return code: 0 09:10:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10: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:10:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10: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:10:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:34 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 0x10d23fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d24f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a05a5920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0dee00>, '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 0x10cf47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0d9b20>, '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.DewLQix8WK/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.KIDZFe6IR8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:10:34 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:10:34 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:10:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DewLQix8WK/Render', 09:10:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:34 INFO - 'HOME': '/Users/cltbld', 09:10:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:34 INFO - 'LOGNAME': 'cltbld', 09:10:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:34 INFO - 'MOZ_NO_REMOTE': '1', 09:10:34 INFO - 'NO_EM_RESTART': '1', 09:10:34 INFO - 'PAGER': '/bin/cat', 09:10:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:34 INFO - 'PWD': '/builds/slave/test', 09:10:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:34 INFO - 'SHELL': '/bin/bash', 09:10:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KIDZFe6IR8/Listeners', 09:10:34 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 09:10:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:34 INFO - 'USER': 'cltbld', 09:10:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:34 INFO - 'XPC_FLAGS': '0x0', 09:10:34 INFO - 'XPC_SERVICE_NAME': '0', 09:10:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:10:34 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-yG878l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:10:34 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-jvQbyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:10:34 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-72PHGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:10:34 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-HSgSzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:10:34 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-iXArZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:10:34 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-vSp1Ip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:10:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-bcNdgc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:10:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-GrszjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:10:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-IQfLre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:10:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Msyc3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:10:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ZIostg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:10:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-n4KrjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:10:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-1a7Q4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:10:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-WgOGcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-zj1hg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:10:36 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-e150Yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:10:36 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-FoQuHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:10:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:10:36 INFO - Running setup.py install for manifestparser 09:10:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:10:36 INFO - Running setup.py install for mozcrash 09:10:36 INFO - Running setup.py install for mozdebug 09:10:36 INFO - Running setup.py install for mozdevice 09:10:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:10:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:10:36 INFO - Running setup.py install for mozfile 09:10:36 INFO - Running setup.py install for mozhttpd 09:10:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:10:37 INFO - Running setup.py install for mozinfo 09:10:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:10:37 INFO - Running setup.py install for mozInstall 09:10:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:10:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:10:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:10:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:10:37 INFO - Running setup.py install for mozleak 09:10:37 INFO - Running setup.py install for mozlog 09:10:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:10:37 INFO - Running setup.py install for moznetwork 09:10:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:10:37 INFO - Running setup.py install for mozprocess 09:10:38 INFO - Running setup.py install for mozprofile 09:10:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:10:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:10:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:10:38 INFO - Running setup.py install for mozrunner 09:10:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:10:38 INFO - Running setup.py install for mozscreenshot 09:10:38 INFO - Running setup.py install for moztest 09:10:38 INFO - Running setup.py install for mozversion 09:10:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:10:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:10:38 INFO - Cleaning up... 09:10:38 INFO - Return code: 0 09:10:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:38 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 0x10d23fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d24f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a05a5920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0dee00>, '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 0x10cf47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0d9b20>, '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.DewLQix8WK/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.KIDZFe6IR8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:10:38 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:10:38 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:10:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DewLQix8WK/Render', 09:10:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:38 INFO - 'HOME': '/Users/cltbld', 09:10:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:38 INFO - 'LOGNAME': 'cltbld', 09:10:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:38 INFO - 'MOZ_NO_REMOTE': '1', 09:10:38 INFO - 'NO_EM_RESTART': '1', 09:10:38 INFO - 'PAGER': '/bin/cat', 09:10:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:38 INFO - 'PWD': '/builds/slave/test', 09:10:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:38 INFO - 'SHELL': '/bin/bash', 09:10:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KIDZFe6IR8/Listeners', 09:10:38 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 09:10:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:38 INFO - 'USER': 'cltbld', 09:10:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:38 INFO - 'XPC_FLAGS': '0x0', 09:10:38 INFO - 'XPC_SERVICE_NAME': '0', 09:10:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:10:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-zvuTlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:10:39 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:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:10:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-nTixUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:10:39 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:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:10:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-CyhUSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:10:39 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:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:10:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ASEiXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:10:39 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:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:10:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-JY0FGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:10:39 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:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:10:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-9cIzpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:10:39 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:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:10:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-PnAG1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:10:39 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:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:10:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-XH2UI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:10:39 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:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:10:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ae5g1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:10:40 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:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:10:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-iFtdeL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:10:40 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:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:10:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-9HQnns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:10:40 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:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:10:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-GuDjPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:10:40 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:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:10:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-80JS4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:10:40 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:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:10:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-zSe9VU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:10:40 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:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-CaSTcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:40 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:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:10:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-QQfdde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:10:40 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:10:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:10:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-7obSUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:10:40 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:10:40 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:10:40 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:10:40 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:10:40 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:10:40 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:10:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:10:40 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:10:40 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:10:40 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:10:40 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:10:40 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:10:40 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:10:43 INFO - Downloading blessings-1.6.tar.gz 09:10:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:10:43 INFO - Installing collected packages: blessings 09:10:43 INFO - Running setup.py install for blessings 09:10:43 INFO - Successfully installed blessings 09:10:43 INFO - Cleaning up... 09:10:43 INFO - Return code: 0 09:10:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:10:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:10:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:10:43 INFO - Reading from file tmpfile_stdout 09:10:43 INFO - Current package versions: 09:10:43 INFO - blessings == 1.6 09:10:43 INFO - blobuploader == 1.2.4 09:10:43 INFO - docopt == 0.6.1 09:10:43 INFO - manifestparser == 1.1 09:10:43 INFO - mozInstall == 1.12 09:10:43 INFO - mozcrash == 0.17 09:10:43 INFO - mozdebug == 0.1 09:10:43 INFO - mozdevice == 0.48 09:10:43 INFO - mozfile == 1.2 09:10:43 INFO - mozhttpd == 0.7 09:10:43 INFO - mozinfo == 0.9 09:10:43 INFO - mozleak == 0.1 09:10:43 INFO - mozlog == 3.1 09:10:43 INFO - moznetwork == 0.27 09:10:43 INFO - mozprocess == 0.22 09:10:43 INFO - mozprofile == 0.28 09:10:43 INFO - mozrunner == 6.11 09:10:43 INFO - mozscreenshot == 0.1 09:10:43 INFO - mozsystemmonitor == 0.0 09:10:43 INFO - moztest == 0.7 09:10:43 INFO - mozversion == 1.4 09:10:43 INFO - psutil == 3.1.1 09:10:43 INFO - requests == 1.2.3 09:10:43 INFO - wsgiref == 0.1.2 09:10:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10: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:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10: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:10:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:43 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 0x10d23fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d24f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a05a5920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0dee00>, '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 0x10cf47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0d9b20>, '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.DewLQix8WK/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.KIDZFe6IR8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:10:43 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:10:43 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:10:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DewLQix8WK/Render', 09:10:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:43 INFO - 'HOME': '/Users/cltbld', 09:10:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:43 INFO - 'LOGNAME': 'cltbld', 09:10:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:43 INFO - 'MOZ_NO_REMOTE': '1', 09:10:43 INFO - 'NO_EM_RESTART': '1', 09:10:43 INFO - 'PAGER': '/bin/cat', 09:10:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:43 INFO - 'PWD': '/builds/slave/test', 09:10:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:43 INFO - 'SHELL': '/bin/bash', 09:10:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KIDZFe6IR8/Listeners', 09:10:43 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 09:10:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:43 INFO - 'USER': 'cltbld', 09:10:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:43 INFO - 'XPC_FLAGS': '0x0', 09:10:43 INFO - 'XPC_SERVICE_NAME': '0', 09:10:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:44 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:10:44 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:10:44 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:10:44 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:10:44 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:10:44 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:10:44 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:10:44 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:10:44 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:10:44 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:10:44 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:10:44 INFO - Cleaning up... 09:10:44 INFO - Return code: 0 09:10:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:10:44 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:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:44 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:10:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:44 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 0x10d23fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d24f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a05a5920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0dee00>, '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 0x10cf47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0d9b20>, '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.DewLQix8WK/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.KIDZFe6IR8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:10:44 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:10:44 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:10:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DewLQix8WK/Render', 09:10:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:44 INFO - 'HOME': '/Users/cltbld', 09:10:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:44 INFO - 'LOGNAME': 'cltbld', 09:10:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:44 INFO - 'MOZ_NO_REMOTE': '1', 09:10:44 INFO - 'NO_EM_RESTART': '1', 09:10:44 INFO - 'PAGER': '/bin/cat', 09:10:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:44 INFO - 'PWD': '/builds/slave/test', 09:10:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:44 INFO - 'SHELL': '/bin/bash', 09:10:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KIDZFe6IR8/Listeners', 09:10:44 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 09:10:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:44 INFO - 'USER': 'cltbld', 09:10:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:44 INFO - 'XPC_FLAGS': '0x0', 09:10:44 INFO - 'XPC_SERVICE_NAME': '0', 09:10:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:44 INFO - Downloading/unpacking jsonschema 09:10:44 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:10:44 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:10:44 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:10:44 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:10:44 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:10:44 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:10:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:10:46 INFO - Downloading/unpacking functools32 (from jsonschema) 09:10:46 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:10:46 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:10:46 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:10:46 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:10:46 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:10:46 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:10:47 INFO - Downloading functools32-3.2.3-2.tar.gz 09:10:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:10:47 INFO - warning: no files found matching '*.txt' 09:10:47 INFO - no previously-included directories found matching 'build' 09:10:47 INFO - no previously-included directories found matching 'dist' 09:10:47 INFO - no previously-included directories found matching '.git*' 09:10:47 INFO - Installing collected packages: jsonschema, functools32 09:10:47 INFO - Running setup.py install for functools32 09:10:47 INFO - warning: no files found matching '*.txt' 09:10:47 INFO - no previously-included directories found matching 'build' 09:10:47 INFO - no previously-included directories found matching 'dist' 09:10:47 INFO - no previously-included directories found matching '.git*' 09:10:47 INFO - Successfully installed jsonschema functools32 09:10:47 INFO - Cleaning up... 09:10:47 INFO - Return code: 0 09:10:47 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:10: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:10:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10: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:10:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d23fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d24f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a05a5920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0dee00>, '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 0x10cf47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0d9b20>, '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.DewLQix8WK/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.KIDZFe6IR8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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:10:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:10:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:10:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DewLQix8WK/Render', 09:10:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:47 INFO - 'HOME': '/Users/cltbld', 09:10:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:47 INFO - 'LOGNAME': 'cltbld', 09:10:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:47 INFO - 'MOZ_NO_REMOTE': '1', 09:10:47 INFO - 'NO_EM_RESTART': '1', 09:10:47 INFO - 'PAGER': '/bin/cat', 09:10:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:47 INFO - 'PWD': '/builds/slave/test', 09:10:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:47 INFO - 'SHELL': '/bin/bash', 09:10:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KIDZFe6IR8/Listeners', 09:10:47 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 09:10:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:47 INFO - 'USER': 'cltbld', 09:10:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:47 INFO - 'XPC_FLAGS': '0x0', 09:10:47 INFO - 'XPC_SERVICE_NAME': '0', 09:10:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:47 INFO - Downloading/unpacking flake8 09:10:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:50 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:10:50 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:50 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:10:50 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:10:51 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:10:51 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:10:51 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:10:51 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:10:51 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:10:51 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:10:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:10:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:10:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:10:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:10:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:10:51 INFO - no previously-included directories found matching 'docs/_build' 09:10:51 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:10:51 INFO - Running setup.py install for pep8 09:10:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:10:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:10:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:10:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:10:51 INFO - no previously-included directories found matching 'docs/_build' 09:10:51 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:10:51 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:10:51 INFO - Cleaning up... 09:10:51 INFO - Return code: 0 09:10:51 INFO - Running post-action listener: _resource_record_post_action 09:10:51 INFO - Running post-action listener: _start_resource_monitoring 09:10:51 INFO - Starting resource monitoring. 09:10:51 INFO - ##### 09:10:51 INFO - ##### Running install step. 09:10:51 INFO - ##### 09:10:51 INFO - Running pre-action listener: _resource_record_pre_action 09:10:51 INFO - Running main action method: install 09:10:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:10:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:10:52 INFO - Reading from file tmpfile_stdout 09:10:52 INFO - Detecting whether we're running mozinstall >=1.0... 09:10:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:10:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:10:52 INFO - Reading from file tmpfile_stdout 09:10:52 INFO - Output received: 09:10:52 INFO - Usage: mozinstall [options] installer 09:10:52 INFO - Options: 09:10:52 INFO - -h, --help show this help message and exit 09:10:52 INFO - -d DEST, --destination=DEST 09:10:52 INFO - Directory to install application into. [default: 09:10:52 INFO - "/builds/slave/test"] 09:10:52 INFO - --app=APP Application being installed. [default: firefox] 09:10:52 INFO - mkdir: /builds/slave/test/build/application 09:10:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:10:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:11:25 INFO - Reading from file tmpfile_stdout 09:11:25 INFO - Output received: 09:11:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:11:25 INFO - Running post-action listener: _resource_record_post_action 09:11:25 INFO - ##### 09:11:25 INFO - ##### Running run-tests step. 09:11:25 INFO - ##### 09:11:25 INFO - Running pre-action listener: _resource_record_pre_action 09:11:25 INFO - Running main action method: run_tests 09:11:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:11:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:11:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:11:25 INFO - 2016-04-14 09:11:25.632 screenresolution[1975:11399] starting screenresolution argv=screenresolution get 09:11:25 INFO - 2016-04-14 09:11:25.634 screenresolution[1975:11399] Display 0: 1600x1200x32@60 09:11:25 INFO - 2016-04-14 09:11:25.640 screenresolution[1976:11401] starting screenresolution argv=screenresolution list 09:11:25 INFO - Available Modes on Display 0 09:11:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:11:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:11:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:11:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:11:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:11:25 INFO - Intel Iris: 09:11:25 INFO - Chipset Model: Intel Iris 09:11:25 INFO - Type: GPU 09:11:25 INFO - Bus: Built-In 09:11:25 INFO - VRAM (Dynamic, Max): 1536 MB 09:11:25 INFO - Vendor: Intel (0x8086) 09:11:25 INFO - Device ID: 0x0a2e 09:11:25 INFO - Revision ID: 0x0009 09:11:25 INFO - Displays: 09:11:25 INFO - Display: 09:11:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:11:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:11:25 INFO - Display Serial Number: 1600x1200 60 09:11:25 INFO - Main Display: Yes 09:11:25 INFO - Mirror: Off 09:11:25 INFO - Online: Yes 09:11:25 INFO - Rotation: Supported 09:11:25 INFO - Return code: 0 09:11:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:11:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:11:25 INFO - Python 2.7.3 09:11:25 INFO - Return code: 0 09:11:25 INFO - grabbing minidump binary from tooltool 09:11: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:11:25 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 0x10d0dee00>, '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 0x10cf47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d0d9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:11:25 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:11:25 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:11:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:11:25 INFO - Return code: 0 09:11:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:11:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:11:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:11:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:11:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:11:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:11:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:11:27 INFO - Return code: 0 09:11:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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:11:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0029 --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:11:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DewLQix8WK/Render', 09:11:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:27 INFO - 'HOME': '/Users/cltbld', 09:11:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:27 INFO - 'LOGNAME': 'cltbld', 09:11:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:11:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:11:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:27 INFO - 'MOZ_NO_REMOTE': '1', 09:11:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:11:27 INFO - 'NO_EM_RESTART': '1', 09:11:27 INFO - 'PAGER': '/bin/cat', 09:11:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:27 INFO - 'PWD': '/builds/slave/test', 09:11:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:11:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:27 INFO - 'SHELL': '/bin/bash', 09:11:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KIDZFe6IR8/Listeners', 09:11:27 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 09:11:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:27 INFO - 'USER': 'cltbld', 09:11:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:27 INFO - 'XPC_FLAGS': '0x0', 09:11:27 INFO - 'XPC_SERVICE_NAME': '0', 09:11:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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:11:27 INFO - mozversion application_buildid: 20160414072048 09:11:27 INFO - mozversion application_changeset: 10f66b3164570b2183333262fa91a16004cbb908 09:11:27 INFO - mozversion application_display_name: Nightly 09:11:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:11:27 INFO - mozversion application_name: Firefox 09:11:27 INFO - mozversion application_remotingname: firefox 09:11:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:11:27 INFO - mozversion application_vendor: Mozilla 09:11:27 INFO - mozversion application_version: 48.0a1 09:11:27 INFO - mozversion platform_buildid: 20160414072048 09:11:27 INFO - mozversion platform_changeset: 10f66b3164570b2183333262fa91a16004cbb908 09:11:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:11:27 INFO - mozversion platform_version: 48.0a1 09:11:27 INFO - using testdate: 1460650287 09:11:27 INFO - actual date: 1460650287 09:11:27 INFO - starting webserver on 'localhost:49397' 09:11:27 INFO - SUITE-START | Running 2 tests 09:11:27 INFO - TEST-START | dromaeo_css 09:11:27 INFO - operating with platform_type : mac_ 09:11:27 INFO - Initialising browser for dromaeo_css test... 09:11:27 INFO - TEST-INFO | started process 1994 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpAIKOOq/profile http://localhost:49397/getInfo.html) 09:11:29 INFO - PROCESS | 1994 | JavaScript error: jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 09:11:30 INFO - PROCESS | 1994 | __metrics Screen width/height:1600/1200 09:11:30 INFO - PROCESS | 1994 | colorDepth:24 09:11:30 INFO - PROCESS | 1994 | Browser inner width/height: 1024/689 09:11:30 INFO - PROCESS | 1994 | __metrics 09:11:30 ERROR - PROCESS | 1994 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 09:11:30 INFO - TEST-INFO | 1994: exit 0 09:11:30 INFO - Browser initialized. 09:11:30 INFO - Running cycle 1/1 for dromaeo_css test... 09:11:30 INFO - TEST-INFO | started process 1997 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpAIKOOq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:11:42 INFO - PROCESS | 1997 | RSS: Main: 247865344 09:11:42 INFO - PROCESS | 1997 | 09:12:32 INFO - PROCESS | 1997 | 1460650352342 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:14:42 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49397/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49397/tests/dromaeo/cssquery-ext.html) 09:14:44 INFO - PROCESS | 1997 | RSS: Main: 260055040 09:14:44 INFO - PROCESS | 1997 | 09:17:46 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49397/tests/dromaeo/cssquery-ext.html (next: http://localhost:49397/tests/dromaeo/cssquery-jquery.html) 09:17:46 INFO - PROCESS | 1997 | RSS: Main: 271962112 09:17:46 INFO - PROCESS | 1997 | 09:20:48 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49397/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49397/tests/dromaeo/cssquery-mootools.html) 09:20:49 INFO - PROCESS | 1997 | RSS: Main: 273862656 09:20:49 INFO - PROCESS | 1997 | 09:23:50 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49397/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49397/tests/dromaeo/cssquery-prototype.html) 09:23:51 INFO - PROCESS | 1997 | RSS: Main: 275406848 09:23:51 INFO - PROCESS | 1997 | 09:26:54 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49397/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49397/tests/dromaeo/cssquery-yui.html) 09:26:54 INFO - PROCESS | 1997 | RSS: Main: 278294528 09:26:54 INFO - PROCESS | 1997 | 09:29:57 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49397/tests/dromaeo/cssquery-yui.html (next: http://localhost:49397/tests/dromaeo/cssquery-dojo.html) 09:29:57 INFO - PROCESS | 1997 | RSS: Main: 279658496 09:29:57 INFO - PROCESS | 1997 | 09:29:57 INFO - PROCESS | 1997 | __start_tp_report 09:29:57 INFO - PROCESS | 1997 | _x_x_mozilla_page_load 09:29:57 INFO - PROCESS | 1997 | _x_x_mozilla_page_load_details 09:29:57 INFO - PROCESS | 1997 | |i|pagename|runs| 09:29:57 INFO - PROCESS | 1997 | |0;dojo.html;2816.88732450702;3382.7801192922475;3404.3872103021463;3433.296174284272;3460.3252365788667;4631.235846085392;4884.803223210319;5551.1118221084635;5589;5634.661920284786;5498.422665620103;5512.145617429295;5518.586106042043;5583.274174357338;5716.371199168098;5011.974940125312;5074.086664400406;5138.6146039047035;5176.948230517703;5198.844034678966;4985.900281994358;5074.568661663763;5080.060188865072;5110.335656364686;5152.639315247934;4975.552200301972;5065.316182315377;5067.569256613192;5094.898102037957;5109.846704598868;5053.5957123430035;5063.26582645518;5068.239764035387;5090.5927525798215;5138.768755406016;4953.5541801237705;5017.974910125463;5043.394792624872;5056.418511871108;5093.923591146098;4472.686911916167;5374.3013408256675;5386.892262154735;5456.881339325429;5460.672359658433;5277.577793776527;6112;6123.479504242145;6132.50939924805;6159.19930409044;6037.396260373927;6134.631922084644;6146.692665366714;6156.3843615638525;6186.690665466754;5680.233168522279;5695.373508914016;5702.287214098238;5704.543636509069;5705.657660540381;6174.598651087665;6178.320384757673;6180.598261113013;6185.154828778722;6195.070739389038;5913.29040515141;5989.790357337472;6020.09698545213;6029.366916473795;6096.542759293071;6033.758649653964;6093.69531523422;6099.9085013724825;6103.23709536308;6104.20645316106;6065.120557519135;6074.665893375818;6091.451769340692;6115.052166914135;6163.137160797402;6024.969875150597;6025.246844144482;6073.817785466443;6135.538906165067;6141.785037523665;3760.8341414161696;4451.888702782391;4741.857744267678;4843.321934929113;4917.901641967141;3859.421086836997;4365.432493775789;4371.278739008092;4387.846425375161;4461.442319710036;5495.835124946258;5551.3893471718075;5553.805616803474;5625.606207565513;5652.491275785199;5002.774875130628;5069.239614057921;6080.939190608037;6082.60463069899;6098.54260930432;5375.16684913839;5502.724863756796;5600.831975040755;5607.046802043662;5609.859753506195;4457.420535330386;4520.412346394947;5293.735313234323;5323.387810401776;5330.760115794798;6256.593321434092;6259.217597800275;6264.498840092872;6279.963805971964;6322.241331040304;6071.241094863142;6137.55767394653;6140.201773769381;6151.446369826737;6156.78451254204;6190.50475961913;6249.187605611241;6255.311915689362;6263.592866463848;6265.498760099273;6037.486813620892;6042.516598672184;6079.148919151411;6089.5737298388685;6109.725062372115;5993.820185394445;6084.939150608438;6137.96931015342;6146.8935591593945;6158.846028849314;4864.927026094682;5939.643621382731;5989.970050149721;6011.428914253139;6065.848353791191;5900.468957793819;5952.464278214981;5952.553558483044;6000.549958753024;6021.06673465596;5671.829845104653;5795.7391917362975;5804;5854.180414742024;5865.560082993878;5825.737841797045;5971.970140149271;6006.429389208019;6027.487663548647;6102.328743838086;5792.94207057924;5813;5856.092305692625;5861.736221870111;5869.325027621708 09:29:57 INFO - PROCESS | 1997 | |1;ext.html;463.31892118585665;482.5102520941243;509.55668568345544;517.9637425380225;581.7643854239031;37118;38147;38169.23661526771;38226.80886595563;38228;39810.0047498812;40274.99312517189;40452.39321410181;40844.59155408445;41177.382339264936;9364.157225849689;9631.66289179879;9646.75883102921;9659.517024148765;9756.853647195263;9327.393719408217;9429.339946203769;9439.81120377592;9558.474283914382;9573.42559446432;9539.332246742732;9561.091696288842;9612.423254604746;9627.614895404175;9646.131848133666;9535.666251681192;9595.616175352978;9610.375325603849;9612;9622.566984485715;8206.876896846552;9501.287403444769;9551.856722149174;9652.420854748667;9697.709068727949;39022;39111.80444097761;39489.42042318304;39838.80080599608;39982;2621.8689065546837;3225.903222903317;3242.3515296940705;3477.3393055319407;3657.8719744808805;28227.576586351017;28366.432671346458;28396.574051389245;29147.85426072877;29267.853660731562;2723.986380068087;2729.0993971989396;2755.3938133610573;2797.5384061629807;2802.8178168418985;392220;415855.96936199354;434062.82968584955;471473;474288.99999999657;48048;49087.75456122697;58272.4172758267;59324.7033764837;59417;56851;62548.06177907335;65798;67377.32622673809;67398.66300668563;16265.51203463898;16285.348586056662;16418.343266269454;16448;16531;12249.510019599116;12443.128980971425;12621.053420993463;12641.87358126407;12776.85008349253;17129;18856;18922.62154756897;18983.81016189848;19410.32063877786;8060.919390806134;8221.17788221173;8234.382421318425;8269.29710974574;8301.916980830236;902.4179404284331;913.2739472119681;937.4328531238502;952.3857112162616;1017.7913527726798;21412.78587214139;28227.858860705564;29826.405068059972;29876.850615746782;29930.70069299323;905.7871400220943;1010.3129871687177;1083.6292090505517;1123.4438952718294;1130.5251794246435;14682.41270349174;14857.554273371816;14875.47935822241;15035.924820375829;15301.311440985182;631126;632296;638753;640192;643525;41685;44536;58601.0699465025;58768;58774.70612646909;1110.4558766204502;1415.0236336927487;1429.371076726237;1448.0081144416108;1448.703015881763;28848.7115128846;28939.710602894545;28961.254861239016;29183.708162918098;29439.85280073586;49986;50423;50442;50536.74731626319;50541.74729126331;437352;575926;577125;577926;578031;26136.34659133537;26212.475750485264;26278.343041424116;26479.205623831316;26644.866775665996;45057.774711126236;45291;45379.773101134284;45542.63372098842;47143.52856471392;33547.496787547716;37290;37555;38216.61783382242;38558.42162367623;36927.44608830816;36988.81505592455;37022;37811;37928;36741;37779.05552361213;38359.42460863145;38492;38624.42063369108;28783.02662051398;29035.921343613212;32099.790020997712;32117;32236.35527289488;23978.04087836467;24189.91145398426;24520;24851;25209.24372268835 09:29:57 INFO - PROCESS | 1997 | |2;jquery.html;337.6353538178769;380.4863434363609;396.9940450893237;402.62958078567715;410.9835606575737;14917.25413729312;18237.088145592716;20019.899900500495;20080.698789518152;20092.095855686493;9558.808823823536;12261.754764904697;19409.61180776387;19767.308144214956;20093.497662558442;4820.963492849037;5224.529792318691;5272.314599102111;5359.517643412092;5418.864528386783;3919.8432062717457;4545;5939.227900372945;5948.583599148062;5958.731857066421;4734.910970476775;5498.80754173604;5684.914726279109;5928.584999050068;5948.464638182562;5804.767809287623;5853;5939.227900372967;5947.464728174464;5963.344032156481;5540.252065971083;5619.859503512404;5658.91511627326;5730.971345143289;5749.0514065179195;12361;13478.19130852152;13585.320733963263;13593.864061359358;13668.589942301747;2105.2947262667026;2143.0570548958563;2218.4675677837363;2263.6151854184827;2744.588311753253;9463.716088517287;9511;9610.75973100671;9660;9886.703398898044;1442.5888621742859;1491.843356447579;1705.351966252816;1717.2959086774363;1717.9742303865453;119981;125519;126741.36629316886;126917;129110;10433.843492347622;11040.723981900517;11642.6507204784;11821.758715334767;11877.406129693654;14971.251437428085;15072.321745521478;15087.622809429851;15118.319675614624;15181;8580.699675511452;8685.305175585938;8714.21572058505;8759.211670949646;8794.86807697885;8375.20435558621;8537.317014638815;8544.060153383123;8565.871511927326;8595.871061934076;3881.4177873319227;4560.384348113029;4598.655100867449;4633.374494443275;4685.695429797052;1340.3968214303602;1777.2091419318538;1886.9528261793564;1938.2634598852346;1974.4372853736616;894.4409743910056;909.7179874239007;929.270522639738;937.4796987671907;1025.1542477456128;19743.60512789736;20357.982100894897;20394.98025098739;20507.8974605129;20807.58384832295;853.3770347646129;934.990650093504;946.9167830772328;959.1128206409044;1037.2790910317258;10514.21143414247;10791;10793.784124317626;10822.837657435144;10832.945835270773;536630;611804;613779;615185;630420;67742.66128669423;108877;133188;134011.32994334967;138417.23165536835;1002.979940401188;1065.861438013069;1074.919381046409;1083.712816103749;1083.8970297821695;17927.64144717072;18020.459386218434;18045.278188872297;18136.72794908104;18173.90913045479;33500.66499335073;48080.759596201795;49965;51261.48738512567;51445.48554514407;73618;74007;74235;75393;78089.87640494316;6209.006558950547;7579.090509138939;7962.124166341582;8130.634121464666;8272.627731751965;10091.899081009096;10734;11803.409829508319;12487.500499980262;12909.419076141408;10638;10727.409992450179;10898.128149747843;11246.718832029264;11369.54521819151;10026.498675066072;10833.349999000086;10897.237193396386;11112.111031117653;11120.165987551129;10951.83572246401;11215.60745373908;11505.252158609663;11527.769444610893;11546.94226528862;8098.392620553364;8140.185981401813;8235.876461853197;8244.299234564889;8496.872546911678;9008.459492430475;9333.393329433566;10557.73605659834;11233.71915702114;11463.885361146282 09:29:57 INFO - PROCESS | 1997 | |3;mootools.html;1436.61929588659;1626.284434848666;1866.4493974277586;1900.9334673286437;1978.4163671716842;8125.390595705331;8656.826863462727;8673.956630216848;8941.329400294979;8968.551572421376;8641.827163456726;8689.652413903452;8761.56192190391;8770.6930257441;8791.736247912557;2447.4003869052062;2460.8400453970435;2472.320111969205;2482.2429159106464;2507.448361360498;2426.5268272686744;2482;2491.9626205606933;2493.6384224287467;2497.8376405533672;1997.4407165993546;2454.128784281581;2458.176510868861;2464.2114523352534;2495.1641200197864;2440.2679196241056;2444.2422848916895;2462.2613216035206;2463.7659422354936;2475.814313926454;2433.2578563538113;2441.218809980807;2441.8046556275453;2447.229122826304;2450.2404254681105;8825.249853762438;8934.553272336358;8945.373823832335;9117.407368521024;9134.36059475837;5270.446603106695;5433.918491222635;5472.452754724535;5473.343198816167;5486.094794358926;3644.8724294649824;3650.0874781304674;3658;3678.429843374281;3682.852685892561;902.3818684201343;1033.152814691953;1052.315994603514;1052.45272458322;1086.6739978006644;9826.968168342364;11344.432778361132;11884.40577971098;11894.762104757856;11918.404079795975;4076.2458945095254;4687.718736875799;4713.175194340976;4925.75371231437;4961.7519124044375;8911.15344042315;10071.295009349422;10078.042585954323;10096.293259471766;10127.442990635438;5357.19642053695;5436.864078397993;5443.237946687467;5471.6169868108855;5478.835634930959;4471.485779135441;4786.664933454625;4932.90134197314;4964.081644895706;4965;4068.1590770860535;4068.877933661995;4078.4494093297617;4094.160697057096;4094.754314741125;1255.7425727725795;1353.661584603849;1354.5597680753795;1360.6258278973164;1370.4792178972127;803.9196080391914;819.7171975668385;820.4543978254394;906.428949761646;922.1746536849616;5734.541236701054;5785.71071446426;5842.5618078644275;5933.732982015819;6143.201383820164;442.92248856449993;449.16007066785323;462.2788450017983;463.8399752085525;465.5949324088065;3949.8025098744947;3957.5053118360206;3991.646778042936;4044.2315959967514;4065.776382299002;10440.37357758537;10669.09312708413;10792.298500597437;10952.4523773811;10989.61536346224;10812.243142979916;10845.186611004206;10893.618723344645;10985.890141098647;11024.50389732464;7925.603719814101;8171.591420428955;8226.670933162726;8286.0056793184;8322.70870519541;5750.7987220447085;5875.970620146872;5882.735276912464;5912.231409916683;5937.881242375042;8919.598618062073;9932.205423565953;9988.300818942604;10020.749481262736;10090.445025523373;9812.65655702047;10013.899861001588;10245.334053286513;10282.794344113225;10346.948265258625;5710.885782284248;7387.852242955219;7562.281583249583;7578.772636820904;7623.4663573549315;8368.330533557422;8373.748787536384;8390.622421991147;8442.70450534253;8475.194856488624;7419.851602967802;7532.904365254569;7558.848823023619;7661;7678.61606919663;7462.328390444886;7473.813154671177;7558.962205188939;7626.8855967159425;7713.845723085619;7085.574865508086;7144.678489467882;7198.20819709821;7206.963965180141;7228.674709638184;4720.480747117883;4787.712737235777;4872.658913875995;4918.975405122951;5027.220780778839;4885.755712214446;4942.4069111705385;5014.247862820606;5014.398272207212;5135.075686376488 09:29:57 INFO - PROCESS | 1997 | |4;prototype.html;318.708381830625;320.4424301715015;328.1304542961153;329.8499182871794;330.1482175985955;5079.933214025054;5863.9444899918;6018.428249316314;6120.908186377208;6317.6841157942035;4412.889677758058;4659.673822832403;5449.618526703132;6003.30961939376;6047.092936059593;4210.831566737327;4291.420658211148;4340.8697739067875;4474.82100715971;4491.550844915499;3467.8439470223775;3711.6288371162805;4114.423980642712;4490.124425736983;4504.121696269229;3691.8338674759702;4447.266201076818;4470.027431757877;4480.462344518646;4514.659152227345;4040.2121586290687;4127.463429754128;4380.299152135643;4430.269005614102;4479.641628669715;3735.34631439497;3743.644353786386;3756.8961381626377;3783.9432408513894;3810.1808111255937;5738.311402631669;5754.3094828620415;5758.3665796762325;5778.971105144489;5799.507041901444;4515.593596576324;5030.773615187325;5107.106256405114;5116.590672746172;5147.974260128675;6344.777932772331;6367.140436041122;6387.872242555123;6410.391012853772;6412.967935160341;4165.291900376913;4203.894902627429;4206.579342065769;4217.40956266123;4234.449521562177;5646.20953066571;5879.794207202727;6224.190855188796;6375.426211640928;6425.261094974045;3461.723062155072;3903.2825556754938;3905.5703872573995;4067.5322337931516;4291.849785257501;3921.509811273591;4270.210011147948;4324.286492728727;4333.7833108345085;4349.891252718644;4362.607365337135;4850.417949845971;5115.746642072716;5152.227165925141;5168.715720635401;5105.055564720538;6470.417662410406;6508.153939987771;6529.886171711792;6667.599944003376;1180.5867946218928;1388.5695434415363;1406.8241469816273;1498.5803974887049;1508.2730273836396;855.4653341661461;897.5422534507449;898.9955050224708;950.5817440326234;971.9271054670928;4723.622110231173;4960.925586116211;5842.357340692521;5923.200367950271;5940.554458415636;5757.942420575824;6471.543902621873;6619.867602648016;6637.933620663732;6642.236142843538;4666.883327916829;4681.485036645966;5572.888542229132;6015.729292181863;6067.150598916155;3371.5279860819505;3492.2491664291792;3511.701505372072;3556.78659280444;3569.821508924543;6765.526413151032;7055;7060.7881763547175;7135.785926422215;7183.281671832775;5988.820335389945;6891.827704307332;6918;6918.792436226921;6925.480588955848;5442.755076021588;6542.443892269211;6867.828304292333;7043.36609705129;7060.611666358297;5916.645001299936;6202.782902598387;6451.709673064817;6701.028350888996;6751.628660423724;5347.893042139213;6090.172948115636;6253;6392.62113438081;6667.533272670867;6943.9652801735665;6996.685149168401;7012.053372794608;7013.789586312419;7016;4212.831486740496;4276.743395396286;4285.292926667065;4303.569643035671;4312.310030395121;6165.260168779596;6535.836604084936;6686.231083425469;6707.128073350434;6719.664016799238;5691.886162276648;6502.382273683992;6503.382178693017;6511.38141876521;6511.804645860631;5368.812091576932;6480.384363485461;6486.870262594816;6527.3472652734345;6545.803625891231;5981.880362392641;6082.969585152223;6312.43188113072;6317.052442133717;6327.778527751507;5469.507744303032;5813.883722325445;6077.814826108866;6414.422701956846;6469.8706025880165;6114.48026917717;6255.530835187288;6296.496280297473;6320.083587879823;6351.682415879095 09:29:57 INFO - PROCESS | 1997 | |5;yui.html;666.9599824010556;730.6931088942647;734.8420089680718;738.3723834740472;796.1083586383252;14724.116553006827;14969.10185388876;15079.472218472356;15220.467283645075;15231.390744370226;14170.362333694982;14228.573142805732;14244.643883902907;14279;14281.642958926031;717.7918403662932;895.1272509303442;917.4953775423523;923.6213152607432;929.525941769699;912.2519533982137;917.0233701108332;917.124146440148;918.4581097152678;919.56780313253;950.1638558068889;968.593190859837;969.4425705219492;970.0784254957783;971.0580736685439;943.2783920300975;948.9193418559431;953.0421925964431;953.8950715421298;955.2930831184942;687.0793137196131;687.1342108942719;688.7589343729704;697.4873468001015;712.8788106021989;14836.184009879453;14853.48012819557;14889.70220595593;14900.776488352683;15063.849361506464;12841.35793210345;12964.416601252966;13025.869741302655;13032.152910060815;13238.139520931109;10101.69694909154;10255.7436064099;10353.965549876923;10451.634192803216;10557.944205579457;3514.9964520223484;3519.225770330523;3543.5039094526787;3597.784132952031;3631.782093074424;88568.55715721444;89683;89961.20077598476;90102.54948725346;90615;9673.467959262309;9679.612815487442;9736.659216927374;9968.80062398748;10031.598736050622;18773.7183942241;21238;22662.206822761123;22763.544729105324;22771.97526111329;7340.192578816433;7354.595497247596;7370.5946172961;7451.403887688972;7470.589117598475;3758.530183727034;3793.7723736575895;3891.8832435026993;3960.1089754805516;3974.3839704845795;3555.2711694102645;3591.066322756102;3641.0715267606593;3666.5600127984812;3670.8164591770837;656.6224420957968;670.3330186464499;672.559473544829;673.3703986772383;715;525.8343621759134;526.3210458508557;561.7612514681244;575.1459083261353;583.9182514447981;6680.5323627345615;7892.171322011221;8045.235702608243;8189.303909167787;8282.17178282179;503.67009608706354;513.2199057432756;558.6340946679932;559.6753882747996;567.503434494817;3868.2263547290654;3919.5688474268377;3935.940960885589;3947.467091942551;3962.9405558916637;89976;91199.54400227957;91451.62822557667;91694;93363;65014;68007.65996170086;79202.81195782068;79822.60088699636;81234.37531249342;62462;64198.0370294446;64374;64847;66610.3338966604;8101.675932962616;8184.754457366289;8363.456375335583;8379.07830138672;8485.915140848512;28450.146495605164;29640.110796676134;37485.688000919836;39001.804990974866;40010.19979600333;57236.71381643065;57464.42535574758;57900.71049644893;58796.706016471355;61857;10841.403722795321;10845.891541084487;10902.618408355669;10980.066694331072;11177.317443813263;11592.88407115918;11701.824472632747;11760;11878.168528203289;11967.700807479883;11062.17033722458;11071.114310854951;11212.943935280271;11315.490802913724;11562.190646654653;11224.551017959517;11380.658580242605;11536.365499897585;11550;11781.705457363292;10917;10976.560937562408;11255.832423309968;11261.549538018715;11388.202825802446;9692.472308637081;9764.9023509764;9942.801143977225;9983.400995940267;10275.332103413255;9607.327487075838;9691.321607487691;9705;9742.853857192289;9783.36408133469 09:29:57 INFO - PROCESS | 1997 | __end_tp_report 09:29:57 INFO - PROCESS | 1997 | __start_cc_report 09:29:57 INFO - PROCESS | 1997 | _x_x_mozilla_cycle_collect,387 09:29:57 INFO - PROCESS | 1997 | __end_cc_report 09:29:57 INFO - PROCESS | 1997 | __startTimestamp1460651397628__endTimestamp 09:29:57 INFO - PROCESS | 1997 | 09:29:57 INFO - PROCESS | 1997 | ------- Summary: start ------- 09:29:57 INFO - PROCESS | 1997 | Number of tests: 6 09:29:57 INFO - PROCESS | 1997 | 09:29:57 INFO - PROCESS | 1997 | [#0] dojo.html Cycles:165 Average:5569.01 Median:5804.00 stddev:681.44 (11.7%) stddev-sans-first:648.43 09:29:57 INFO - PROCESS | 1997 | Values: 2816.9 3382.8 3404.4 3433.3 3460.3 4631.2 4884.8 5551.1 5589.0 5634.7 5498.4 5512.1 5518.6 5583.3 5716.4 5012.0 5074.1 5138.6 5176.9 5198.8 4985.9 5074.6 5080.1 5110.3 5152.6 4975.6 5065.3 5067.6 5094.9 5109.8 5053.6 5063.3 5068.2 5090.6 5138.8 4953.6 5018.0 5043.4 5056.4 5093.9 4472.7 5374.3 5386.9 5456.9 5460.7 5277.6 6112.0 6123.5 6132.5 6159.2 6037.4 6134.6 6146.7 6156.4 6186.7 5680.2 5695.4 5702.3 5704.5 5705.7 6174.6 6178.3 6180.6 6185.2 6195.1 5913.3 5989.8 6020.1 6029.4 6096.5 6033.8 6093.7 6099.9 6103.2 6104.2 6065.1 6074.7 6091.5 6115.1 6163.1 6025.0 6025.2 6073.8 6135.5 6141.8 3760.8 4451.9 4741.9 4843.3 4917.9 3859.4 4365.4 4371.3 4387.8 4461.4 5495.8 5551.4 5553.8 5625.6 5652.5 5002.8 5069.2 6080.9 6082.6 6098.5 5375.2 5502.7 5600.8 5607.0 5609.9 4457.4 4520.4 5293.7 5323.4 5330.8 6256.6 6259.2 6264.5 6280.0 6322.2 6071.2 6137.6 6140.2 6151.4 6156.8 6190.5 6249.2 6255.3 6263.6 6265.5 6037.5 6042.5 6079.1 6089.6 6109.7 5993.8 6084.9 6138.0 6146.9 6158.8 4864.9 5939.6 5990.0 6011.4 6065.8 5900.5 5952.5 5952.6 6000.5 6021.1 5671.8 5795.7 5804.0 5854.2 5865.6 5825.7 5972.0 6006.4 6027.5 6102.3 5792.9 5813.0 5856.1 5861.7 5869.3 09:29:57 INFO - PROCESS | 1997 | 09:29:57 INFO - PROCESS | 1997 | [#1] ext.html Cycles:180 Average:67148.58 Median:39066.90 stddev:146724.11 (375.6%) stddev-sans-first:147050.29 09:29:57 INFO - PROCESS | 1997 | Values: 463.3 482.5 509.6 518.0 581.8 37118.0 38147.0 38169.2 38226.8 38228.0 39810.0 40275.0 40452.4 40844.6 41177.4 9364.2 9631.7 9646.8 9659.5 9756.9 9327.4 9429.3 9439.8 9558.5 9573.4 9539.3 9561.1 9612.4 9627.6 9646.1 9535.7 9595.6 9610.4 9612.0 9622.6 8206.9 9501.3 9551.9 9652.4 9697.7 39022.0 39111.8 39489.4 39838.8 39982.0 2621.9 3225.9 3242.4 3477.3 3657.9 28227.6 28366.4 28396.6 29147.9 29267.9 2724.0 2729.1 2755.4 2797.5 2802.8 392220.0 415856.0 434062.8 471473.0 474289.0 48048.0 49087.8 58272.4 59324.7 59417.0 56851.0 62548.1 65798.0 67377.3 67398.7 16265.5 16285.3 16418.3 16448.0 16531.0 12249.5 12443.1 12621.1 12641.9 12776.9 17129.0 18856.0 18922.6 18983.8 19410.3 8060.9 8221.2 8234.4 8269.3 8301.9 902.4 913.3 937.4 952.4 1017.8 21412.8 28227.9 29826.4 29876.9 29930.7 905.8 1010.3 1083.6 1123.4 1130.5 14682.4 14857.6 14875.5 15035.9 15301.3 631126.0 632296.0 638753.0 640192.0 643525.0 41685.0 44536.0 58601.1 58768.0 58774.7 1110.5 1415.0 1429.4 1448.0 1448.7 28848.7 28939.7 28961.3 29183.7 29439.9 49986.0 50423.0 50442.0 50536.7 50541.7 437352.0 575926.0 577125.0 577926.0 578031.0 26136.3 26212.5 26278.3 26479.2 26644.9 45057.8 45291.0 45379.8 45542.6 47143.5 33547.5 37290.0 37555.0 38216.6 38558.4 36927.4 36988.8 37022.0 37811.0 37928.0 36741.0 37779.1 38359.4 38492.0 38624.4 28783.0 29035.9 32099.8 32117.0 32236.4 23978.0 24189.9 24520.0 24851.0 25209.2 09:29:57 INFO - PROCESS | 1997 | 09:29:57 INFO - PROCESS | 1997 | [#2] jquery.html Cycles:180 Average:34119.78 Median:2241.04 stddev:100584.36 (4488.3%) stddev-sans-first:100834.55 09:29:57 INFO - PROCESS | 1997 | Values: 337.6 380.5 397.0 402.6 411.0 14917.3 18237.1 20019.9 20080.7 20092.1 9558.8 12261.8 19409.6 19767.3 20093.5 4821.0 5224.5 5272.3 5359.5 5418.9 3919.8 4545.0 5939.2 5948.6 5958.7 4734.9 5498.8 5684.9 5928.6 5948.5 5804.8 5853.0 5939.2 5947.5 5963.3 5540.3 5619.9 5658.9 5731.0 5749.1 12361.0 13478.2 13585.3 13593.9 13668.6 2105.3 2143.1 2218.5 2263.6 2744.6 9463.7 9511.0 9610.8 9660.0 9886.7 1442.6 1491.8 1705.4 1717.3 1718.0 119981.0 125519.0 126741.4 126917.0 129110.0 10433.8 11040.7 11642.7 11821.8 11877.4 14971.3 15072.3 15087.6 15118.3 15181.0 8580.7 8685.3 8714.2 8759.2 8794.9 8375.2 8537.3 8544.1 8565.9 8595.9 3881.4 4560.4 4598.7 4633.4 4685.7 1340.4 1777.2 1887.0 1938.3 1974.4 894.4 909.7 929.3 937.5 1025.2 19743.6 20358.0 20395.0 20507.9 20807.6 853.4 935.0 946.9 959.1 1037.3 10514.2 10791.0 10793.8 10822.8 10832.9 536630.0 611804.0 613779.0 615185.0 630420.0 67742.7 108877.0 133188.0 134011.3 138417.2 1003.0 1065.9 1074.9 1083.7 1083.9 17927.6 18020.5 18045.3 18136.7 18173.9 33500.7 48080.8 49965.0 51261.5 51445.5 73618.0 74007.0 74235.0 75393.0 78089.9 6209.0 7579.1 7962.1 8130.6 8272.6 10091.9 10734.0 11803.4 12487.5 12909.4 10638.0 10727.4 10898.1 11246.7 11369.5 10026.5 10833.3 10897.2 11112.1 11120.2 10951.8 11215.6 11505.3 11527.8 11546.9 8098.4 8140.2 8235.9 8244.3 8496.9 9008.5 9333.4 10557.7 11233.7 11463.9 09:29:57 INFO - PROCESS | 1997 | 09:29:57 INFO - PROCESS | 1997 | [#3] mootools.html Cycles:180 Average:5742.00 Median:4879.21 stddev:3205.00 (65.7%) stddev-sans-first:3197.65 09:29:57 INFO - PROCESS | 1997 | Values: 1436.6 1626.3 1866.4 1900.9 1978.4 8125.4 8656.8 8674.0 8941.3 8968.6 8641.8 8689.7 8761.6 8770.7 8791.7 2447.4 2460.8 2472.3 2482.2 2507.4 2426.5 2482.0 2492.0 2493.6 2497.8 1997.4 2454.1 2458.2 2464.2 2495.2 2440.3 2444.2 2462.3 2463.8 2475.8 2433.3 2441.2 2441.8 2447.2 2450.2 8825.2 8934.6 8945.4 9117.4 9134.4 5270.4 5433.9 5472.5 5473.3 5486.1 3644.9 3650.1 3658.0 3678.4 3682.9 902.4 1033.2 1052.3 1052.5 1086.7 9827.0 11344.4 11884.4 11894.8 11918.4 4076.2 4687.7 4713.2 4925.8 4961.8 8911.2 10071.3 10078.0 10096.3 10127.4 5357.2 5436.9 5443.2 5471.6 5478.8 4471.5 4786.7 4932.9 4964.1 4965.0 4068.2 4068.9 4078.4 4094.2 4094.8 1255.7 1353.7 1354.6 1360.6 1370.5 803.9 819.7 820.5 906.4 922.2 5734.5 5785.7 5842.6 5933.7 6143.2 442.9 449.2 462.3 463.8 465.6 3949.8 3957.5 3991.6 4044.2 4065.8 10440.4 10669.1 10792.3 10952.5 10989.6 10812.2 10845.2 10893.6 10985.9 11024.5 7925.6 8171.6 8226.7 8286.0 8322.7 5750.8 5876.0 5882.7 5912.2 5937.9 8919.6 9932.2 9988.3 10020.7 10090.4 9812.7 10013.9 10245.3 10282.8 10346.9 5710.9 7387.9 7562.3 7578.8 7623.5 8368.3 8373.7 8390.6 8442.7 8475.2 7419.9 7532.9 7558.8 7661.0 7678.6 7462.3 7473.8 7559.0 7626.9 7713.8 7085.6 7144.7 7198.2 7207.0 7228.7 4720.5 4787.7 4872.7 4919.0 5027.2 4885.8 4942.4 5014.2 5014.4 5135.1 09:29:57 INFO - PROCESS | 1997 | 09:29:57 INFO - PROCESS | 1997 | [#4] prototype.html Cycles:180 Average:5114.90 Median:5789.24 stddev:1665.97 (28.8%) stddev-sans-first:1631.29 09:29:57 INFO - PROCESS | 1997 | Values: 318.7 320.4 328.1 329.8 330.1 5079.9 5863.9 6018.4 6120.9 6317.7 4412.9 4659.7 5449.6 6003.3 6047.1 4210.8 4291.4 4340.9 4474.8 4491.6 3467.8 3711.6 4114.4 4490.1 4504.1 3691.8 4447.3 4470.0 4480.5 4514.7 4040.2 4127.5 4380.3 4430.3 4479.6 3735.3 3743.6 3756.9 3783.9 3810.2 5738.3 5754.3 5758.4 5779.0 5799.5 4515.6 5030.8 5107.1 5116.6 5148.0 6344.8 6367.1 6387.9 6410.4 6413.0 4165.3 4203.9 4206.6 4217.4 4234.4 5646.2 5879.8 6224.2 6375.4 6425.3 3461.7 3903.3 3905.6 4067.5 4291.8 3921.5 4270.2 4324.3 4333.8 4349.9 4362.6 4850.4 5115.7 5152.2 5168.7 5105.1 6470.4 6508.2 6529.9 6667.6 1180.6 1388.6 1406.8 1498.6 1508.3 855.5 897.5 899.0 950.6 971.9 4723.6 4960.9 5842.4 5923.2 5940.6 5757.9 6471.5 6619.9 6637.9 6642.2 4666.9 4681.5 5572.9 6015.7 6067.2 3371.5 3492.2 3511.7 3556.8 3569.8 6765.5 7055.0 7060.8 7135.8 7183.3 5988.8 6891.8 6918.0 6918.8 6925.5 5442.8 6542.4 6867.8 7043.4 7060.6 5916.6 6202.8 6451.7 6701.0 6751.6 5347.9 6090.2 6253.0 6392.6 6667.5 6944.0 6996.7 7012.1 7013.8 7016.0 4212.8 4276.7 4285.3 4303.6 4312.3 6165.3 6535.8 6686.2 6707.1 6719.7 5691.9 6502.4 6503.4 6511.4 6511.8 5368.8 6480.4 6486.9 6527.3 6545.8 5981.9 6083.0 6312.4 6317.1 6327.8 5469.5 5813.9 6077.8 6414.4 6469.9 6114.5 6255.5 6296.5 6320.1 6351.7 09:29:57 INFO - PROCESS | 1997 | 09:29:57 INFO - PROCESS | 1997 | [#5] yui.html Cycles:180 Average:17631.95 Median:57350.57 stddev:24954.71 (43.5%) stddev-sans-first:24992.21 09:29:57 INFO - PROCESS | 1997 | Values: 667.0 730.7 734.8 738.4 796.1 14724.1 14969.1 15079.5 15220.5 15231.4 14170.4 14228.6 14244.6 14279.0 14281.6 717.8 895.1 917.5 923.6 929.5 912.3 917.0 917.1 918.5 919.6 950.2 968.6 969.4 970.1 971.1 943.3 948.9 953.0 953.9 955.3 687.1 687.1 688.8 697.5 712.9 14836.2 14853.5 14889.7 14900.8 15063.8 12841.4 12964.4 13025.9 13032.2 13238.1 10101.7 10255.7 10354.0 10451.6 10557.9 3515.0 3519.2 3543.5 3597.8 3631.8 88568.6 89683.0 89961.2 90102.5 90615.0 9673.5 9679.6 9736.7 9968.8 10031.6 18773.7 21238.0 22662.2 22763.5 22772.0 7340.2 7354.6 7370.6 7451.4 7470.6 3758.5 3793.8 3891.9 3960.1 3974.4 3555.3 3591.1 3641.1 3666.6 3670.8 656.6 670.3 672.6 673.4 715.0 525.8 526.3 561.8 575.1 583.9 6680.5 7892.2 8045.2 8189.3 8282.2 503.7 513.2 558.6 559.7 567.5 3868.2 3919.6 3935.9 3947.5 3962.9 89976.0 91199.5 91451.6 91694.0 93363.0 65014.0 68007.7 79202.8 79822.6 81234.4 62462.0 64198.0 64374.0 64847.0 66610.3 8101.7 8184.8 8363.5 8379.1 8485.9 28450.1 29640.1 37485.7 39001.8 40010.2 57236.7 57464.4 57900.7 58796.7 61857.0 10841.4 10845.9 10902.6 10980.1 11177.3 11592.9 11701.8 11760.0 11878.2 11967.7 11062.2 11071.1 11212.9 11315.5 11562.2 11224.6 11380.7 11536.4 11550.0 11781.7 10917.0 10976.6 11255.8 11261.5 11388.2 9692.5 9764.9 9942.8 9983.4 10275.3 9607.3 9691.3 9705.0 9742.9 9783.4 09:29:57 INFO - PROCESS | 1997 | -------- Summary: end -------- 09:29:57 INFO - PROCESS | 1997 | 09:29:58 INFO - TEST-INFO | 1997: exit 0 09:29:58 INFO - TEST-OK | dromaeo_css | took 1110765ms 09:29:58 INFO - TEST-START | kraken 09:29:58 INFO - operating with platform_type : mac_ 09:29:58 INFO - Initialising browser for kraken test... 09:29:58 INFO - TEST-INFO | started process 2002 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpRafVQY/profile http://localhost:49397/getInfo.html) 09:30:00 INFO - PROCESS | 2002 | JavaScript error: jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 09:30:01 INFO - PROCESS | 2002 | __metrics Screen width/height:1600/1200 09:30:01 INFO - PROCESS | 2002 | colorDepth:24 09:30:01 INFO - PROCESS | 2002 | Browser inner width/height: 1024/689 09:30:01 INFO - PROCESS | 2002 | __metrics 09:30:01 ERROR - PROCESS | 2002 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 09:30:01 INFO - TEST-INFO | 2002: exit 0 09:30:01 INFO - Browser initialized. 09:30:01 INFO - Running cycle 1/1 for kraken test... 09:30:01 INFO - TEST-INFO | started process 2006 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpRafVQY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:30:13 INFO - PROCESS | 2006 | RSS: Main: 247746560 09:30:13 INFO - PROCESS | 2006 | 09:30:16 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49397/tests/kraken/driver.html?testName=audio-beat-detection) 09:30:17 INFO - PROCESS | 2006 | RSS: Main: 260120576 09:30:17 INFO - PROCESS | 2006 | 09:30:20 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49397/tests/kraken/driver.html?testName=audio-dft) 09:30:20 INFO - PROCESS | 2006 | RSS: Main: 269164544 09:30:20 INFO - PROCESS | 2006 | 09:30:24 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49397/tests/kraken/driver.html?testName=audio-fft) 09:30:24 INFO - PROCESS | 2006 | RSS: Main: 272228352 09:30:24 INFO - PROCESS | 2006 | 09:30:27 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49397/tests/kraken/driver.html?testName=audio-oscillator) 09:30:27 INFO - PROCESS | 2006 | RSS: Main: 273838080 09:30:27 INFO - PROCESS | 2006 | 09:30:29 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49397/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:30:30 INFO - PROCESS | 2006 | RSS: Main: 273780736 09:30:30 INFO - PROCESS | 2006 | 09:30:33 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49397/tests/kraken/driver.html?testName=imaging-darkroom) 09:30:34 INFO - PROCESS | 2006 | RSS: Main: 273436672 09:30:34 INFO - PROCESS | 2006 | 09:30:37 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49397/tests/kraken/driver.html?testName=imaging-desaturate) 09:30:37 INFO - PROCESS | 2006 | RSS: Main: 274931712 09:30:37 INFO - PROCESS | 2006 | 09:30:40 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49397/tests/kraken/driver.html?testName=json-parse-financial) 09:30:41 INFO - PROCESS | 2006 | RSS: Main: 275320832 09:30:41 INFO - PROCESS | 2006 | 09:30:43 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49397/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:30:43 INFO - PROCESS | 2006 | RSS: Main: 275189760 09:30:43 INFO - PROCESS | 2006 | 09:30:45 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49397/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:30:46 INFO - PROCESS | 2006 | RSS: Main: 275083264 09:30:46 INFO - PROCESS | 2006 | 09:30:48 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49397/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:30:49 INFO - PROCESS | 2006 | RSS: Main: 275226624 09:30:49 INFO - PROCESS | 2006 | 09:30:52 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49397/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:30:52 INFO - PROCESS | 2006 | RSS: Main: 275550208 09:30:52 INFO - PROCESS | 2006 | 09:30:55 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49397/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:30:56 INFO - PROCESS | 2006 | RSS: Main: 275238912 09:30:56 INFO - PROCESS | 2006 | 09:30:57 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49397/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49397/tests/kraken/driver.html?testName=ai-astar) 09:30:58 INFO - PROCESS | 2006 | RSS: Main: 276561920 09:30:58 INFO - PROCESS | 2006 | 09:30:58 INFO - PROCESS | 2006 | __start_tp_report 09:30:58 INFO - PROCESS | 2006 | _x_x_mozilla_page_load 09:30:58 INFO - PROCESS | 2006 | _x_x_mozilla_page_load_details 09:30:58 INFO - PROCESS | 2006 | |i|pagename|runs| 09:30:58 INFO - PROCESS | 2006 | |0;ai-astar;117;113;114;116;115;126;114;114;116;115 09:30:58 INFO - PROCESS | 2006 | |1;audio-beat-detection;153;124;148;167;127;177;171;153;204;125 09:30:58 INFO - PROCESS | 2006 | |2;audio-dft;194;201;211;253;275;264;200;201;198;195 09:30:58 INFO - PROCESS | 2006 | |3;audio-fft;65;81;81;172;86;80;80;88;130;72 09:30:58 INFO - PROCESS | 2006 | |4;audio-oscillator;79;96;146;114;122;109;122;77;80;123 09:30:58 INFO - PROCESS | 2006 | |5;imaging-gaussian-blur;137;132;89;96;135;84;117;76;137;130 09:30:58 INFO - PROCESS | 2006 | |6;imaging-darkroom;94;117;96;96;95;93;96;96;102;96 09:30:58 INFO - PROCESS | 2006 | |7;imaging-desaturate;116;83;147;147;135;91;91;145;149;92 09:30:58 INFO - PROCESS | 2006 | |8;json-parse-financial;58;61;59;59;61;61;60;79;75;90 09:30:58 INFO - PROCESS | 2006 | |9;json-stringify-tinderbox;66;71;76;77;96;73;60;55;61;103 09:30:58 INFO - PROCESS | 2006 | |10;stanford-crypto-aes;90;89;86;86;87;89;90;87;87;86 09:30:58 INFO - PROCESS | 2006 | |11;stanford-crypto-ccm;181;157;158;143;145;146;144;146;219;151 09:30:58 INFO - PROCESS | 2006 | |12;stanford-crypto-pbkdf2;174;180;200;171;169;175;171;173;172;178 09:30:58 INFO - PROCESS | 2006 | |13;stanford-crypto-sha256-iterative;68;68;70;66;72;68;69;69;68;67 09:30:58 INFO - PROCESS | 2006 | __end_tp_report 09:30:58 INFO - PROCESS | 2006 | __start_cc_report 09:30:58 INFO - PROCESS | 2006 | _x_x_mozilla_cycle_collect,975 09:30:58 INFO - PROCESS | 2006 | __end_cc_report 09:30:58 INFO - PROCESS | 2006 | __startTimestamp1460651458216__endTimestamp 09:30:58 INFO - PROCESS | 2006 | 09:30:58 INFO - PROCESS | 2006 | ------- Summary: start ------- 09:30:58 INFO - PROCESS | 2006 | Number of tests: 14 09:30:58 INFO - PROCESS | 2006 | 09:30:58 INFO - PROCESS | 2006 | [#0] ai-astar Cycles:10 Average:116.00 Median:115.50 stddev:3.71 (3.2%) stddev-sans-first:3.92 09:30:58 INFO - PROCESS | 2006 | Values: 117.0 113.0 114.0 116.0 115.0 126.0 114.0 114.0 116.0 115.0 09:30:58 INFO - PROCESS | 2006 | 09:30:58 INFO - PROCESS | 2006 | [#1] audio-beat-detection Cycles:10 Average:154.90 Median:160.00 stddev:25.79 (16.1%) stddev-sans-first:27.35 09:30:58 INFO - PROCESS | 2006 | Values: 153.0 124.0 148.0 167.0 127.0 177.0 171.0 153.0 204.0 125.0 09:30:58 INFO - PROCESS | 2006 | 09:30:58 INFO - PROCESS | 2006 | [#2] audio-dft Cycles:10 Average:219.20 Median:206.00 stddev:31.68 (15.4%) stddev-sans-first:32.26 09:30:58 INFO - PROCESS | 2006 | Values: 194.0 201.0 211.0 253.0 275.0 264.0 200.0 201.0 198.0 195.0 09:30:58 INFO - PROCESS | 2006 | 09:30:58 INFO - PROCESS | 2006 | [#3] audio-fft Cycles:10 Average:93.50 Median:80.50 stddev:32.54 (40.4%) stddev-sans-first:32.84 09:30:58 INFO - PROCESS | 2006 | Values: 65.0 81.0 81.0 172.0 86.0 80.0 80.0 88.0 130.0 72.0 09:30:58 INFO - PROCESS | 2006 | 09:30:58 INFO - PROCESS | 2006 | [#4] audio-oscillator Cycles:10 Average:106.80 Median:111.50 stddev:23.13 (20.7%) stddev-sans-first:22.23 09:30:58 INFO - PROCESS | 2006 | Values: 79.0 96.0 146.0 114.0 122.0 109.0 122.0 77.0 80.0 123.0 09:30:58 INFO - PROCESS | 2006 | 09:30:58 INFO - PROCESS | 2006 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:106.50 stddev:24.44 (22.9%) stddev-sans-first:24.37 09:30:58 INFO - PROCESS | 2006 | Values: 137.0 132.0 89.0 96.0 135.0 84.0 117.0 76.0 137.0 130.0 09:30:58 INFO - PROCESS | 2006 | 09:30:58 INFO - PROCESS | 2006 | [#6] imaging-darkroom Cycles:10 Average:98.10 Median:96.00 stddev:7.05 (7.3%) stddev-sans-first:7.32 09:30:58 INFO - PROCESS | 2006 | Values: 94.0 117.0 96.0 96.0 95.0 93.0 96.0 96.0 102.0 96.0 09:30:58 INFO - PROCESS | 2006 | 09:30:58 INFO - PROCESS | 2006 | [#7] imaging-desaturate Cycles:10 Average:119.60 Median:116.00 stddev:27.89 (24.0%) stddev-sans-first:29.55 09:30:58 INFO - PROCESS | 2006 | Values: 116.0 83.0 147.0 147.0 135.0 91.0 91.0 145.0 149.0 92.0 09:30:58 INFO - PROCESS | 2006 | 09:30:58 INFO - PROCESS | 2006 | [#8] json-parse-financial Cycles:10 Average:66.30 Median:61.00 stddev:11.05 (18.1%) stddev-sans-first:11.30 09:30:58 INFO - PROCESS | 2006 | Values: 58.0 61.0 59.0 59.0 61.0 61.0 60.0 79.0 75.0 90.0 09:30:58 INFO - PROCESS | 2006 | 09:30:58 INFO - PROCESS | 2006 | [#9] json-stringify-tinderbox Cycles:10 Average:73.80 Median:72.00 stddev:15.41 (21.4%) stddev-sans-first:16.09 09:30:58 INFO - PROCESS | 2006 | Values: 66.0 71.0 76.0 77.0 96.0 73.0 60.0 55.0 61.0 103.0 09:30:58 INFO - PROCESS | 2006 | 09:30:58 INFO - PROCESS | 2006 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:88.00 stddev:1.64 (1.9%) stddev-sans-first:1.51 09:30:58 INFO - PROCESS | 2006 | Values: 90.0 89.0 86.0 86.0 87.0 89.0 90.0 87.0 87.0 86.0 09:30:58 INFO - PROCESS | 2006 | 09:30:58 INFO - PROCESS | 2006 | [#11] stanford-crypto-ccm Cycles:10 Average:159.00 Median:154.00 stddev:23.96 (15.6%) stddev-sans-first:24.06 09:30:58 INFO - PROCESS | 2006 | Values: 181.0 157.0 158.0 143.0 145.0 146.0 144.0 146.0 219.0 151.0 09:30:58 INFO - PROCESS | 2006 | 09:30:58 INFO - PROCESS | 2006 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.30 Median:174.50 stddev:8.97 (5.1%) stddev-sans-first:9.48 09:30:58 INFO - PROCESS | 2006 | Values: 174.0 180.0 200.0 171.0 169.0 175.0 171.0 173.0 172.0 178.0 09:30:58 INFO - PROCESS | 2006 | 09:30:58 INFO - PROCESS | 2006 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.50 stddev:1.65 (2.4%) stddev-sans-first:1.74 09:30:58 INFO - PROCESS | 2006 | Values: 68.0 68.0 70.0 66.0 72.0 68.0 69.0 69.0 68.0 67.0 09:30:58 INFO - PROCESS | 2006 | -------- Summary: end -------- 09:30:58 INFO - PROCESS | 2006 | 09:30:58 INFO - TEST-INFO | 2006: exit 0 09:30:59 INFO - TEST-OK | kraken | took 60847ms 09:30:59 INFO - SUITE-END | took 1171s 09:30:59 INFO - Completed test suite (00:19:31) 09:30:59 INFO - javascript benchmark 09:30:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2816.88732450702, 3382.7801192922475, 3404.3872103021463, 3433.296174284272, 3460.3252365788667, 4631.235846085392, 4884.803223210319, 5551.1118221084635, 5589.0, 5634.661920284786, 5498.422665620103, 5512.145617429295, 5518.586106042043, 5583.274174357338, 5716.371199168098, 5011.974940125312, 5074.086664400406, 5138.6146039047035, 5176.948230517703, 5198.844034678966, 4985.900281994358, 5074.568661663763, 5080.060188865072, 5110.335656364686, 5152.639315247934, 4975.552200301972, 5065.316182315377, 5067.569256613192, 5094.898102037957, 5109.846704598868, 5053.5957123430035, 5063.26582645518, 5068.239764035387, 5090.5927525798215, 5138.768755406016, 4953.5541801237705, 5017.974910125463, 5043.394792624872, 5056.418511871108, 5093.923591146098, 4472.686911916167, 5374.3013408256675, 5386.892262154735, 5456.881339325429, 5460.672359658433, 5277.577793776527, 6112.0, 6123.479504242145, 6132.50939924805, 6159.19930409044, 6037.396260373927, 6134.631922084644, 6146.692665366714, 6156.3843615638525, 6186.690665466754, 5680.233168522279, 5695.373508914016, 5702.287214098238, 5704.543636509069, 5705.657660540381, 6174.598651087665, 6178.320384757673, 6180.598261113013, 6185.154828778722, 6195.070739389038, 5913.29040515141, 5989.790357337472, 6020.09698545213, 6029.366916473795, 6096.542759293071, 6033.758649653964, 6093.69531523422, 6099.9085013724825, 6103.23709536308, 6104.20645316106, 6065.120557519135, 6074.665893375818, 6091.451769340692, 6115.052166914135, 6163.137160797402, 6024.969875150597, 6025.246844144482, 6073.817785466443, 6135.538906165067, 6141.785037523665, 3760.8341414161696, 4451.888702782391, 4741.857744267678, 4843.321934929113, 4917.901641967141, 3859.421086836997, 4365.432493775789, 4371.278739008092, 4387.846425375161, 4461.442319710036, 5495.835124946258, 5551.3893471718075, 5553.805616803474, 5625.606207565513, 5652.491275785199, 5002.774875130628, 5069.239614057921, 6080.939190608037, 6082.60463069899, 6098.54260930432, 5375.16684913839, 5502.724863756796, 5600.831975040755, 5607.046802043662, 5609.859753506195, 4457.420535330386, 4520.412346394947, 5293.735313234323, 5323.387810401776, 5330.760115794798, 6256.593321434092, 6259.217597800275, 6264.498840092872, 6279.963805971964, 6322.241331040304, 6071.241094863142, 6137.55767394653, 6140.201773769381, 6151.446369826737, 6156.78451254204, 6190.50475961913, 6249.187605611241, 6255.311915689362, 6263.592866463848, 6265.498760099273, 6037.486813620892, 6042.516598672184, 6079.148919151411, 6089.5737298388685, 6109.725062372115, 5993.820185394445, 6084.939150608438, 6137.96931015342, 6146.8935591593945, 6158.846028849314, 4864.927026094682, 5939.643621382731, 5989.970050149721, 6011.428914253139, 6065.848353791191, 5900.468957793819, 5952.464278214981, 5952.553558483044, 6000.549958753024, 6021.06673465596, 5671.829845104653, 5795.7391917362975, 5804.0, 5854.180414742024, 5865.560082993878, 5825.737841797045, 5971.970140149271, 6006.429389208019, 6027.487663548647, 6102.328743838086, 5792.94207057924, 5813.0, 5856.092305692625, 5861.736221870111, 5869.325027621708], "value": 5525.224017573064, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.31892118585665, 482.5102520941243, 509.55668568345544, 517.9637425380225, 581.7643854239031, 37118.0, 38147.0, 38169.23661526771, 38226.80886595563, 38228.0, 39810.0047498812, 40274.99312517189, 40452.39321410181, 40844.59155408445, 41177.382339264936, 9364.157225849689, 9631.66289179879, 9646.75883102921, 9659.517024148765, 9756.853647195263, 9327.393719408217, 9429.339946203769, 9439.81120377592, 9558.474283914382, 9573.42559446432, 9539.332246742732, 9561.091696288842, 9612.423254604746, 9627.614895404175, 9646.131848133666, 9535.666251681192, 9595.616175352978, 9610.375325603849, 9612.0, 9622.566984485715, 8206.876896846552, 9501.287403444769, 9551.856722149174, 9652.420854748667, 9697.709068727949, 39022.0, 39111.80444097761, 39489.42042318304, 39838.80080599608, 39982.0, 2621.8689065546837, 3225.903222903317, 3242.3515296940705, 3477.3393055319407, 3657.8719744808805, 28227.576586351017, 28366.432671346458, 28396.574051389245, 29147.85426072877, 29267.853660731562, 2723.986380068087, 2729.0993971989396, 2755.3938133610573, 2797.5384061629807, 2802.8178168418985, 392220.0, 415855.96936199354, 434062.82968584955, 471473.0, 474288.99999999657, 48048.0, 49087.75456122697, 58272.4172758267, 59324.7033764837, 59417.0, 56851.0, 62548.06177907335, 65798.0, 67377.32622673809, 67398.66300668563, 16265.51203463898, 16285.348586056662, 16418.343266269454, 16448.0, 16531.0, 12249.510019599116, 12443.128980971425, 12621.053420993463, 12641.87358126407, 12776.85008349253, 17129.0, 18856.0, 18922.62154756897, 18983.81016189848, 19410.32063877786, 8060.919390806134, 8221.17788221173, 8234.382421318425, 8269.29710974574, 8301.916980830236, 902.4179404284331, 913.2739472119681, 937.4328531238502, 952.3857112162616, 1017.7913527726798, 21412.78587214139, 28227.858860705564, 29826.405068059972, 29876.850615746782, 29930.70069299323, 905.7871400220943, 1010.3129871687177, 1083.6292090505517, 1123.4438952718294, 1130.5251794246435, 14682.41270349174, 14857.554273371816, 14875.47935822241, 15035.924820375829, 15301.311440985182, 631126.0, 632296.0, 638753.0, 640192.0, 643525.0, 41685.0, 44536.0, 58601.0699465025, 58768.0, 58774.70612646909, 1110.4558766204502, 1415.0236336927487, 1429.371076726237, 1448.0081144416108, 1448.703015881763, 28848.7115128846, 28939.710602894545, 28961.254861239016, 29183.708162918098, 29439.85280073586, 49986.0, 50423.0, 50442.0, 50536.74731626319, 50541.74729126331, 437352.0, 575926.0, 577125.0, 577926.0, 578031.0, 26136.34659133537, 26212.475750485264, 26278.343041424116, 26479.205623831316, 26644.866775665996, 45057.774711126236, 45291.0, 45379.773101134284, 45542.63372098842, 47143.52856471392, 33547.496787547716, 37290.0, 37555.0, 38216.61783382242, 38558.42162367623, 36927.44608830816, 36988.81505592455, 37022.0, 37811.0, 37928.0, 36741.0, 37779.05552361213, 38359.42460863145, 38492.0, 38624.42063369108, 28783.02662051398, 29035.921343613212, 32099.790020997712, 32117.0, 32236.35527289488, 23978.04087836467, 24189.91145398426, 24520.0, 24851.0, 25209.24372268835], "value": 19380.515236484942, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.6353538178769, 380.4863434363609, 396.9940450893237, 402.62958078567715, 410.9835606575737, 14917.25413729312, 18237.088145592716, 20019.899900500495, 20080.698789518152, 20092.095855686493, 9558.808823823536, 12261.754764904697, 19409.61180776387, 19767.308144214956, 20093.497662558442, 4820.963492849037, 5224.529792318691, 5272.314599102111, 5359.517643412092, 5418.864528386783, 3919.8432062717457, 4545.0, 5939.227900372945, 5948.583599148062, 5958.731857066421, 4734.910970476775, 5498.80754173604, 5684.914726279109, 5928.584999050068, 5948.464638182562, 5804.767809287623, 5853.0, 5939.227900372967, 5947.464728174464, 5963.344032156481, 5540.252065971083, 5619.859503512404, 5658.91511627326, 5730.971345143289, 5749.0514065179195, 12361.0, 13478.19130852152, 13585.320733963263, 13593.864061359358, 13668.589942301747, 2105.2947262667026, 2143.0570548958563, 2218.4675677837363, 2263.6151854184827, 2744.588311753253, 9463.716088517287, 9511.0, 9610.75973100671, 9660.0, 9886.703398898044, 1442.5888621742859, 1491.843356447579, 1705.351966252816, 1717.2959086774363, 1717.9742303865453, 119981.0, 125519.0, 126741.36629316886, 126917.0, 129110.0, 10433.843492347622, 11040.723981900517, 11642.6507204784, 11821.758715334767, 11877.406129693654, 14971.251437428085, 15072.321745521478, 15087.622809429851, 15118.319675614624, 15181.0, 8580.699675511452, 8685.305175585938, 8714.21572058505, 8759.211670949646, 8794.86807697885, 8375.20435558621, 8537.317014638815, 8544.060153383123, 8565.871511927326, 8595.871061934076, 3881.4177873319227, 4560.384348113029, 4598.655100867449, 4633.374494443275, 4685.695429797052, 1340.3968214303602, 1777.2091419318538, 1886.9528261793564, 1938.2634598852346, 1974.4372853736616, 894.4409743910056, 909.7179874239007, 929.270522639738, 937.4796987671907, 1025.1542477456128, 19743.60512789736, 20357.982100894897, 20394.98025098739, 20507.8974605129, 20807.58384832295, 853.3770347646129, 934.990650093504, 946.9167830772328, 959.1128206409044, 1037.2790910317258, 10514.21143414247, 10791.0, 10793.784124317626, 10822.837657435144, 10832.945835270773, 536630.0, 611804.0, 613779.0, 615185.0, 630420.0, 67742.66128669423, 108877.0, 133188.0, 134011.32994334967, 138417.23165536835, 1002.979940401188, 1065.861438013069, 1074.919381046409, 1083.712816103749, 1083.8970297821695, 17927.64144717072, 18020.459386218434, 18045.278188872297, 18136.72794908104, 18173.90913045479, 33500.66499335073, 48080.759596201795, 49965.0, 51261.48738512567, 51445.48554514407, 73618.0, 74007.0, 74235.0, 75393.0, 78089.87640494316, 6209.006558950547, 7579.090509138939, 7962.124166341582, 8130.634121464666, 8272.627731751965, 10091.899081009096, 10734.0, 11803.409829508319, 12487.500499980262, 12909.419076141408, 10638.0, 10727.409992450179, 10898.128149747843, 11246.718832029264, 11369.54521819151, 10026.498675066072, 10833.349999000086, 10897.237193396386, 11112.111031117653, 11120.165987551129, 10951.83572246401, 11215.60745373908, 11505.252158609663, 11527.769444610893, 11546.94226528862, 8098.392620553364, 8140.185981401813, 8235.876461853197, 8244.299234564889, 8496.872546911678, 9008.459492430475, 9333.393329433566, 10557.73605659834, 11233.71915702114, 11463.885361146282], "value": 9102.620622212053, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1436.61929588659, 1626.284434848666, 1866.4493974277586, 1900.9334673286437, 1978.4163671716842, 8125.390595705331, 8656.826863462727, 8673.956630216848, 8941.329400294979, 8968.551572421376, 8641.827163456726, 8689.652413903452, 8761.56192190391, 8770.6930257441, 8791.736247912557, 2447.4003869052062, 2460.8400453970435, 2472.320111969205, 2482.2429159106464, 2507.448361360498, 2426.5268272686744, 2482.0, 2491.9626205606933, 2493.6384224287467, 2497.8376405533672, 1997.4407165993546, 2454.128784281581, 2458.176510868861, 2464.2114523352534, 2495.1641200197864, 2440.2679196241056, 2444.2422848916895, 2462.2613216035206, 2463.7659422354936, 2475.814313926454, 2433.2578563538113, 2441.218809980807, 2441.8046556275453, 2447.229122826304, 2450.2404254681105, 8825.249853762438, 8934.553272336358, 8945.373823832335, 9117.407368521024, 9134.36059475837, 5270.446603106695, 5433.918491222635, 5472.452754724535, 5473.343198816167, 5486.094794358926, 3644.8724294649824, 3650.0874781304674, 3658.0, 3678.429843374281, 3682.852685892561, 902.3818684201343, 1033.152814691953, 1052.315994603514, 1052.45272458322, 1086.6739978006644, 9826.968168342364, 11344.432778361132, 11884.40577971098, 11894.762104757856, 11918.404079795975, 4076.2458945095254, 4687.718736875799, 4713.175194340976, 4925.75371231437, 4961.7519124044375, 8911.15344042315, 10071.295009349422, 10078.042585954323, 10096.293259471766, 10127.442990635438, 5357.19642053695, 5436.864078397993, 5443.237946687467, 5471.6169868108855, 5478.835634930959, 4471.485779135441, 4786.664933454625, 4932.90134197314, 4964.081644895706, 4965.0, 4068.1590770860535, 4068.877933661995, 4078.4494093297617, 4094.160697057096, 4094.754314741125, 1255.7425727725795, 1353.661584603849, 1354.5597680753795, 1360.6258278973164, 1370.4792178972127, 803.9196080391914, 819.7171975668385, 820.4543978254394, 906.428949761646, 922.1746536849616, 5734.541236701054, 5785.71071446426, 5842.5618078644275, 5933.732982015819, 6143.201383820164, 442.92248856449993, 449.16007066785323, 462.2788450017983, 463.8399752085525, 465.5949324088065, 3949.8025098744947, 3957.5053118360206, 3991.646778042936, 4044.2315959967514, 4065.776382299002, 10440.37357758537, 10669.09312708413, 10792.298500597437, 10952.4523773811, 10989.61536346224, 10812.243142979916, 10845.186611004206, 10893.618723344645, 10985.890141098647, 11024.50389732464, 7925.603719814101, 8171.591420428955, 8226.670933162726, 8286.0056793184, 8322.70870519541, 5750.7987220447085, 5875.970620146872, 5882.735276912464, 5912.231409916683, 5937.881242375042, 8919.598618062073, 9932.205423565953, 9988.300818942604, 10020.749481262736, 10090.445025523373, 9812.65655702047, 10013.899861001588, 10245.334053286513, 10282.794344113225, 10346.948265258625, 5710.885782284248, 7387.852242955219, 7562.281583249583, 7578.772636820904, 7623.4663573549315, 8368.330533557422, 8373.748787536384, 8390.622421991147, 8442.70450534253, 8475.194856488624, 7419.851602967802, 7532.904365254569, 7558.848823023619, 7661.0, 7678.61606919663, 7462.328390444886, 7473.813154671177, 7558.962205188939, 7626.8855967159425, 7713.845723085619, 7085.574865508086, 7144.678489467882, 7198.20819709821, 7206.963965180141, 7228.674709638184, 4720.480747117883, 4787.712737235777, 4872.658913875995, 4918.975405122951, 5027.220780778839, 4885.755712214446, 4942.4069111705385, 5014.247862820606, 5014.398272207212, 5135.075686376488], "value": 4553.229913637903, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.708381830625, 320.4424301715015, 328.1304542961153, 329.8499182871794, 330.1482175985955, 5079.933214025054, 5863.9444899918, 6018.428249316314, 6120.908186377208, 6317.6841157942035, 4412.889677758058, 4659.673822832403, 5449.618526703132, 6003.30961939376, 6047.092936059593, 4210.831566737327, 4291.420658211148, 4340.8697739067875, 4474.82100715971, 4491.550844915499, 3467.8439470223775, 3711.6288371162805, 4114.423980642712, 4490.124425736983, 4504.121696269229, 3691.8338674759702, 4447.266201076818, 4470.027431757877, 4480.462344518646, 4514.659152227345, 4040.2121586290687, 4127.463429754128, 4380.299152135643, 4430.269005614102, 4479.641628669715, 3735.34631439497, 3743.644353786386, 3756.8961381626377, 3783.9432408513894, 3810.1808111255937, 5738.311402631669, 5754.3094828620415, 5758.3665796762325, 5778.971105144489, 5799.507041901444, 4515.593596576324, 5030.773615187325, 5107.106256405114, 5116.590672746172, 5147.974260128675, 6344.777932772331, 6367.140436041122, 6387.872242555123, 6410.391012853772, 6412.967935160341, 4165.291900376913, 4203.894902627429, 4206.579342065769, 4217.40956266123, 4234.449521562177, 5646.20953066571, 5879.794207202727, 6224.190855188796, 6375.426211640928, 6425.261094974045, 3461.723062155072, 3903.2825556754938, 3905.5703872573995, 4067.5322337931516, 4291.849785257501, 3921.509811273591, 4270.210011147948, 4324.286492728727, 4333.7833108345085, 4349.891252718644, 4362.607365337135, 4850.417949845971, 5115.746642072716, 5152.227165925141, 5168.715720635401, 5105.055564720538, 6470.417662410406, 6508.153939987771, 6529.886171711792, 6667.599944003376, 1180.5867946218928, 1388.5695434415363, 1406.8241469816273, 1498.5803974887049, 1508.2730273836396, 855.4653341661461, 897.5422534507449, 898.9955050224708, 950.5817440326234, 971.9271054670928, 4723.622110231173, 4960.925586116211, 5842.357340692521, 5923.200367950271, 5940.554458415636, 5757.942420575824, 6471.543902621873, 6619.867602648016, 6637.933620663732, 6642.236142843538, 4666.883327916829, 4681.485036645966, 5572.888542229132, 6015.729292181863, 6067.150598916155, 3371.5279860819505, 3492.2491664291792, 3511.701505372072, 3556.78659280444, 3569.821508924543, 6765.526413151032, 7055.0, 7060.7881763547175, 7135.785926422215, 7183.281671832775, 5988.820335389945, 6891.827704307332, 6918.0, 6918.792436226921, 6925.480588955848, 5442.755076021588, 6542.443892269211, 6867.828304292333, 7043.36609705129, 7060.611666358297, 5916.645001299936, 6202.782902598387, 6451.709673064817, 6701.028350888996, 6751.628660423724, 5347.893042139213, 6090.172948115636, 6253.0, 6392.62113438081, 6667.533272670867, 6943.9652801735665, 6996.685149168401, 7012.053372794608, 7013.789586312419, 7016.0, 4212.831486740496, 4276.743395396286, 4285.292926667065, 4303.569643035671, 4312.310030395121, 6165.260168779596, 6535.836604084936, 6686.231083425469, 6707.128073350434, 6719.664016799238, 5691.886162276648, 6502.382273683992, 6503.382178693017, 6511.38141876521, 6511.804645860631, 5368.812091576932, 6480.384363485461, 6486.870262594816, 6527.3472652734345, 6545.803625891231, 5981.880362392641, 6082.969585152223, 6312.43188113072, 6317.052442133717, 6327.778527751507, 5469.507744303032, 5813.883722325445, 6077.814826108866, 6414.422701956846, 6469.8706025880165, 6114.48026917717, 6255.530835187288, 6296.496280297473, 6320.083587879823, 6351.682415879095], "value": 4573.602179072806, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [666.9599824010556, 730.6931088942647, 734.8420089680718, 738.3723834740472, 796.1083586383252, 14724.116553006827, 14969.10185388876, 15079.472218472356, 15220.467283645075, 15231.390744370226, 14170.362333694982, 14228.573142805732, 14244.643883902907, 14279.0, 14281.642958926031, 717.7918403662932, 895.1272509303442, 917.4953775423523, 923.6213152607432, 929.525941769699, 912.2519533982137, 917.0233701108332, 917.124146440148, 918.4581097152678, 919.56780313253, 950.1638558068889, 968.593190859837, 969.4425705219492, 970.0784254957783, 971.0580736685439, 943.2783920300975, 948.9193418559431, 953.0421925964431, 953.8950715421298, 955.2930831184942, 687.0793137196131, 687.1342108942719, 688.7589343729704, 697.4873468001015, 712.8788106021989, 14836.184009879453, 14853.48012819557, 14889.70220595593, 14900.776488352683, 15063.849361506464, 12841.35793210345, 12964.416601252966, 13025.869741302655, 13032.152910060815, 13238.139520931109, 10101.69694909154, 10255.7436064099, 10353.965549876923, 10451.634192803216, 10557.944205579457, 3514.9964520223484, 3519.225770330523, 3543.5039094526787, 3597.784132952031, 3631.782093074424, 88568.55715721444, 89683.0, 89961.20077598476, 90102.54948725346, 90615.0, 9673.467959262309, 9679.612815487442, 9736.659216927374, 9968.80062398748, 10031.598736050622, 18773.7183942241, 21238.0, 22662.206822761123, 22763.544729105324, 22771.97526111329, 7340.192578816433, 7354.595497247596, 7370.5946172961, 7451.403887688972, 7470.589117598475, 3758.530183727034, 3793.7723736575895, 3891.8832435026993, 3960.1089754805516, 3974.3839704845795, 3555.2711694102645, 3591.066322756102, 3641.0715267606593, 3666.5600127984812, 3670.8164591770837, 656.6224420957968, 670.3330186464499, 672.559473544829, 673.3703986772383, 715.0, 525.8343621759134, 526.3210458508557, 561.7612514681244, 575.1459083261353, 583.9182514447981, 6680.5323627345615, 7892.171322011221, 8045.235702608243, 8189.303909167787, 8282.17178282179, 503.67009608706354, 513.2199057432756, 558.6340946679932, 559.6753882747996, 567.503434494817, 3868.2263547290654, 3919.5688474268377, 3935.940960885589, 3947.467091942551, 3962.9405558916637, 89976.0, 91199.54400227957, 91451.62822557667, 91694.0, 93363.0, 65014.0, 68007.65996170086, 79202.81195782068, 79822.60088699636, 81234.37531249342, 62462.0, 64198.0370294446, 64374.0, 64847.0, 66610.3338966604, 8101.675932962616, 8184.754457366289, 8363.456375335583, 8379.07830138672, 8485.915140848512, 28450.146495605164, 29640.110796676134, 37485.688000919836, 39001.804990974866, 40010.19979600333, 57236.71381643065, 57464.42535574758, 57900.71049644893, 58796.706016471355, 61857.0, 10841.403722795321, 10845.891541084487, 10902.618408355669, 10980.066694331072, 11177.317443813263, 11592.88407115918, 11701.824472632747, 11760.0, 11878.168528203289, 11967.700807479883, 11062.17033722458, 11071.114310854951, 11212.943935280271, 11315.490802913724, 11562.190646654653, 11224.551017959517, 11380.658580242605, 11536.365499897585, 11550.0, 11781.705457363292, 10917.0, 10976.560937562408, 11255.832423309968, 11261.549538018715, 11388.202825802446, 9692.472308637081, 9764.9023509764, 9942.801143977225, 9983.400995940267, 10275.332103413255, 9607.327487075838, 9691.321607487691, 9705.0, 9742.853857192289, 9783.36408133469], "value": 6806.601635101334, "unit": "score"}], "name": "dromaeo_css", "value": 7190.157437964602, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 113.0, 114.0, 116.0, 115.0, 126.0, 114.0, 114.0, 116.0, 115.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 124.0, 148.0, 167.0, 127.0, 177.0, 171.0, 153.0, 204.0, 125.0], "value": 154.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 201.0, 211.0, 253.0, 275.0, 264.0, 200.0, 201.0, 198.0, 195.0], "value": 219.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 81.0, 81.0, 172.0, 86.0, 80.0, 80.0, 88.0, 130.0, 72.0], "value": 93.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 96.0, 146.0, 114.0, 122.0, 109.0, 122.0, 77.0, 80.0, 123.0], "value": 106.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 132.0, 89.0, 96.0, 135.0, 84.0, 117.0, 76.0, 137.0, 130.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 117.0, 96.0, 96.0, 95.0, 93.0, 96.0, 96.0, 102.0, 96.0], "value": 98.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 83.0, 147.0, 147.0, 135.0, 91.0, 91.0, 145.0, 149.0, 92.0], "value": 119.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 61.0, 59.0, 59.0, 61.0, 61.0, 60.0, 79.0, 75.0, 90.0], "value": 66.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 76.0, 77.0, 96.0, 73.0, 60.0, 55.0, 61.0, 103.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 86.0, 86.0, 87.0, 89.0, 90.0, 87.0, 87.0, 86.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 157.0, 158.0, 143.0, 145.0, 146.0, 144.0, 146.0, 219.0, 151.0], "value": 159.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 180.0, 200.0, 171.0, 169.0, 175.0, 171.0, 173.0, 172.0, 178.0], "value": 176.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 70.0, 66.0, 72.0, 68.0, 69.0, 69.0, 68.0, 67.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1652.9999999999998, "alertThreshold": 2.0}]} 09:30:59 INFO - Return code: 0 09:30:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:30:59 ERROR - # TBPL SUCCESS # 09:30:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:30:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:30:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:30:59 INFO - 2016-04-14 09:30:59.538 screenresolution[2011:13936] starting screenresolution argv=screenresolution get 09:30:59 INFO - 2016-04-14 09:30:59.541 screenresolution[2011:13936] Display 0: 1600x1200x32@60 09:30:59 INFO - 2016-04-14 09:30:59.547 screenresolution[2012:13939] starting screenresolution argv=screenresolution list 09:30:59 INFO - Available Modes on Display 0 09:30:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:30:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:30:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:30:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:30:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:30:59 INFO - Intel Iris: 09:30:59 INFO - Chipset Model: Intel Iris 09:30:59 INFO - Type: GPU 09:30:59 INFO - Bus: Built-In 09:30:59 INFO - VRAM (Dynamic, Max): 1536 MB 09:30:59 INFO - Vendor: Intel (0x8086) 09:30:59 INFO - Device ID: 0x0a2e 09:30:59 INFO - Revision ID: 0x0009 09:30:59 INFO - Displays: 09:30:59 INFO - Display: 09:30:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:30:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:30:59 INFO - Display Serial Number: 1600x1200 60 09:30:59 INFO - Main Display: Yes 09:30:59 INFO - Mirror: Off 09:30:59 INFO - Online: Yes 09:30:59 INFO - Rotation: Supported 09:30:59 INFO - Return code: 0 09:30:59 INFO - Running post-action listener: _resource_record_post_action 09:30:59 INFO - Running post-run listener: _resource_record_post_run 09:31:00 INFO - Total resource usage - Wall time: 1207s; CPU: 54.0%; Read bytes: 397312; Write bytes: 697348096; Read time: 9; Write time: 1960 09:31:00 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 381383168; Write bytes: 373941760; Read time: 28196; Write time: 759 09:31:00 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 397312; Write bytes: 300274688; Read time: 9; Write time: 1150 09:31:00 INFO - Running post-run listener: _upload_blobber_files 09:31:00 INFO - Blob upload gear active. 09:31:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:31:00 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 09:31:00 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:31:00 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:31:01 INFO - (blobuploader) - INFO - Open directory for files ... 09:31:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:31:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:31:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:02 INFO - (blobuploader) - INFO - Done attempting. 09:31:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:31:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:31:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:03 INFO - (blobuploader) - INFO - Done attempting. 09:31:03 INFO - (blobuploader) - INFO - Iteration through files over. 09:31:03 INFO - Return code: 0 09:31:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:31:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:31:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/378d66e2eeaea36c8c70242bef870d40a08d0bbb77da3eb36fa427ad3c3514362ef50c55b636e340a5fcc9367733a075146a25215dd952b8d98cbf704c2a1998", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c464612a967c3b9ab2c3385c1a68ba43ccfae22f3f140cfe6f440021196e07e28bf67963b1060220f5bd019c32e16a86bd2ae99d1c1ef7e537496b6a7632c77f"} 09:31:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:31:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:31:03 INFO - Contents: 09:31:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/378d66e2eeaea36c8c70242bef870d40a08d0bbb77da3eb36fa427ad3c3514362ef50c55b636e340a5fcc9367733a075146a25215dd952b8d98cbf704c2a1998", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c464612a967c3b9ab2c3385c1a68ba43ccfae22f3f140cfe6f440021196e07e28bf67963b1060220f5bd019c32e16a86bd2ae99d1c1ef7e537496b6a7632c77f"} 09:31:03 INFO - Running post-run listener: copy_logs_to_upload_dir 09:31:03 INFO - Copying logs to upload dir... 09:31:03 INFO - mkdir: /builds/slave/test/build/upload/logs 09:31:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.829384 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-04-14 09:31:03.517711) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-14 09:31:03.525730) ========= 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.DewLQix8WK/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.KIDZFe6IR8/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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/Firefox/sha512/378d66e2eeaea36c8c70242bef870d40a08d0bbb77da3eb36fa427ad3c3514362ef50c55b636e340a5fcc9367733a075146a25215dd952b8d98cbf704c2a1998", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c464612a967c3b9ab2c3385c1a68ba43ccfae22f3f140cfe6f440021196e07e28bf67963b1060220f5bd019c32e16a86bd2ae99d1c1ef7e537496b6a7632c77f"} build_url:https://queue.taskcluster.net/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011189 build_url: 'https://queue.taskcluster.net/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/378d66e2eeaea36c8c70242bef870d40a08d0bbb77da3eb36fa427ad3c3514362ef50c55b636e340a5fcc9367733a075146a25215dd952b8d98cbf704c2a1998", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c464612a967c3b9ab2c3385c1a68ba43ccfae22f3f140cfe6f440021196e07e28bf67963b1060220f5bd019c32e16a86bd2ae99d1c1ef7e537496b6a7632c77f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-14 09:31:03.555924) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 09:31:03.556235) ========= 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.DewLQix8WK/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.KIDZFe6IR8/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.005139 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 09:31:03.607810) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 09:31:03.608104) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 09:31:03.608458) ========= ========= Total master_lag: 0.18 =========