builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0115 starttime: 1460650217.65 results: success (0) buildid: 20160414072048 builduid: 4bc212b1ceae4d1aa097dd9dd0d5658a revision: 10f66b3164570b2183333262fa91a16004cbb908 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 09:10:17.648410) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 09:10:17.648823) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-14 09:10:17.649111) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-14 09:10:17.678423) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-04-14 09:10:17.678713) ========= 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.MTDgyfbvxB/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.O8uEUwymUT/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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:10:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 163M=0s 2016-04-14 09:10:17 (163 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116678 ========= master_lag: 6.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-04-14 09:10:23.802879) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 09:10:23.803211) ========= 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.MTDgyfbvxB/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.O8uEUwymUT/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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.019670 ========= master_lag: 0.55 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 09:10:24.368868) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-14 09:10:24.369237) ========= 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.MTDgyfbvxB/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.O8uEUwymUT/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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:10:24,450 truncating revision to first 12 chars 2016-04-14 09:10:24,450 Setting DEBUG logging. 2016-04-14 09:10:24,450 attempt 1/10 2016-04-14 09:10:24,450 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:10:25,844 unpacking tar archive at: mozilla-central-10f66b316457/testing/mozharness/ program finished with exit code 0 elapsedTime=1.644527 ========= master_lag: 0.50 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-14 09:10:26.509496) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 09:10:26.509862) ========= script_repo_revision: 10f66b3164570b2183333262fa91a16004cbb908 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 09:10:26.510277) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 09:10:26.510576) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 09:10:26.534786) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-04-14 09:10:26.535165) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.MTDgyfbvxB/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.O8uEUwymUT/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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:10:26 INFO - MultiFileLogger online at 20160414 09:10:26 in /builds/slave/test 09:10:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:10:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:10:26 INFO - {'append_to_log': False, 09:10:26 INFO - 'base_work_dir': '/builds/slave/test', 09:10:26 INFO - 'blob_upload_branch': 'Firefox', 09:10:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:10:26 INFO - 'branch': 'Firefox', 09:10:26 INFO - 'buildbot_json_path': 'buildprops.json', 09:10:26 INFO - 'config_files': ('talos/mac_config.py',), 09:10:26 INFO - 'default_actions': ('clobber', 09:10:26 INFO - 'read-buildbot-config', 09:10:26 INFO - 'download-and-extract', 09:10:26 INFO - 'populate-webroot', 09:10:26 INFO - 'create-virtualenv', 09:10:26 INFO - 'install', 09:10:26 INFO - 'run-tests'), 09:10:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:10:26 INFO - 'download_minidump_stackwalk': True, 09:10:26 INFO - 'download_symbols': 'ondemand', 09:10:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:10:26 INFO - 'tooltool.py': '/tools/tooltool.py', 09:10:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:10:26 INFO - '/tools/misc-python/virtualenv.py')}, 09:10:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:10:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:10:26 INFO - 'installer_path': 'installer.exe', 09:10:26 INFO - 'log_level': 'info', 09:10:26 INFO - 'log_name': 'talos', 09:10:26 INFO - 'log_to_console': True, 09:10:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:10:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:10:26 INFO - 'opt_config_files': (), 09:10:26 INFO - 'pip_index': False, 09:10:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:10:26 INFO - 'cmd': ('bash', 09:10:26 INFO - '-c', 09:10:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:10:26 INFO - 'enabled': True, 09:10:26 INFO - 'halt_on_failure': False, 09:10:26 INFO - 'name': 'check_screen_resolution'},), 09:10:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:10:26 INFO - 'cmd': ('bash', 09:10:26 INFO - '-c', 09:10:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:10:26 INFO - 'enabled': True, 09:10:26 INFO - 'halt_on_failure': False, 09:10:26 INFO - 'name': 'check_screen_resolution'},), 09:10:26 INFO - 'run_cmd_checks_enabled': True, 09:10:26 INFO - 'sps_profile': False, 09:10:26 INFO - 'sps_profile_interval': 0, 09:10:26 INFO - 'suite': 'dromaeojs', 09:10:26 INFO - 'system_bits': '32', 09:10:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:10:26 INFO - 'title': 't-yosemite-r7-0115', 09:10:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:10:26 INFO - 'use_talos_json': True, 09:10:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:10:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:10:26 INFO - 'work_dir': 'build'} 09:10:26 INFO - ##### 09:10:26 INFO - ##### Running clobber step. 09:10:26 INFO - ##### 09:10:26 INFO - Running pre-action listener: _resource_record_pre_action 09:10:26 INFO - Running main action method: clobber 09:10:26 INFO - rmtree: /builds/slave/test/build 09:10:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:10:28 INFO - Running post-action listener: _resource_record_post_action 09:10:28 INFO - ##### 09:10:28 INFO - ##### Running read-buildbot-config step. 09:10:28 INFO - ##### 09:10:28 INFO - Running pre-action listener: _resource_record_pre_action 09:10:28 INFO - Running main action method: read_buildbot_config 09:10:28 INFO - Using buildbot properties: 09:10:28 INFO - { 09:10:28 INFO - "project": "", 09:10:28 INFO - "product": "firefox", 09:10:28 INFO - "script_repo_revision": "production", 09:10:28 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:10:28 INFO - "repository": "", 09:10:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:10:28 INFO - "buildid": "20160414072048", 09:10:28 INFO - "pgo_build": "False", 09:10:28 INFO - "basedir": "/builds/slave/test", 09:10:28 INFO - "buildnumber": 216, 09:10:28 INFO - "slavename": "t-yosemite-r7-0115", 09:10:28 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:10:28 INFO - "platform": "yosemite_r7", 09:10:28 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:10:28 INFO - "branch": "mozilla-central", 09:10:28 INFO - "repo_path": "mozilla-central", 09:10:28 INFO - "revision": "10f66b3164570b2183333262fa91a16004cbb908", 09:10:28 INFO - "stage_platform": "macosx64", 09:10:28 INFO - "builduid": "4bc212b1ceae4d1aa097dd9dd0d5658a", 09:10:28 INFO - "slavebuilddir": "test" 09:10:28 INFO - } 09:10:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 09:10:28 INFO - Running post-action listener: _resource_record_post_action 09:10:28 INFO - ##### 09:10:28 INFO - ##### Running download-and-extract step. 09:10:28 INFO - ##### 09:10:28 INFO - Running pre-action listener: _resource_record_pre_action 09:10:28 INFO - Running main action method: download_and_extract 09:10:28 INFO - mkdir: /builds/slave/test/build/tests 09:10:28 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:28 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:28 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:28 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:28 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:28 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:29 INFO - Downloaded 1199 bytes. 09:10:29 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:10:29 INFO - Using the following test package requirements: 09:10:29 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:10:29 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:10:29 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 09:10:29 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:10:29 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 09:10:29 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:10:29 INFO - u'jsshell-mac.zip'], 09:10:29 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:10:29 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 09:10:29 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:10:29 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:10:29 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 09:10:29 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:10:29 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 09:10:29 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:10:29 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 09:10:29 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:10:29 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 09:10:29 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 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 - 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:29 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:29 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:29 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:29 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:30 INFO - Downloaded 18028907 bytes. 09:10:30 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:30 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:33 INFO - Return code: 0 09:10:33 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:33 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:33 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:33 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:33 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:33 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:33 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:34 INFO - Downloaded 18028907 bytes. 09:10:34 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:34 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:38 INFO - Return code: 0 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 - 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:38 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:38 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:38 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:38 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:39 INFO - Downloaded 10841076 bytes. 09:10:39 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:39 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:39 INFO - Return code: 0 09:10:39 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:39 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:39 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:39 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:39 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:39 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:42 INFO - Downloaded 109851218 bytes. 09:10:42 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:42 INFO - mkdir: /builds/slave/test/properties 09:10:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:10:42 INFO - Writing to file /builds/slave/test/properties/build_url 09:10:42 INFO - Contents: 09:10:42 INFO - build_url:https://queue.taskcluster.net/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:10:44 INFO - Running post-action listener: _resource_record_post_action 09:10:44 INFO - Running post-action listener: set_extra_try_arguments 09:10:44 INFO - ##### 09:10:44 INFO - ##### Running populate-webroot step. 09:10:44 INFO - ##### 09:10:44 INFO - Running pre-action listener: _resource_record_pre_action 09:10:44 INFO - Running main action method: populate_webroot 09:10:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:10:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:10:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:10:44 INFO - '--tppagecycles', 09:10:44 INFO - '7'], 09:10:44 INFO - 'tests': ['tsvgm']}}, 09:10:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:10:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:10:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:10:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:10:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:10:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:10:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:10:44 INFO - 'talos_options': ['--disable-e10s'], 09:10:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:10:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:10:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:10:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:10:44 INFO - 'talos_options': ['--disable-e10s'], 09:10:44 INFO - 'tests': ['damp', 'tps']}, 09:10:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:10:44 INFO - 'tests': ['damp', 'tps']}, 09:10:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:10:44 INFO - 'tests': ['dromaeo_dom']}, 09:10:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:10:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:10:44 INFO - 'tests': ['a11yr', 09:10:44 INFO - 'ts_paint', 09:10:44 INFO - 'tpaint', 09:10:44 INFO - 'sessionrestore', 09:10:44 INFO - 'sessionrestore_no_auto_restore', 09:10:44 INFO - 'tabpaint']}, 09:10:44 INFO - 'other-e10s': {'tests': ['a11yr', 09:10:44 INFO - 'ts_paint', 09:10:44 INFO - 'tpaint', 09:10:44 INFO - 'sessionrestore', 09:10:44 INFO - 'sessionrestore_no_auto_restore', 09:10:44 INFO - 'tabpaint']}, 09:10:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:10:44 INFO - 'ts_paint', 09:10:44 INFO - 'tpaint', 09:10:44 INFO - 'sessionrestore', 09:10:44 INFO - 'sessionrestore_no_auto_restore', 09:10:44 INFO - 'tabpaint']}, 09:10:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:10:44 INFO - 'ts_paint', 09:10:44 INFO - 'tpaint', 09:10:44 INFO - 'sessionrestore', 09:10:44 INFO - 'sessionrestore_no_auto_restore', 09:10:44 INFO - 'tabpaint']}, 09:10:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:10:44 INFO - 'tests': ['a11yr', 09:10:44 INFO - 'ts_paint', 09:10:44 INFO - 'tpaint', 09:10:44 INFO - 'sessionrestore', 09:10:44 INFO - 'sessionrestore_no_auto_restore', 09:10:44 INFO - 'tabpaint']}, 09:10:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:10:44 INFO - 'tests': ['a11yr', 09:10:44 INFO - 'ts_paint', 09:10:44 INFO - 'tpaint', 09:10:44 INFO - 'sessionrestore', 09:10:44 INFO - 'sessionrestore_no_auto_restore', 09:10:44 INFO - 'tabpaint']}, 09:10:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:10:44 INFO - 'tests': ['tsvgx', 09:10:44 INFO - 'tsvgr_opacity', 09:10:44 INFO - 'tart', 09:10:44 INFO - 'tscrollx', 09:10:44 INFO - 'cart']}, 09:10:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:10:44 INFO - 'tsvgr_opacity', 09:10:44 INFO - 'tart', 09:10:44 INFO - 'tscrollx', 09:10:44 INFO - 'cart']}, 09:10:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:10:44 INFO - 'talos_options': ['--disable-e10s'], 09:10:44 INFO - 'tests': ['tp5o']}, 09:10:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:10:44 INFO - 'tests': ['tp5o']}, 09:10:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:10:44 INFO - 'talos_options': ['--disable-e10s', 09:10:44 INFO - '--xperf_path', 09:10:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:10:44 INFO - 'tests': ['tp5n']}, 09:10:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:10:44 INFO - 'talos_options': ['--xperf_path', 09:10:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:10:44 INFO - 'tests': ['tp5n']}}, 09:10:44 INFO - 'talos.zip': {'path': '', 09:10:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:10:44 INFO - Running post-action listener: _resource_record_post_action 09:10:44 INFO - ##### 09:10:44 INFO - ##### Running create-virtualenv step. 09:10:44 INFO - ##### 09:10:44 INFO - Running pre-action listener: _resource_record_pre_action 09:10:44 INFO - Running main action method: create_virtualenv 09:10:44 INFO - Creating virtualenv /builds/slave/test/build/venv 09:10:44 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:10:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:10:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:10:44 INFO - Using real prefix '/tools/python27' 09:10:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:10:45 INFO - Installing distribute.............................................................................................................................................................................................done. 09:10:48 INFO - Installing pip.................done. 09:10:48 INFO - Return code: 0 09:10:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106696a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066a6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc68c3e940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106535e00>, '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 0x10635edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106530b20>, '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.MTDgyfbvxB/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.O8uEUwymUT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:10:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:10:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTDgyfbvxB/Render', 09:10:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:48 INFO - 'HOME': '/Users/cltbld', 09:10:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:48 INFO - 'LOGNAME': 'cltbld', 09:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:48 INFO - 'MOZ_NO_REMOTE': '1', 09:10:48 INFO - 'NO_EM_RESTART': '1', 09:10:48 INFO - 'PAGER': '/bin/cat', 09:10:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:48 INFO - 'PWD': '/builds/slave/test', 09:10:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:48 INFO - 'SHELL': '/bin/bash', 09:10:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8uEUwymUT/Listeners', 09:10:48 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 09:10:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:48 INFO - 'USER': 'cltbld', 09:10:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:48 INFO - 'XPC_FLAGS': '0x0', 09:10:48 INFO - 'XPC_SERVICE_NAME': '0', 09:10:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:48 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:48 INFO - Cleaning up... 09:10:48 INFO - Return code: 0 09:10:48 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106696a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066a6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc68c3e940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106535e00>, '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 0x10635edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106530b20>, '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.MTDgyfbvxB/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.O8uEUwymUT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:10:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:10:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTDgyfbvxB/Render', 09:10:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:48 INFO - 'HOME': '/Users/cltbld', 09:10:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:48 INFO - 'LOGNAME': 'cltbld', 09:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:48 INFO - 'MOZ_NO_REMOTE': '1', 09:10:48 INFO - 'NO_EM_RESTART': '1', 09:10:48 INFO - 'PAGER': '/bin/cat', 09:10:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:48 INFO - 'PWD': '/builds/slave/test', 09:10:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:48 INFO - 'SHELL': '/bin/bash', 09:10:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8uEUwymUT/Listeners', 09:10:48 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 09:10:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:48 INFO - 'USER': 'cltbld', 09:10:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:48 INFO - 'XPC_FLAGS': '0x0', 09:10:48 INFO - 'XPC_SERVICE_NAME': '0', 09:10:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:48 INFO - Downloading/unpacking psutil>=0.7.1 09:10:48 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:48 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:48 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:48 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:48 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:48 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/psutil/setup.py) egg_info for package psutil 09:10:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:10:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:10:51 INFO - Installing collected packages: psutil 09:10:51 INFO - Running setup.py install for psutil 09:10:51 INFO - building 'psutil._psutil_osx' extension 09:10:51 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:51 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:51 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:51 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:51 INFO - building 'psutil._psutil_posix' extension 09:10:51 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:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:10:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:10:51 INFO - ^ 09:10:51 INFO - 1 warning generated. 09:10:51 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:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:10:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:10:51 INFO - Successfully installed psutil 09:10:51 INFO - Cleaning up... 09:10:52 INFO - Return code: 0 09:10:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:10:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106696a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066a6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc68c3e940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106535e00>, '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 0x10635edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106530b20>, '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.MTDgyfbvxB/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.O8uEUwymUT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:10:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:10:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTDgyfbvxB/Render', 09:10:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:52 INFO - 'HOME': '/Users/cltbld', 09:10:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:52 INFO - 'LOGNAME': 'cltbld', 09:10:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:52 INFO - 'MOZ_NO_REMOTE': '1', 09:10:52 INFO - 'NO_EM_RESTART': '1', 09:10:52 INFO - 'PAGER': '/bin/cat', 09:10:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:52 INFO - 'PWD': '/builds/slave/test', 09:10:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:52 INFO - 'SHELL': '/bin/bash', 09:10:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8uEUwymUT/Listeners', 09:10:52 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 09:10:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:52 INFO - 'USER': 'cltbld', 09:10:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:52 INFO - 'XPC_FLAGS': '0x0', 09:10:52 INFO - 'XPC_SERVICE_NAME': '0', 09:10:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:10:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:10:54 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:54 INFO - Installing collected packages: mozsystemmonitor 09:10:54 INFO - Running setup.py install for mozsystemmonitor 09:10:55 INFO - Successfully installed mozsystemmonitor 09:10:55 INFO - Cleaning up... 09:10:55 INFO - Return code: 0 09:10:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:10:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106696a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066a6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc68c3e940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106535e00>, '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 0x10635edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106530b20>, '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.MTDgyfbvxB/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.O8uEUwymUT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:10:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:10:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTDgyfbvxB/Render', 09:10:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:55 INFO - 'HOME': '/Users/cltbld', 09:10:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:55 INFO - 'LOGNAME': 'cltbld', 09:10:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:55 INFO - 'MOZ_NO_REMOTE': '1', 09:10:55 INFO - 'NO_EM_RESTART': '1', 09:10:55 INFO - 'PAGER': '/bin/cat', 09:10:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:55 INFO - 'PWD': '/builds/slave/test', 09:10:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:55 INFO - 'SHELL': '/bin/bash', 09:10:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8uEUwymUT/Listeners', 09:10:55 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 09:10:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:55 INFO - 'USER': 'cltbld', 09:10:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:55 INFO - 'XPC_FLAGS': '0x0', 09:10:55 INFO - 'XPC_SERVICE_NAME': '0', 09:10:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:55 INFO - Downloading/unpacking blobuploader==1.2.4 09:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:57 INFO - Downloading blobuploader-1.2.4.tar.gz 09:10:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:10:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:10:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:58 INFO - Downloading docopt-0.6.1.tar.gz 09:10:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:10:59 INFO - Installing collected packages: blobuploader, requests, docopt 09:10:59 INFO - Running setup.py install for blobuploader 09:10:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:10:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:10:59 INFO - Running setup.py install for requests 09:10:59 INFO - Running setup.py install for docopt 09:10:59 INFO - Successfully installed blobuploader requests docopt 09:10:59 INFO - Cleaning up... 09:10:59 INFO - Return code: 0 09:10:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10:59 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:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:59 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:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:59 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 0x106696a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066a6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc68c3e940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106535e00>, '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 0x10635edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106530b20>, '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.MTDgyfbvxB/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.O8uEUwymUT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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:59 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:59 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:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTDgyfbvxB/Render', 09:10:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:59 INFO - 'HOME': '/Users/cltbld', 09:10:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:59 INFO - 'LOGNAME': 'cltbld', 09:10:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:59 INFO - 'MOZ_NO_REMOTE': '1', 09:10:59 INFO - 'NO_EM_RESTART': '1', 09:10:59 INFO - 'PAGER': '/bin/cat', 09:10:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:59 INFO - 'PWD': '/builds/slave/test', 09:10:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:59 INFO - 'SHELL': '/bin/bash', 09:10:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8uEUwymUT/Listeners', 09:10:59 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 09:10:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:59 INFO - 'USER': 'cltbld', 09:10:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:59 INFO - 'XPC_FLAGS': '0x0', 09:10:59 INFO - 'XPC_SERVICE_NAME': '0', 09:10:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:11:00 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-QOa056-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:11:00 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-HD1KiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:11:00 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-Et4UWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:11:00 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-f6VsEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:11:00 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-aJtZa7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:11:00 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-88dApD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:11:00 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-hBOUOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:11:00 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-KU3l1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:11:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:11:01 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-0gGQfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:11:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:11:01 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-orL7qW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:11:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:11:01 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-oOWGVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:11:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:11:01 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-3MWDcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:11:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:11:01 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-ToZiy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:11:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:11:01 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-UkUl74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:11:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:01 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-yuEDy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:11:01 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-xW1FqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:11:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:11:01 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-IRn1dd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:11:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:11:01 INFO - Running setup.py install for manifestparser 09:11:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:11:02 INFO - Running setup.py install for mozcrash 09:11:02 INFO - Running setup.py install for mozdebug 09:11:02 INFO - Running setup.py install for mozdevice 09:11:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:11:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:11:02 INFO - Running setup.py install for mozfile 09:11:02 INFO - Running setup.py install for mozhttpd 09:11:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:11:02 INFO - Running setup.py install for mozinfo 09:11:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:11:02 INFO - Running setup.py install for mozInstall 09:11:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:11:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:11:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:11:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:11:03 INFO - Running setup.py install for mozleak 09:11:03 INFO - Running setup.py install for mozlog 09:11:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:11:03 INFO - Running setup.py install for moznetwork 09:11:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:11:03 INFO - Running setup.py install for mozprocess 09:11:03 INFO - Running setup.py install for mozprofile 09:11:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:11:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:11:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:11:03 INFO - Running setup.py install for mozrunner 09:11:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:11:04 INFO - Running setup.py install for mozscreenshot 09:11:04 INFO - Running setup.py install for moztest 09:11:04 INFO - Running setup.py install for mozversion 09:11:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:11:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:11:04 INFO - Cleaning up... 09:11:04 INFO - Return code: 0 09:11:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:04 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:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:04 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:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:04 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 0x106696a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066a6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc68c3e940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106535e00>, '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 0x10635edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106530b20>, '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.MTDgyfbvxB/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.O8uEUwymUT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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:11:04 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:11:04 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:11:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTDgyfbvxB/Render', 09:11:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:04 INFO - 'HOME': '/Users/cltbld', 09:11:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:04 INFO - 'LOGNAME': 'cltbld', 09:11:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:04 INFO - 'MOZ_NO_REMOTE': '1', 09:11:04 INFO - 'NO_EM_RESTART': '1', 09:11:04 INFO - 'PAGER': '/bin/cat', 09:11:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:04 INFO - 'PWD': '/builds/slave/test', 09:11:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:04 INFO - 'SHELL': '/bin/bash', 09:11:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8uEUwymUT/Listeners', 09:11:04 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 09:11:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:04 INFO - 'USER': 'cltbld', 09:11:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:04 INFO - 'XPC_FLAGS': '0x0', 09:11:04 INFO - 'XPC_SERVICE_NAME': '0', 09:11:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:11:04 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-pTlXM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:11:04 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:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:11:04 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-vVJ_rI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:11:04 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:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:11:04 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-D8qnld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:11:05 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:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:11:05 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-TlI_N_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:11:05 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:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:11:05 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-TbcrWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:11:05 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:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:11:05 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-2oDP6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:11:05 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:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:11:05 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-KmTEq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:11:05 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:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:11:05 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-AnsU25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:11:05 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:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:11:05 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-_ZcvHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:11:05 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:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:11:05 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-xUQWB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:11:05 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:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:11:05 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-eU7Fko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:11:05 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:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:11:05 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-vGYt_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:11:06 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:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:11:06 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-7xIaHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:11:06 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:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:11:06 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-0WGk8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:11:06 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:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:06 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-upvnYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:06 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:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:11:06 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-uJs2e7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:11:06 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:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:11:06 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-TnQ6uO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:11:06 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:11:06 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:11:06 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:11:06 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:11:06 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:11:06 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:11:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:11:06 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:11:06 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:11:06 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:11:06 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:11:06 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:11:06 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:11:08 INFO - Downloading blessings-1.6.tar.gz 09:11:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:11:09 INFO - Installing collected packages: blessings 09:11:09 INFO - Running setup.py install for blessings 09:11:09 INFO - Successfully installed blessings 09:11:09 INFO - Cleaning up... 09:11:09 INFO - Return code: 0 09:11:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:11:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:11:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:09 INFO - Reading from file tmpfile_stdout 09:11:09 INFO - Current package versions: 09:11:09 INFO - blessings == 1.6 09:11:09 INFO - blobuploader == 1.2.4 09:11:09 INFO - docopt == 0.6.1 09:11:09 INFO - manifestparser == 1.1 09:11:09 INFO - mozInstall == 1.12 09:11:09 INFO - mozcrash == 0.17 09:11:09 INFO - mozdebug == 0.1 09:11:09 INFO - mozdevice == 0.48 09:11:09 INFO - mozfile == 1.2 09:11:09 INFO - mozhttpd == 0.7 09:11:09 INFO - mozinfo == 0.9 09:11:09 INFO - mozleak == 0.1 09:11:09 INFO - mozlog == 3.1 09:11:09 INFO - moznetwork == 0.27 09:11:09 INFO - mozprocess == 0.22 09:11:09 INFO - mozprofile == 0.28 09:11:09 INFO - mozrunner == 6.11 09:11:09 INFO - mozscreenshot == 0.1 09:11:09 INFO - mozsystemmonitor == 0.0 09:11:09 INFO - moztest == 0.7 09:11:09 INFO - mozversion == 1.4 09:11:09 INFO - psutil == 3.1.1 09:11:09 INFO - requests == 1.2.3 09:11:09 INFO - wsgiref == 0.1.2 09:11:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:11:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:09 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 0x106696a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066a6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc68c3e940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106535e00>, '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 0x10635edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106530b20>, '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.MTDgyfbvxB/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.O8uEUwymUT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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:11:09 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:11:09 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:11:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTDgyfbvxB/Render', 09:11:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:09 INFO - 'HOME': '/Users/cltbld', 09:11:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:09 INFO - 'LOGNAME': 'cltbld', 09:11:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:09 INFO - 'MOZ_NO_REMOTE': '1', 09:11:09 INFO - 'NO_EM_RESTART': '1', 09:11:09 INFO - 'PAGER': '/bin/cat', 09:11:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:09 INFO - 'PWD': '/builds/slave/test', 09:11:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:09 INFO - 'SHELL': '/bin/bash', 09:11:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8uEUwymUT/Listeners', 09:11:09 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 09:11:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:09 INFO - 'USER': 'cltbld', 09:11:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:09 INFO - 'XPC_FLAGS': '0x0', 09:11:09 INFO - 'XPC_SERVICE_NAME': '0', 09:11:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:09 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:11:09 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:11:09 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:11:09 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:11:09 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:11:09 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:11:09 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:11:09 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:11:09 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:11:09 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:11:09 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:11:09 INFO - Cleaning up... 09:11:09 INFO - Return code: 0 09:11:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:11:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:09 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 0x106696a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066a6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc68c3e940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106535e00>, '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 0x10635edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106530b20>, '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.MTDgyfbvxB/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.O8uEUwymUT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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:11:09 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:11:09 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:11:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTDgyfbvxB/Render', 09:11:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:09 INFO - 'HOME': '/Users/cltbld', 09:11:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:09 INFO - 'LOGNAME': 'cltbld', 09:11:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:09 INFO - 'MOZ_NO_REMOTE': '1', 09:11:09 INFO - 'NO_EM_RESTART': '1', 09:11:09 INFO - 'PAGER': '/bin/cat', 09:11:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:09 INFO - 'PWD': '/builds/slave/test', 09:11:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:09 INFO - 'SHELL': '/bin/bash', 09:11:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8uEUwymUT/Listeners', 09:11:09 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 09:11:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:09 INFO - 'USER': 'cltbld', 09:11:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:09 INFO - 'XPC_FLAGS': '0x0', 09:11:09 INFO - 'XPC_SERVICE_NAME': '0', 09:11:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:10 INFO - Downloading/unpacking jsonschema 09:11:10 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:11:10 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:11:10 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:11:10 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:11:10 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:11:10 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:11:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:11:12 INFO - Downloading/unpacking functools32 (from jsonschema) 09:11:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:13 INFO - Downloading functools32-3.2.3-2.tar.gz 09:11:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:11:13 INFO - warning: no files found matching '*.txt' 09:11:13 INFO - no previously-included directories found matching 'build' 09:11:13 INFO - no previously-included directories found matching 'dist' 09:11:13 INFO - no previously-included directories found matching '.git*' 09:11:13 INFO - Installing collected packages: jsonschema, functools32 09:11:13 INFO - Running setup.py install for functools32 09:11:13 INFO - warning: no files found matching '*.txt' 09:11:13 INFO - no previously-included directories found matching 'build' 09:11:13 INFO - no previously-included directories found matching 'dist' 09:11:13 INFO - no previously-included directories found matching '.git*' 09:11:13 INFO - Successfully installed jsonschema functools32 09:11:13 INFO - Cleaning up... 09:11:13 INFO - Return code: 0 09:11:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:11: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:11:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11: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:11:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:13 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 0x106696a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066a6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc68c3e940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106535e00>, '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 0x10635edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106530b20>, '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.MTDgyfbvxB/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.O8uEUwymUT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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:11:13 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:11:13 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:11:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTDgyfbvxB/Render', 09:11:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:13 INFO - 'HOME': '/Users/cltbld', 09:11:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:13 INFO - 'LOGNAME': 'cltbld', 09:11:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:13 INFO - 'MOZ_NO_REMOTE': '1', 09:11:13 INFO - 'NO_EM_RESTART': '1', 09:11:13 INFO - 'PAGER': '/bin/cat', 09:11:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:13 INFO - 'PWD': '/builds/slave/test', 09:11:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:13 INFO - 'SHELL': '/bin/bash', 09:11:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8uEUwymUT/Listeners', 09:11:13 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 09:11:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:13 INFO - 'USER': 'cltbld', 09:11:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:13 INFO - 'XPC_FLAGS': '0x0', 09:11:13 INFO - 'XPC_SERVICE_NAME': '0', 09:11:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:13 INFO - Downloading/unpacking flake8 09:11:13 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:11:13 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:11:13 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:11:13 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:11:13 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:11:13 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:11:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:11:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:11:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:17 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:11:17 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:11:17 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:11:17 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:11:17 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:11:17 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:11:17 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:11:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:11:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:11:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:11:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:11:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:11:17 INFO - no previously-included directories found matching 'docs/_build' 09:11:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:11:17 INFO - Running setup.py install for pep8 09:11:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:11:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:11:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:11:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:11:17 INFO - no previously-included directories found matching 'docs/_build' 09:11:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:11:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:11:18 INFO - Cleaning up... 09:11:18 INFO - Return code: 0 09:11:18 INFO - Running post-action listener: _resource_record_post_action 09:11:18 INFO - Running post-action listener: _start_resource_monitoring 09:11:18 INFO - Starting resource monitoring. 09:11:18 INFO - ##### 09:11:18 INFO - ##### Running install step. 09:11:18 INFO - ##### 09:11:18 INFO - Running pre-action listener: _resource_record_pre_action 09:11:18 INFO - Running main action method: install 09:11:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:18 INFO - Reading from file tmpfile_stdout 09:11:18 INFO - Detecting whether we're running mozinstall >=1.0... 09:11:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:11:18 INFO - Reading from file tmpfile_stdout 09:11:18 INFO - Output received: 09:11:18 INFO - Usage: mozinstall [options] installer 09:11:18 INFO - Options: 09:11:18 INFO - -h, --help show this help message and exit 09:11:18 INFO - -d DEST, --destination=DEST 09:11:18 INFO - Directory to install application into. [default: 09:11:18 INFO - "/builds/slave/test"] 09:11:18 INFO - --app=APP Application being installed. [default: firefox] 09:11:18 INFO - mkdir: /builds/slave/test/build/application 09:11:18 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:11:18 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:52 INFO - Reading from file tmpfile_stdout 09:11:52 INFO - Output received: 09:11:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:11:52 INFO - Running post-action listener: _resource_record_post_action 09:11:52 INFO - ##### 09:11:52 INFO - ##### Running run-tests step. 09:11:52 INFO - ##### 09:11:52 INFO - Running pre-action listener: _resource_record_pre_action 09:11:52 INFO - Running main action method: run_tests 09:11:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:11:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:11:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:11:52 INFO - 2016-04-14 09:11:52.084 screenresolution[1935:17107] starting screenresolution argv=screenresolution get 09:11:52 INFO - 2016-04-14 09:11:52.086 screenresolution[1935:17107] Display 0: 1600x1200x32@60 09:11:52 INFO - 2016-04-14 09:11:52.092 screenresolution[1936:17109] starting screenresolution argv=screenresolution list 09:11:52 INFO - Available Modes on Display 0 09:11:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:11:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:11:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:11:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:11:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:11:52 INFO - Intel Iris: 09:11:52 INFO - Chipset Model: Intel Iris 09:11:52 INFO - Type: GPU 09:11:52 INFO - Bus: Built-In 09:11:52 INFO - VRAM (Dynamic, Max): 1536 MB 09:11:52 INFO - Vendor: Intel (0x8086) 09:11:52 INFO - Device ID: 0x0a2e 09:11:52 INFO - Revision ID: 0x0009 09:11:52 INFO - Displays: 09:11:52 INFO - Display: 09:11:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:11:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:11:52 INFO - Display Serial Number: 1600x1200 60 09:11:52 INFO - Main Display: Yes 09:11:52 INFO - Mirror: Off 09:11:52 INFO - Online: Yes 09:11:52 INFO - Rotation: Supported 09:11:52 INFO - Return code: 0 09:11:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:11:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:11:52 INFO - Python 2.7.3 09:11:52 INFO - Return code: 0 09:11:52 INFO - grabbing minidump binary from tooltool 09:11:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:11:52 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 0x106535e00>, '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 0x10635edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106530b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:11:52 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:52 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:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:11:52 INFO - Return code: 0 09:11:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:11:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:11:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:11:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:11:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:11:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:11:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:11:53 INFO - Return code: 0 09:11:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--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-0115', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:11:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --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-0115 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:11:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTDgyfbvxB/Render', 09:11:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:53 INFO - 'HOME': '/Users/cltbld', 09:11:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:53 INFO - 'LOGNAME': 'cltbld', 09:11:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:11:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:11:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:53 INFO - 'MOZ_NO_REMOTE': '1', 09:11:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:11:53 INFO - 'NO_EM_RESTART': '1', 09:11:53 INFO - 'PAGER': '/bin/cat', 09:11:53 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:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:53 INFO - 'PWD': '/builds/slave/test', 09:11:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:11:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:53 INFO - 'SHELL': '/bin/bash', 09:11:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8uEUwymUT/Listeners', 09:11:53 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 09:11:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:53 INFO - 'USER': 'cltbld', 09:11:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:53 INFO - 'XPC_FLAGS': '0x0', 09:11:53 INFO - 'XPC_SERVICE_NAME': '0', 09:11:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--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-0115', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:11:53 INFO - mozversion application_buildid: 20160414072048 09:11:53 INFO - mozversion application_changeset: 10f66b3164570b2183333262fa91a16004cbb908 09:11:53 INFO - mozversion application_display_name: Nightly 09:11:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:11:53 INFO - mozversion application_name: Firefox 09:11:53 INFO - mozversion application_remotingname: firefox 09:11:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:11:53 INFO - mozversion application_vendor: Mozilla 09:11:53 INFO - mozversion application_version: 48.0a1 09:11:53 INFO - mozversion platform_buildid: 20160414072048 09:11:53 INFO - mozversion platform_changeset: 10f66b3164570b2183333262fa91a16004cbb908 09:11:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:11:53 INFO - mozversion platform_version: 48.0a1 09:11:53 INFO - using testdate: 1460650313 09:11:53 INFO - actual date: 1460650313 09:11:53 INFO - starting webserver on 'localhost:56083' 09:11:53 INFO - SUITE-START | Running 2 tests 09:11:53 INFO - TEST-START | dromaeo_css 09:11:53 INFO - operating with platform_type : mac_ 09:11:53 INFO - Initialising browser for dromaeo_css test... 09:11:53 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpfrJ7KZ/profile http://localhost:56083/getInfo.html) 09:11:56 INFO - PROCESS | 1954 | __metrics Screen width/height:1600/1200 09:11:56 INFO - PROCESS | 1954 | colorDepth:24 09:11:56 INFO - PROCESS | 1954 | Browser inner width/height: 1024/689 09:11:56 INFO - PROCESS | 1954 | __metrics 09:11:56 INFO - PROCESS | 1954 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:11:56 ERROR - PROCESS | 1954 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:11:56 INFO - TEST-INFO | 1954: exit 0 09:11:56 INFO - Browser initialized. 09:11:56 INFO - Running cycle 1/1 for dromaeo_css test... 09:11:56 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpfrJ7KZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:12:08 INFO - PROCESS | 1956 | RSS: Main: 237801472 09:12:08 INFO - PROCESS | 1956 | 09:13:00 INFO - PROCESS | 1956 | 1460650380037 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:15:10 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:56083/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56083/tests/dromaeo/cssquery-ext.html) 09:15:11 INFO - PROCESS | 1956 | RSS: Main: 339333120 09:15:11 INFO - PROCESS | 1956 | 09:18:14 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:56083/tests/dromaeo/cssquery-ext.html (next: http://localhost:56083/tests/dromaeo/cssquery-jquery.html) 09:18:14 INFO - PROCESS | 1956 | RSS: Main: 378753024 09:18:14 INFO - PROCESS | 1956 | 09:21:16 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:56083/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56083/tests/dromaeo/cssquery-mootools.html) 09:21:17 INFO - PROCESS | 1956 | RSS: Main: 377614336 09:21:17 INFO - PROCESS | 1956 | 09:24:19 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:56083/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56083/tests/dromaeo/cssquery-prototype.html) 09:24:20 INFO - PROCESS | 1956 | RSS: Main: 374743040 09:24:20 INFO - PROCESS | 1956 | 09:27:23 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:56083/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56083/tests/dromaeo/cssquery-yui.html) 09:27:23 INFO - PROCESS | 1956 | RSS: Main: 347738112 09:27:23 INFO - PROCESS | 1956 | 09:30:26 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:56083/tests/dromaeo/cssquery-yui.html (next: http://localhost:56083/tests/dromaeo/cssquery-dojo.html) 09:30:27 INFO - PROCESS | 1956 | RSS: Main: 382152704 09:30:27 INFO - PROCESS | 1956 | 09:30:27 INFO - PROCESS | 1956 | __start_tp_report 09:30:27 INFO - PROCESS | 1956 | _x_x_mozilla_page_load 09:30:27 INFO - PROCESS | 1956 | _x_x_mozilla_page_load_details 09:30:27 INFO - PROCESS | 1956 | |i|pagename|runs| 09:30:27 INFO - PROCESS | 1956 | |0;dojo.html;3375.240570871554;3418.3676019936315;3462.653734626539;3463.220775325552;3500.2124521982555;5612.326520817508;5685.317761868577;5699.829005129843;5707.914381284284;5711.1433285007215;5743.741531631076;5829.154772557988;5837.949169149551;5846.4445877641665;5847.976604094277;5174.0945334566395;5234.37187537496;5235.973820130894;5241.213817927322;5311.601629877755;5224.085784987631;5246.1606143017125;5253.86865328366;5256.894862102756;5258.0535503609335;5102;5259.894802103956;5323.574114070884;5324.946750532503;5334.733263336837;5119.129747942859;5203.817866374697;5219.556337711299;5245.977034478278;5247.449017853109;4265.296226122686;5054.913193663343;5093.1850903855575;5137.845864624067;5174.09453345663;4589.2657174851865;5594.524465420444;5619.044762390363;5620.63696557511;5663.773449062032;6079.787207447718;6112.16914378915;6188.257409110935;6194.194754681907;6209.782657606962;6143.96928015357;6187.876242475125;6204;6206.968965155191;6218.347073557302;5696;5708.80019199326;5711.40030296817;5745.224394706703;5759.22250496186;6187.195664563578;6188.195534580475;6297.433231009231;6303;6311.842203944846;6067.683744968392;6069.908951365733;6097.664628445389;6098.969505152535;6116.66358350295;6223.875522489525;6229.719662615194;6255.437010669061;6257.342978987141;6269.341719119518;6055.2430946131735;6082.787102451393;6110.2550082019825;6122.113280529527;6137.048757442514;6099.340048627943;6102.115193296947;6173.043178307369;6183.5671502994355;6217.595856269328;4392.363107349416;4719.952800471951;4905.215165573562;4922.75386230687;4946.233333833262;4416.072624748775;4425.579569940851;4433.334999750064;4464.017916058462;4471.329300604935;4821.9035619288115;5555.194496798023;5567.66594004361;5627.352854421712;5726.3700992890745;6221.720022598994;6223;6281.937180628227;6292.559520833589;6305.369463053658;5649.548036157097;5652.576056795757;5662.348829884616;5664;5691.9715401423555;5246.134387825966;5326.467353264643;5376.677399356051;5394.91907621386;5416.322959630046;5292.603054770831;6278.780242691484;6293.465055470245;6333.145025421509;6335.556511044183;5879.235699359056;6269.028300613412;6277.843053923689;6286.339934306832;6322.241331040304;6294.905576416266;6304.905426418699;6352.205974253218;6355.205599300088;6357.4596159326975;6143.877122457437;6151.323354431099;6157.692115394123;6158.599691020069;6159.5072394209255;6102.974033066949;6164.7842325518395;6261.405166509175;6262.749490020348;6266.717997690206;6086.573939824169;6105.308121371012;6113.567524283718;6115.072094319962;6150.415710507495;6055.333913269449;6127.601705889103;6134.78528251509;6135.07973803933;6139.478144357601;5788.855278618068;5860.970695146497;5933.436323549256;5947.851303717442;5978.551608629283;5136.640435169652;5845.561582881386;6083.756649734138;6118.938810611837;6119.632822030692;5876.500497457764;5880.088586269135;5898.55760817949;5936;5940.346561878104 09:30:27 INFO - PROCESS | 1956 | |1;ext.html;493.06071932967694;529.9559867061889;530.7160669042061;592.2951687491882;635.482082103086;23611;36769.448458273146;37937.62062379375;38020.23959520808;38107;40857;41452.75641730746;41565.792171039175;41574.79212603933;41634.16731665365;7511.849763004737;9333.533323333857;9539.47532885691;9540;9548.665796697116;7232.746853860116;8671.479711217347;8680.956595217014;8689.17452841979;11180.832287515694;11259.2118551701;11349.602763903265;11373.545058197662;11377.317360958328;11380.71548211293;11257.211995160302;11388.487518061707;11411.828822567668;11422.657320280405;11448.313101213955;8407.411481196319;8692.739217823475;8697.695580654647;11059.336439813556;11253.381064041474;37997;38601.98174237331;38656.61343386558;38666.22667546661;38672.41991370132;3216.5657636219044;3216.678332166788;3249.5938007749032;3251.561039259693;3278.032980270802;27260.045898393462;27716.30709232265;27912.860435697894;27979;28198;2671.7461841125064;2678.236702539786;2689.5024420482273;2697.2852194168563;2892.8461101533558;483551;484889;487066;487553;488792.55603721755;34414.31171376609;34848;34933.82533087318;35005.299894001975;35037.91950167262;34092.31815363679;34127.658723412445;34252.65747342545;34296.657033429845;34338.82830585831;14605.12369257848;14771.630709232355;14790.926045369704;14794.778078328834;14816.851831481546;12567.811482827765;12631.494740210288;12633.431495582721;12961.287129207984;13008.869911300955;7458.627068646546;7595.240475952471;7596.696132154652;7604.391648668094;7897.210278972057;2834.4331133773326;3326.534285200074;3334.233126380946;3341.63242043375;3371.4100032494216;911.9316051296179;989.6536212325628;1001.324106228293;1002.0030070080317;1015.9695209143738;22795.88602056979;27947.860260698973;28306.85846570754;28760.280992975342;28762;1157.6121999130385;1158.8377627132209;1221.3160630047203;1242.3229340009716;1253.790092560665;15037.097774133586;15170.393184272727;15266.748853296813;15275.388984440498;15437.768433473508;551183;571012;571771;572092;574541;51435.48564514307;52672;57366.7131664339;59806.70096649576;59893;1446.8987170898147;1463.868251857338;1468.7882496939974;1479.6744716162427;1480.5854360778999;23361.76638233664;28628.42743145167;28662.570061448678;29454.410911782074;29470.852645736635;47881.32415365445;47891.28163077626;48443.27335090047;48473.75763121162;48537;635906;651018;753674.2316288602;778243;778791;25651.615225772002;25978;26113.955441782116;26116;26139.386061393707;38089;44398.778006109766;45451.77274113608;45706.31440528328;46732.299015514116;35886.461703073954;37131.25737485181;37245.81377093206;38020.80989595034;38122.618773811904;36366.63633363738;36388.81805590955;37168;37351.813240933625;37555.2488950214;36035.27929441449;36575.63424365722;36664.45003324899;37108;37631.62368376391;31466.52800207953;31954;32090;32219.838900805345;32229.677703222667;23902.402439939142;24217;24435.26694199177;24561.50876982486;24750.752492475563 09:30:27 INFO - PROCESS | 1956 | |2;jquery.html;392.75649097559517;393.15864050931003;398.8364770444118;402.42855145693113;407.6758976613594;19184;19879.602407951832;20680.862552559604;20682.96585170741;22205.333839984825;20796.896015519902;21229.787702122972;21587;21755.021024053916;21892.343229703136;5684.630499017571;6013.639181649115;6029.216201893748;6032.758689652409;6058.0610005449225;5630.296212973379;5783.479486846183;5933.317668468117;5943.613665111754;5954.910676339859;5841.766329346842;5868.471837534621;5872.207252020987;5892.351841297454;5896.970515147418;5691.174779656968;5716.39977802331;5864.824055278327;5869.295684517842;5919.875223707481;5146.716930568818;5181.222816577506;5182.585393168538;5195.168772996302;5199.844004679828;22935.885320573456;25252.873735631205;25514.617280740804;26782;26850.86574567134;2176.967345489819;2257.413072601119;2275.340151356105;2432.391902024494;2464.96471481978;14828.17392683029;15165.696686066216;15305.540833775005;15361.231938403147;15468.226588670634;1703.2590822991915;1714.1172296267432;1719.7334413165854;1763.3740022292159;1795.5331613780381;117577;120585.58828823462;124809;124989;125192.6105542088;9277.469217579024;9949.403035817873;10038.949805251073;10043.548040338203;10700.127477691372;12417.44121514534;12419.006479481619;12487.875121248853;12527.56153534622;12528.81206781899;8043.356531477468;8056.154103819132;8076.353891688651;8177;8193.016837979481;8293.792655183668;8293.95853020731;8314.542700151549;8438.704645337384;8515;3846.673032792244;3953.9011524711536;3966.960330396717;4004.7196696231567;4005.239004589177;1905.7331973523717;1943.5335519475225;1945.8540609454064;1956.7260583518319;1959.617874514456;835.3985130705883;869.6912596028445;912.2975309012023;943.5423819447562;974.6393834281362;16455;19165.712514312298;21235.68146477804;21308.784272634344;21377.144914203567;940.5250348573926;954.2175416158683;956.7850426263178;970.6408628807245;1032.7934413117407;10916.017558419622;11021.135879200825;11037.944810275898;11100.278481898651;11115.832837552103;597276;608251;610317;612882;614880;125241.37379313045;127752;128508.35745821211;130797;133071;1004.9497525123714;1005.527402121002;1027.6505987964129;1033.850091736694;1034.2915103154364;17535.473935781945;18166.636667266317;18223;18476.260949561867;18565.81434185695;48859.755701221264;49299.75350123226;49993.75003124961;50787.49212507828;50811.23783143182;70338.64830676018;73942.63028685037;74064.25935740574;74106;76866;7144.535605185646;7339.59632220233;7475.2151024141685;7518.248175182438;8412.116727743743;7980.920190798018;9246;10349.9482502587;12093.448017430937;13173.473061077833;11071;11166.665000050012;11224.438778061227;11490.597829075943;11662.300261984308;10504.632337868137;11018.638492973316;11032.227744057838;11232.66302010941;11274.379909105079;9844.901550984685;11405.429728513374;11586.826197606873;11740;11746.530138794353;7355.8896616551865;7484.550926944401;7511.5868627223845;7516.210797866145;7854.803629909298;8203.507789532647;10753.623623173151;11208.327500350006;11339.489722962651;11558.364289964133 09:30:27 INFO - PROCESS | 1956 | |3;mootools.html;1423.4306277489009;1589.9602509937254;1612.6371566397563;1889.168765743073;2011.215625905896;8039.598020098993;8724.12758724128;8964.103589641041;9000.504972226525;9018.188363047324;7277.963610181955;8822.86765698515;8823.867641985375;8827.95586022069;8844.5577721114;2455.6562081308584;2481.5781317176125;2489.4523204894986;2490.987545062272;2494.276659768665;2428.089466450081;2444.303373538542;2450.6936632920883;2452.3378687754293;2456.6192240202795;2431.6960379952507;2457.533068716938;2486.5772818620876;2489.825712200147;2495.0394098272113;2133.6052830226304;2430.0765709030543;2448.069733501276;2450.5221481811054;2474.01039584166;2428.113738485458;2458.6926634170727;2466.963875172432;2468.1608253194;2476.430421003179;8653.17794809492;8815.51514666693;8901.866472002925;8920.821583568357;8924.68763593271;5332.92000619991;5520.834374968757;5535.944640553583;5539.695316757577;5560.805371811967;3491.5635545556806;3602.225521512861;3660.8535658573624;3667.9083022924374;3693.0028892199225;927.3091546797611;1042.213129087534;1054.9630762923337;1065.7442213868617;1075.4837677914566;11389.658310250707;11567.768644627144;11586.536538538448;11611.419429028514;11620.244684095507;3978.8209530571194;4489;4489.371487991683;4692.6245900327895;4703.988642441891;8942.776430589158;8954.955225223832;8978.371513993958;8980.416272942239;8990.190882820578;5275.498827611371;5291.470852914677;5298.947010529922;5305.973470132624;5339.439358867341;4151.750894946313;4315.935260971088;4476.798544065525;4618.792154353062;5013.298138260647;3284.7372210223602;3803.2963901678277;3852.845886164522;3891.4434226309704;3913.3151698453225;1218.7440637466054;1221.3832014832453;1222.3277197541317;1237.3874931908583;1237.3874931908763;687.3092541995263;759.8822182561712;821.2075347289774;955.507913424587;963.419842475888;5612.635178713371;5730.226419433407;5945.554083443759;5954.196183515257;6047.12316714074;562.6820846221872;586.2671660424469;589.6255877517722;590.2268028882178;694.930506949301;3620.25784714133;3820.0640842993644;3953.881383558498;4014.2172276406404;4096.078382364004;8586.699465518677;10764.730881727863;10817.134629229642;10819.67540973772;10945.671629851116;9827.11555959967;10589.576416943237;10645.467726613639;10896.564137434414;10906.454677266105;8200.712975045844;8281.585920704061;8378.329733621296;8383.580820958927;8387.574112400907;5706.172604972173;5732.598718089694;5785.479306862402;5888.381719919346;5962.642241465526;9980.401175929468;10002.899971000197;10056.044675755791;10129.64546240878;10164.491775411052;9578.952105239428;9731.221502279943;10169.084782369326;10182.185425166117;10194.847077293995;7389.076365454318;7463.888041679271;7562.697492100475;7575.6969721210535;7580.734674286374;8094.271515563628;8213.219744124299;8253.876191857247;8266.338692904434;8291.08798032228;7403.407727381689;7547.849043018999;7549.811254718676;7591.013168288087;7711.845763084819;7411.5553066816165;7428.9628551856895;7497.437692173218;7562.697492100255;7590.430717696306;6902.378785909092;6957.339052790179;7034.964825175842;7040.683169257293;7042.295770422917;4779.450363208275;4869.464358920587;4943.77753001123;5045.940352525864;5187.974060129675;4933.186024305949;5001.8499445016705;5043.2435134730085;5094.6688465249645;5283.233931080049 09:30:27 INFO - PROCESS | 1956 | |4;prototype.html;307.61240836545954;312.6654479706713;317.61409886987315;319.6148640887729;324.4646333549644;4882.877928051796;4932.408111026682;5948.702564871755;5949.791757288495;6025.517958563315;5413.810516631918;5638.210650508932;5849.941500584993;5882.882342353161;5898.7935422260225;3452.8791492297773;3783.186614877801;3854.0557563396865;4168.874933751992;4180.927018641296;3693.3905905525558;3841.750286231386;4308.46144231971;4381.3208952612395;4406.272964960794;4373.628241599468;4377.912441751163;4390.385346051556;4419.116176764644;4422.336649502584;3891.9102651257695;4121.52602450716;4138.461999940018;4387.407699960512;4412.698254015073;3684.8157592120288;3792.2415516896453;3809.580946095955;3818.327170980325;3818.579956204815;4887.462379138292;5507.981023510663;5642.971785141089;5674.177244299594;5696.174054762037;4927.77824997876;5088;5090.032893750175;5090.1855703087695;5158.2520534522655;5152.819651312224;6137.754489820401;6169.167162433059;6173.382661733835;6185.9793134132815;4230.534641189467;4230.851920182809;4230.978845105786;4244.469441319835;4250.808713607864;6302.936970630327;6505.739770409178;6514.413702766727;6523.18460192476;6527.510436717218;3812.8093595320124;4248.808803603845;4273.166732487197;4291.82405020289;4295.570442955679;4345.391645169679;4352.216601011785;4364.323529852878;4372.825086996484;4379.1460665170625;4368.759718215465;4771.73755443447;5049.267856160836;5055.6713813602;5065.721385323769;6198.473129784019;6245.812625621148;6273.435390814849;6274.592151510228;6287.434130928238;1314.9013823963242;1342.744878473087;1424.3732757586629;1453.941842326295;1503.7819516170314;942.330945029037;997.8203923293731;1002.9448380339005;1009.369144284822;1011.5801942193907;5178.689278643294;5216.008958297912;5522.972385138049;5617.747201375948;5828.154917537018;5169.4572069932865;5919.526437884959;6447.4197322241225;6453.225612926479;6477.935220647827;5075.416327122354;5461.508464238158;5760.884782304331;5773.191753154561;5783.739731712083;3331.3836940166148;3363.8486268117995;3380.797152170878;3389.694927456541;3391.1183092396154;6413.8660915753;6707.664616769142;6717.90976150976;6726.697298621574;6760.492963027793;5734.39788822176;6279.963805972055;6533.738650454024;6598.934010659928;6619.3380661933425;5502.724863756796;6133.29467111279;6683.398494135551;6706.731730730814;6725.948585681316;5625.240592519957;6038.365971572922;6042.247748153162;6066.241719785027;6369.426751592379;5296.867578310573;6235.688215589112;6240;6284.497240220863;6331.778387756591;6422.743090276336;6684.231313398827;6717.664116794043;6783.321667833177;6819.931800682129;4027.476428064333;4151.916961660811;4155.584441555941;4188.392683060878;4194.769287689084;6277.96861015692;6420.1974753155855;6450.258220304538;6453.580517266362;6492.623835605553;6192.938070619416;6252.687365631609;6291.874162516816;6298.685065746603;6311.842203944938;5279.604029697711;6131.63210207389;6223.128761973237;6276.372362763687;6292.590981586182;5888.528917686661;5922.920081557652;6072.271327440735;6182.969085154546;6196.535259855439;5466.808661696821;5765.279340082489;6168.35232300602;6207.255129384323;6253.155823963707;5160.48395160481;6065.727042283196;6158.643539271018;6168.290646575701;6170.598911070766 09:30:27 INFO - PROCESS | 1956 | |5;yui.html;691.0222035819315;706.9469789765767;710.6020628448073;722.8807246804278;777.7044723005257;11979.700507487318;15459.22703864808;15476.84523154768;15483.767743483828;15491.535253942386;11879.940600297008;14040.789388159186;14139.92930035351;14174.50389236377;14219.71560568788;708.7129712466434;729.5440349781387;898.1826537850574;911.6399022386154;912.9634814607441;836;877.0834477970504;879.160401816264;880.0803160697087;880.6917578847384;955.8231727130503;973.9902600974006;976.5361453309692;976.6679329028129;977.5210147027941;751.9323260906544;776.9067711874554;776.9300762931307;958.1711819276311;969.2149359019216;684.1482354468718;712.6508011074588;714.0431821359404;714.3428046197458;716.0083284650744;15007.774883376758;15048.473303434437;15078.246087695681;15084.547463576111;15088.24558772057;12949.741005179938;12964.48142074316;13032.41354139066;13158.210507369493;13340.533081342106;10061.223276733192;10311.587536498531;10482.423466709328;10651.30766500175;10657.893421065844;3510.78935263885;3557.5908770491465;3605.729570282213;3605.7475976681644;3673.687736542397;79186.6040669793;79474;79560;80045;83544.58227708883;9690.515474226402;9695.563699633392;9818.705438836703;10014.449205293779;10124.595016199417;22362.329130126123;22392.216272430385;22394;22407;22415.21546745856;7426.702931882664;7554.2445755425115;7643.694252229849;7698.34564062061;7809.3362064225175;4081.673466122703;4156.418101465737;4160.729552579072;4162.7502349859105;4231.640310573635;3521.190126270972;3592.4790905319105;3625;3626.4741612466573;3633.818309084535;633.4837107757163;639.3287048598952;654.3423859021652;661.1735330836455;667.552739664426;540.2706346432363;557.345119484604;582.2779753106117;611.3183800062898;614.5605891787393;7719.189485104095;7737.187595302525;8038;8045.115037345888;8315.584220789058;523.5706720489238;555.7832445346318;559.6642014791174;579.0387955993032;619.5353484886335;3687.649673281034;3768.1898391845884;3782.867599634055;3905.9804700976315;3948.3287841067086;74115.62942185254;75633.10917227004;78483;80981;82031;32242.710291588544;32573.837130814667;67072.32927670759;67470.65058698897;67840.98238526426;63910;64235;64761;64813.999999999054;66476.66761666161;7432.145303289974;7533.886991695239;7590.354819840375;7639.847203056019;7681.8463630725955;32564.51153232751;41319.96700082522;42513;42703.57296426996;43710.56289437065;45892.77053614844;57311.42688573061;59003.739476474184;59124.70437647956;59249.40750592439;10122.8481572775;10200.948995255274;10283.742906427398;10300.536475858755;10627.840582391425;10892;10969;11059.83410248863;11232;11326.83009754871;8955.910440895508;10433.843492347773;10533.683989480327;10757.13942884583;10761.623343182951;10380.377177369382;10585.52365143555;10757.677269681923;10943.179261555575;11251.606193783506;9811.313208075366;10136.290459667753;10153.25956565667;10167.339122956984;10542.736431589272;8131.9593402032615;9117.22503587176;9159.77100572491;9655.420674759536;9719.539136723399;9674.70975870725;9700.27247956392;9710.757231068997;9715.70852874415;9716.22270218395 09:30:27 INFO - PROCESS | 1956 | __end_tp_report 09:30:27 INFO - PROCESS | 1956 | __start_cc_report 09:30:27 INFO - PROCESS | 1956 | _x_x_mozilla_cycle_collect,1208 09:30:27 INFO - PROCESS | 1956 | __end_cc_report 09:30:27 INFO - PROCESS | 1956 | __startTimestamp1460651427033__endTimestamp 09:30:27 INFO - PROCESS | 1956 | 09:30:27 INFO - PROCESS | 1956 | ------- Summary: start ------- 09:30:27 INFO - PROCESS | 1956 | Number of tests: 6 09:30:27 INFO - PROCESS | 1956 | 09:30:27 INFO - PROCESS | 1956 | [#0] dojo.html Cycles:165 Average:5699.20 Median:5880.09 stddev:645.82 (11.0%) stddev-sans-first:621.53 09:30:27 INFO - PROCESS | 1956 | Values: 3375.2 3418.4 3462.7 3463.2 3500.2 5612.3 5685.3 5699.8 5707.9 5711.1 5743.7 5829.2 5837.9 5846.4 5848.0 5174.1 5234.4 5236.0 5241.2 5311.6 5224.1 5246.2 5253.9 5256.9 5258.1 5102.0 5259.9 5323.6 5324.9 5334.7 5119.1 5203.8 5219.6 5246.0 5247.4 4265.3 5054.9 5093.2 5137.8 5174.1 4589.3 5594.5 5619.0 5620.6 5663.8 6079.8 6112.2 6188.3 6194.2 6209.8 6144.0 6187.9 6204.0 6207.0 6218.3 5696.0 5708.8 5711.4 5745.2 5759.2 6187.2 6188.2 6297.4 6303.0 6311.8 6067.7 6069.9 6097.7 6099.0 6116.7 6223.9 6229.7 6255.4 6257.3 6269.3 6055.2 6082.8 6110.3 6122.1 6137.0 6099.3 6102.1 6173.0 6183.6 6217.6 4392.4 4720.0 4905.2 4922.8 4946.2 4416.1 4425.6 4433.3 4464.0 4471.3 4821.9 5555.2 5567.7 5627.4 5726.4 6221.7 6223.0 6281.9 6292.6 6305.4 5649.5 5652.6 5662.3 5664.0 5692.0 5246.1 5326.5 5376.7 5394.9 5416.3 5292.6 6278.8 6293.5 6333.1 6335.6 5879.2 6269.0 6277.8 6286.3 6322.2 6294.9 6304.9 6352.2 6355.2 6357.5 6143.9 6151.3 6157.7 6158.6 6159.5 6103.0 6164.8 6261.4 6262.7 6266.7 6086.6 6105.3 6113.6 6115.1 6150.4 6055.3 6127.6 6134.8 6135.1 6139.5 5788.9 5861.0 5933.4 5947.9 5978.6 5136.6 5845.6 6083.8 6118.9 6119.6 5876.5 5880.1 5898.6 5936.0 5940.3 09:30:27 INFO - PROCESS | 1956 | 09:30:27 INFO - PROCESS | 1956 | [#1] ext.html Cycles:180 Average:69363.25 Median:34317.74 stddev:161357.15 (470.2%) stddev-sans-first:161726.95 09:30:27 INFO - PROCESS | 1956 | Values: 493.1 530.0 530.7 592.3 635.5 23611.0 36769.4 37937.6 38020.2 38107.0 40857.0 41452.8 41565.8 41574.8 41634.2 7511.8 9333.5 9539.5 9540.0 9548.7 7232.7 8671.5 8681.0 8689.2 11180.8 11259.2 11349.6 11373.5 11377.3 11380.7 11257.2 11388.5 11411.8 11422.7 11448.3 8407.4 8692.7 8697.7 11059.3 11253.4 37997.0 38602.0 38656.6 38666.2 38672.4 3216.6 3216.7 3249.6 3251.6 3278.0 27260.0 27716.3 27912.9 27979.0 28198.0 2671.7 2678.2 2689.5 2697.3 2892.8 483551.0 484889.0 487066.0 487553.0 488792.6 34414.3 34848.0 34933.8 35005.3 35037.9 34092.3 34127.7 34252.7 34296.7 34338.8 14605.1 14771.6 14790.9 14794.8 14816.9 12567.8 12631.5 12633.4 12961.3 13008.9 7458.6 7595.2 7596.7 7604.4 7897.2 2834.4 3326.5 3334.2 3341.6 3371.4 911.9 989.7 1001.3 1002.0 1016.0 22795.9 27947.9 28306.9 28760.3 28762.0 1157.6 1158.8 1221.3 1242.3 1253.8 15037.1 15170.4 15266.7 15275.4 15437.8 551183.0 571012.0 571771.0 572092.0 574541.0 51435.5 52672.0 57366.7 59806.7 59893.0 1446.9 1463.9 1468.8 1479.7 1480.6 23361.8 28628.4 28662.6 29454.4 29470.9 47881.3 47891.3 48443.3 48473.8 48537.0 635906.0 651018.0 753674.2 778243.0 778791.0 25651.6 25978.0 26114.0 26116.0 26139.4 38089.0 44398.8 45451.8 45706.3 46732.3 35886.5 37131.3 37245.8 38020.8 38122.6 36366.6 36388.8 37168.0 37351.8 37555.2 36035.3 36575.6 36664.5 37108.0 37631.6 31466.5 31954.0 32090.0 32219.8 32229.7 23902.4 24217.0 24435.3 24561.5 24750.8 09:30:27 INFO - PROCESS | 1956 | 09:30:27 INFO - PROCESS | 1956 | [#2] jquery.html Cycles:180 Average:35190.32 Median:13858.92 stddev:101606.60 (733.1%) stddev-sans-first:101858.04 09:30:27 INFO - PROCESS | 1956 | Values: 392.8 393.2 398.8 402.4 407.7 19184.0 19879.6 20680.9 20683.0 22205.3 20796.9 21229.8 21587.0 21755.0 21892.3 5684.6 6013.6 6029.2 6032.8 6058.1 5630.3 5783.5 5933.3 5943.6 5954.9 5841.8 5868.5 5872.2 5892.4 5897.0 5691.2 5716.4 5864.8 5869.3 5919.9 5146.7 5181.2 5182.6 5195.2 5199.8 22935.9 25252.9 25514.6 26782.0 26850.9 2177.0 2257.4 2275.3 2432.4 2465.0 14828.2 15165.7 15305.5 15361.2 15468.2 1703.3 1714.1 1719.7 1763.4 1795.5 117577.0 120585.6 124809.0 124989.0 125192.6 9277.5 9949.4 10038.9 10043.5 10700.1 12417.4 12419.0 12487.9 12527.6 12528.8 8043.4 8056.2 8076.4 8177.0 8193.0 8293.8 8294.0 8314.5 8438.7 8515.0 3846.7 3953.9 3967.0 4004.7 4005.2 1905.7 1943.5 1945.9 1956.7 1959.6 835.4 869.7 912.3 943.5 974.6 16455.0 19165.7 21235.7 21308.8 21377.1 940.5 954.2 956.8 970.6 1032.8 10916.0 11021.1 11037.9 11100.3 11115.8 597276.0 608251.0 610317.0 612882.0 614880.0 125241.4 127752.0 128508.4 130797.0 133071.0 1004.9 1005.5 1027.7 1033.9 1034.3 17535.5 18166.6 18223.0 18476.3 18565.8 48859.8 49299.8 49993.8 50787.5 50811.2 70338.6 73942.6 74064.3 74106.0 76866.0 7144.5 7339.6 7475.2 7518.2 8412.1 7980.9 9246.0 10349.9 12093.4 13173.5 11071.0 11166.7 11224.4 11490.6 11662.3 10504.6 11018.6 11032.2 11232.7 11274.4 9844.9 11405.4 11586.8 11740.0 11746.5 7355.9 7484.6 7511.6 7516.2 7854.8 8203.5 10753.6 11208.3 11339.5 11558.4 09:30:27 INFO - PROCESS | 1956 | 09:30:27 INFO - PROCESS | 1956 | [#3] mootools.html Cycles:180 Average:5671.62 Median:5070.30 stddev:3160.81 (62.3%) stddev-sans-first:3153.55 09:30:27 INFO - PROCESS | 1956 | Values: 1423.4 1590.0 1612.6 1889.2 2011.2 8039.6 8724.1 8964.1 9000.5 9018.2 7278.0 8822.9 8823.9 8828.0 8844.6 2455.7 2481.6 2489.5 2491.0 2494.3 2428.1 2444.3 2450.7 2452.3 2456.6 2431.7 2457.5 2486.6 2489.8 2495.0 2133.6 2430.1 2448.1 2450.5 2474.0 2428.1 2458.7 2467.0 2468.2 2476.4 8653.2 8815.5 8901.9 8920.8 8924.7 5332.9 5520.8 5535.9 5539.7 5560.8 3491.6 3602.2 3660.9 3667.9 3693.0 927.3 1042.2 1055.0 1065.7 1075.5 11389.7 11567.8 11586.5 11611.4 11620.2 3978.8 4489.0 4489.4 4692.6 4704.0 8942.8 8955.0 8978.4 8980.4 8990.2 5275.5 5291.5 5298.9 5306.0 5339.4 4151.8 4315.9 4476.8 4618.8 5013.3 3284.7 3803.3 3852.8 3891.4 3913.3 1218.7 1221.4 1222.3 1237.4 1237.4 687.3 759.9 821.2 955.5 963.4 5612.6 5730.2 5945.6 5954.2 6047.1 562.7 586.3 589.6 590.2 694.9 3620.3 3820.1 3953.9 4014.2 4096.1 8586.7 10764.7 10817.1 10819.7 10945.7 9827.1 10589.6 10645.5 10896.6 10906.5 8200.7 8281.6 8378.3 8383.6 8387.6 5706.2 5732.6 5785.5 5888.4 5962.6 9980.4 10002.9 10056.0 10129.6 10164.5 9579.0 9731.2 10169.1 10182.2 10194.8 7389.1 7463.9 7562.7 7575.7 7580.7 8094.3 8213.2 8253.9 8266.3 8291.1 7403.4 7547.8 7549.8 7591.0 7711.8 7411.6 7429.0 7497.4 7562.7 7590.4 6902.4 6957.3 7035.0 7040.7 7042.3 4779.5 4869.5 4943.8 5045.9 5188.0 4933.2 5001.8 5043.2 5094.7 5283.2 09:30:27 INFO - PROCESS | 1956 | 09:30:27 INFO - PROCESS | 1956 | [#4] prototype.html Cycles:180 Average:5022.04 Median:5621.49 stddev:1580.10 (28.1%) stddev-sans-first:1544.40 09:30:27 INFO - PROCESS | 1956 | Values: 307.6 312.7 317.6 319.6 324.5 4882.9 4932.4 5948.7 5949.8 6025.5 5413.8 5638.2 5849.9 5882.9 5898.8 3452.9 3783.2 3854.1 4168.9 4180.9 3693.4 3841.8 4308.5 4381.3 4406.3 4373.6 4377.9 4390.4 4419.1 4422.3 3891.9 4121.5 4138.5 4387.4 4412.7 3684.8 3792.2 3809.6 3818.3 3818.6 4887.5 5508.0 5643.0 5674.2 5696.2 4927.8 5088.0 5090.0 5090.2 5158.3 5152.8 6137.8 6169.2 6173.4 6186.0 4230.5 4230.9 4231.0 4244.5 4250.8 6302.9 6505.7 6514.4 6523.2 6527.5 3812.8 4248.8 4273.2 4291.8 4295.6 4345.4 4352.2 4364.3 4372.8 4379.1 4368.8 4771.7 5049.3 5055.7 5065.7 6198.5 6245.8 6273.4 6274.6 6287.4 1314.9 1342.7 1424.4 1453.9 1503.8 942.3 997.8 1002.9 1009.4 1011.6 5178.7 5216.0 5523.0 5617.7 5828.2 5169.5 5919.5 6447.4 6453.2 6477.9 5075.4 5461.5 5760.9 5773.2 5783.7 3331.4 3363.8 3380.8 3389.7 3391.1 6413.9 6707.7 6717.9 6726.7 6760.5 5734.4 6280.0 6533.7 6598.9 6619.3 5502.7 6133.3 6683.4 6706.7 6725.9 5625.2 6038.4 6042.2 6066.2 6369.4 5296.9 6235.7 6240.0 6284.5 6331.8 6422.7 6684.2 6717.7 6783.3 6819.9 4027.5 4151.9 4155.6 4188.4 4194.8 6278.0 6420.2 6450.3 6453.6 6492.6 6192.9 6252.7 6291.9 6298.7 6311.8 5279.6 6131.6 6223.1 6276.4 6292.6 5888.5 5922.9 6072.3 6183.0 6196.5 5466.8 5765.3 6168.4 6207.3 6253.2 5160.5 6065.7 6158.6 6168.3 6170.6 09:30:27 INFO - PROCESS | 1956 | 09:30:27 INFO - PROCESS | 1956 | [#5] yui.html Cycles:180 Average:16374.29 Median:29793.01 stddev:22187.79 (74.5%) stddev-sans-first:22218.78 09:30:27 INFO - PROCESS | 1956 | Values: 691.0 706.9 710.6 722.9 777.7 11979.7 15459.2 15476.8 15483.8 15491.5 11879.9 14040.8 14139.9 14174.5 14219.7 708.7 729.5 898.2 911.6 913.0 836.0 877.1 879.2 880.1 880.7 955.8 974.0 976.5 976.7 977.5 751.9 776.9 776.9 958.2 969.2 684.1 712.7 714.0 714.3 716.0 15007.8 15048.5 15078.2 15084.5 15088.2 12949.7 12964.5 13032.4 13158.2 13340.5 10061.2 10311.6 10482.4 10651.3 10657.9 3510.8 3557.6 3605.7 3605.7 3673.7 79186.6 79474.0 79560.0 80045.0 83544.6 9690.5 9695.6 9818.7 10014.4 10124.6 22362.3 22392.2 22394.0 22407.0 22415.2 7426.7 7554.2 7643.7 7698.3 7809.3 4081.7 4156.4 4160.7 4162.8 4231.6 3521.2 3592.5 3625.0 3626.5 3633.8 633.5 639.3 654.3 661.2 667.6 540.3 557.3 582.3 611.3 614.6 7719.2 7737.2 8038.0 8045.1 8315.6 523.6 555.8 559.7 579.0 619.5 3687.6 3768.2 3782.9 3906.0 3948.3 74115.6 75633.1 78483.0 80981.0 82031.0 32242.7 32573.8 67072.3 67470.7 67841.0 63910.0 64235.0 64761.0 64814.0 66476.7 7432.1 7533.9 7590.4 7639.8 7681.8 32564.5 41320.0 42513.0 42703.6 43710.6 45892.8 57311.4 59003.7 59124.7 59249.4 10122.8 10200.9 10283.7 10300.5 10627.8 10892.0 10969.0 11059.8 11232.0 11326.8 8955.9 10433.8 10533.7 10757.1 10761.6 10380.4 10585.5 10757.7 10943.2 11251.6 9811.3 10136.3 10153.3 10167.3 10542.7 8132.0 9117.2 9159.8 9655.4 9719.5 9674.7 9700.3 9710.8 9715.7 9716.2 09:30:27 INFO - PROCESS | 1956 | -------- Summary: end -------- 09:30:27 INFO - PROCESS | 1956 | 09:30:27 INFO - TEST-INFO | 1956: exit 0 09:30:27 INFO - TEST-OK | dromaeo_css | took 1113744ms 09:30:27 INFO - TEST-START | kraken 09:30:27 INFO - operating with platform_type : mac_ 09:30:27 INFO - Initialising browser for kraken test... 09:30:27 INFO - TEST-INFO | started process 1959 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmp9fJyvw/profile http://localhost:56083/getInfo.html) 09:30:29 INFO - PROCESS | 1959 | __metrics Screen width/height:1600/1200 09:30:29 INFO - PROCESS | 1959 | colorDepth:24 09:30:29 INFO - PROCESS | 1959 | Browser inner width/height: 1024/689 09:30:29 INFO - PROCESS | 1959 | __metrics 09:30:29 INFO - PROCESS | 1959 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:30:29 ERROR - PROCESS | 1959 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:30:30 INFO - TEST-INFO | 1959: exit 0 09:30:30 INFO - Browser initialized. 09:30:30 INFO - Running cycle 1/1 for kraken test... 09:30:30 INFO - TEST-INFO | started process 1961 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmp9fJyvw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:30:42 INFO - PROCESS | 1961 | RSS: Main: 237797376 09:30:42 INFO - PROCESS | 1961 | 09:30:45 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56083/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56083/tests/kraken/driver.html?testName=audio-beat-detection) 09:30:45 INFO - PROCESS | 1961 | RSS: Main: 286449664 09:30:45 INFO - PROCESS | 1961 | 09:30:48 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56083/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56083/tests/kraken/driver.html?testName=audio-dft) 09:30:49 INFO - PROCESS | 1961 | RSS: Main: 299028480 09:30:49 INFO - PROCESS | 1961 | 09:30:52 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56083/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56083/tests/kraken/driver.html?testName=audio-fft) 09:30:53 INFO - PROCESS | 1961 | RSS: Main: 387776512 09:30:53 INFO - PROCESS | 1961 | 09:30:55 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56083/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56083/tests/kraken/driver.html?testName=audio-oscillator) 09:30:56 INFO - PROCESS | 1961 | RSS: Main: 286801920 09:30:56 INFO - PROCESS | 1961 | 09:30:58 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56083/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56083/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:30:58 INFO - PROCESS | 1961 | RSS: Main: 290656256 09:30:58 INFO - PROCESS | 1961 | 09:31:01 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56083/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56083/tests/kraken/driver.html?testName=imaging-darkroom) 09:31:02 INFO - PROCESS | 1961 | RSS: Main: 327606272 09:31:02 INFO - PROCESS | 1961 | 09:31:05 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56083/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56083/tests/kraken/driver.html?testName=imaging-desaturate) 09:31:05 INFO - PROCESS | 1961 | RSS: Main: 329986048 09:31:05 INFO - PROCESS | 1961 | 09:31:08 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56083/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56083/tests/kraken/driver.html?testName=json-parse-financial) 09:31:09 INFO - PROCESS | 1961 | RSS: Main: 328273920 09:31:09 INFO - PROCESS | 1961 | 09:31:10 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56083/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56083/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:31:11 INFO - PROCESS | 1961 | RSS: Main: 320073728 09:31:11 INFO - PROCESS | 1961 | 09:31:13 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56083/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56083/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:31:13 INFO - PROCESS | 1961 | RSS: Main: 326754304 09:31:13 INFO - PROCESS | 1961 | 09:31:16 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56083/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56083/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:31:16 INFO - PROCESS | 1961 | RSS: Main: 329633792 09:31:16 INFO - PROCESS | 1961 | 09:31:19 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56083/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56083/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:31:20 INFO - PROCESS | 1961 | RSS: Main: 334802944 09:31:20 INFO - PROCESS | 1961 | 09:31:23 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56083/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56083/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:31:23 INFO - PROCESS | 1961 | RSS: Main: 332533760 09:31:23 INFO - PROCESS | 1961 | 09:31:25 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56083/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56083/tests/kraken/driver.html?testName=ai-astar) 09:31:25 INFO - PROCESS | 1961 | RSS: Main: 396546048 09:31:25 INFO - PROCESS | 1961 | 09:31:25 INFO - PROCESS | 1961 | __start_tp_report 09:31:25 INFO - PROCESS | 1961 | _x_x_mozilla_page_load 09:31:25 INFO - PROCESS | 1961 | _x_x_mozilla_page_load_details 09:31:25 INFO - PROCESS | 1961 | |i|pagename|runs| 09:31:25 INFO - PROCESS | 1961 | |0;ai-astar;112;109;112;113;112;113;117;112;114;114 09:31:25 INFO - PROCESS | 1961 | |1;audio-beat-detection;158;127;144;183;143;147;172;146;206;119 09:31:25 INFO - PROCESS | 1961 | |2;audio-dft;184;184;184;186;185;180;181;182;182;183 09:31:25 INFO - PROCESS | 1961 | |3;audio-fft;77;72;85;98;81;84;83;81;83;55 09:31:25 INFO - PROCESS | 1961 | |4;audio-oscillator;90;102;136;77;78;80;126;79;78;126 09:31:25 INFO - PROCESS | 1961 | |5;imaging-gaussian-blur;89;92;134;117;88;86;90;87;88;86 09:31:25 INFO - PROCESS | 1961 | |6;imaging-darkroom;95;100;132;95;94;94;104;149;109;95 09:31:25 INFO - PROCESS | 1961 | |7;imaging-desaturate;86;85;86;87;84;85;85;85;84;86 09:31:25 INFO - PROCESS | 1961 | |8;json-parse-financial;60;58;58;57;58;57;60;76;59;56 09:31:25 INFO - PROCESS | 1961 | |9;json-stringify-tinderbox;64;70;70;74;71;72;70;68;68;68 09:31:25 INFO - PROCESS | 1961 | |10;stanford-crypto-aes;100;105;96;86;99;100;83;87;86;85 09:31:25 INFO - PROCESS | 1961 | |11;stanford-crypto-ccm;147;142;146;144;140;143;146;143;177;145 09:31:25 INFO - PROCESS | 1961 | |12;stanford-crypto-pbkdf2;169;169;171;168;166;170;169;167;167;168 09:31:25 INFO - PROCESS | 1961 | |13;stanford-crypto-sha256-iterative;71;70;70;70;69;67;68;70;68;69 09:31:25 INFO - PROCESS | 1961 | __end_tp_report 09:31:25 INFO - PROCESS | 1961 | __start_cc_report 09:31:25 INFO - PROCESS | 1961 | _x_x_mozilla_cycle_collect,1922 09:31:25 INFO - PROCESS | 1961 | __end_cc_report 09:31:25 INFO - PROCESS | 1961 | __startTimestamp1460651485791__endTimestamp 09:31:25 INFO - PROCESS | 1961 | 09:31:25 INFO - PROCESS | 1961 | ------- Summary: start ------- 09:31:25 INFO - PROCESS | 1961 | Number of tests: 14 09:31:25 INFO - PROCESS | 1961 | 09:31:25 INFO - PROCESS | 1961 | [#0] ai-astar Cycles:10 Average:112.80 Median:113.00 stddev:2.04 (1.8%) stddev-sans-first:2.15 09:31:25 INFO - PROCESS | 1961 | Values: 112.0 109.0 112.0 113.0 112.0 113.0 117.0 112.0 114.0 114.0 09:31:25 INFO - PROCESS | 1961 | 09:31:25 INFO - PROCESS | 1961 | [#1] audio-beat-detection Cycles:10 Average:154.50 Median:152.50 stddev:26.18 (17.2%) stddev-sans-first:27.74 09:31:25 INFO - PROCESS | 1961 | Values: 158.0 127.0 144.0 183.0 143.0 147.0 172.0 146.0 206.0 119.0 09:31:25 INFO - PROCESS | 1961 | 09:31:25 INFO - PROCESS | 1961 | [#2] audio-dft Cycles:10 Average:183.10 Median:184.00 stddev:1.85 (1.0%) stddev-sans-first:1.94 09:31:25 INFO - PROCESS | 1961 | Values: 184.0 184.0 184.0 186.0 185.0 180.0 181.0 182.0 182.0 183.0 09:31:25 INFO - PROCESS | 1961 | 09:31:25 INFO - PROCESS | 1961 | [#3] audio-fft Cycles:10 Average:79.90 Median:83.00 stddev:10.97 (13.2%) stddev-sans-first:11.58 09:31:25 INFO - PROCESS | 1961 | Values: 77.0 72.0 85.0 98.0 81.0 84.0 83.0 81.0 83.0 55.0 09:31:25 INFO - PROCESS | 1961 | 09:31:25 INFO - PROCESS | 1961 | [#4] audio-oscillator Cycles:10 Average:97.20 Median:78.00 stddev:23.60 (30.3%) stddev-sans-first:24.88 09:31:25 INFO - PROCESS | 1961 | Values: 90.0 102.0 136.0 77.0 78.0 80.0 126.0 79.0 78.0 126.0 09:31:25 INFO - PROCESS | 1961 | 09:31:25 INFO - PROCESS | 1961 | [#5] imaging-gaussian-blur Cycles:10 Average:95.70 Median:88.00 stddev:16.31 (18.5%) stddev-sans-first:17.12 09:31:25 INFO - PROCESS | 1961 | Values: 89.0 92.0 134.0 117.0 88.0 86.0 90.0 87.0 88.0 86.0 09:31:25 INFO - PROCESS | 1961 | 09:31:25 INFO - PROCESS | 1961 | [#6] imaging-darkroom Cycles:10 Average:106.70 Median:94.00 stddev:18.92 (20.1%) stddev-sans-first:19.58 09:31:25 INFO - PROCESS | 1961 | Values: 95.0 100.0 132.0 95.0 94.0 94.0 104.0 149.0 109.0 95.0 09:31:25 INFO - PROCESS | 1961 | 09:31:25 INFO - PROCESS | 1961 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.50 stddev:0.95 (1.1%) stddev-sans-first:0.97 09:31:25 INFO - PROCESS | 1961 | Values: 86.0 85.0 86.0 87.0 84.0 85.0 85.0 85.0 84.0 86.0 09:31:25 INFO - PROCESS | 1961 | 09:31:25 INFO - PROCESS | 1961 | [#8] json-parse-financial Cycles:10 Average:59.90 Median:58.50 stddev:5.80 (9.9%) stddev-sans-first:6.15 09:31:25 INFO - PROCESS | 1961 | Values: 60.0 58.0 58.0 57.0 58.0 57.0 60.0 76.0 59.0 56.0 09:31:25 INFO - PROCESS | 1961 | 09:31:25 INFO - PROCESS | 1961 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:70.00 stddev:2.72 (3.9%) stddev-sans-first:2.03 09:31:25 INFO - PROCESS | 1961 | Values: 64.0 70.0 70.0 74.0 71.0 72.0 70.0 68.0 68.0 68.0 09:31:25 INFO - PROCESS | 1961 | 09:31:25 INFO - PROCESS | 1961 | [#10] stanford-crypto-aes Cycles:10 Average:92.70 Median:86.00 stddev:8.06 (9.4%) stddev-sans-first:8.10 09:31:25 INFO - PROCESS | 1961 | Values: 100.0 105.0 96.0 86.0 99.0 100.0 83.0 87.0 86.0 85.0 09:31:25 INFO - PROCESS | 1961 | 09:31:25 INFO - PROCESS | 1961 | [#11] stanford-crypto-ccm Cycles:10 Average:147.30 Median:145.50 stddev:10.65 (7.3%) stddev-sans-first:11.29 09:31:25 INFO - PROCESS | 1961 | Values: 147.0 142.0 146.0 144.0 140.0 143.0 146.0 143.0 177.0 145.0 09:31:25 INFO - PROCESS | 1961 | 09:31:25 INFO - PROCESS | 1961 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.40 Median:169.00 stddev:1.51 (0.9%) stddev-sans-first:1.58 09:31:25 INFO - PROCESS | 1961 | Values: 169.0 169.0 171.0 168.0 166.0 170.0 169.0 167.0 167.0 168.0 09:31:25 INFO - PROCESS | 1961 | 09:31:25 INFO - PROCESS | 1961 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:70.00 stddev:1.23 (1.8%) stddev-sans-first:1.12 09:31:25 INFO - PROCESS | 1961 | Values: 71.0 70.0 70.0 70.0 69.0 67.0 68.0 70.0 68.0 69.0 09:31:25 INFO - PROCESS | 1961 | -------- Summary: end -------- 09:31:25 INFO - PROCESS | 1961 | 09:31:26 INFO - TEST-INFO | 1961: exit 0 09:31:26 INFO - TEST-OK | kraken | took 59009ms 09:31:26 INFO - SUITE-END | took 1172s 09:31:26 INFO - Completed test suite (00:19:33) 09:31:26 INFO - javascript benchmark 09:31:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3375.240570871554, 3418.3676019936315, 3462.653734626539, 3463.220775325552, 3500.2124521982555, 5612.326520817508, 5685.317761868577, 5699.829005129843, 5707.914381284284, 5711.1433285007215, 5743.741531631076, 5829.154772557988, 5837.949169149551, 5846.4445877641665, 5847.976604094277, 5174.0945334566395, 5234.37187537496, 5235.973820130894, 5241.213817927322, 5311.601629877755, 5224.085784987631, 5246.1606143017125, 5253.86865328366, 5256.894862102756, 5258.0535503609335, 5102.0, 5259.894802103956, 5323.574114070884, 5324.946750532503, 5334.733263336837, 5119.129747942859, 5203.817866374697, 5219.556337711299, 5245.977034478278, 5247.449017853109, 4265.296226122686, 5054.913193663343, 5093.1850903855575, 5137.845864624067, 5174.09453345663, 4589.2657174851865, 5594.524465420444, 5619.044762390363, 5620.63696557511, 5663.773449062032, 6079.787207447718, 6112.16914378915, 6188.257409110935, 6194.194754681907, 6209.782657606962, 6143.96928015357, 6187.876242475125, 6204.0, 6206.968965155191, 6218.347073557302, 5696.0, 5708.80019199326, 5711.40030296817, 5745.224394706703, 5759.22250496186, 6187.195664563578, 6188.195534580475, 6297.433231009231, 6303.0, 6311.842203944846, 6067.683744968392, 6069.908951365733, 6097.664628445389, 6098.969505152535, 6116.66358350295, 6223.875522489525, 6229.719662615194, 6255.437010669061, 6257.342978987141, 6269.341719119518, 6055.2430946131735, 6082.787102451393, 6110.2550082019825, 6122.113280529527, 6137.048757442514, 6099.340048627943, 6102.115193296947, 6173.043178307369, 6183.5671502994355, 6217.595856269328, 4392.363107349416, 4719.952800471951, 4905.215165573562, 4922.75386230687, 4946.233333833262, 4416.072624748775, 4425.579569940851, 4433.334999750064, 4464.017916058462, 4471.329300604935, 4821.9035619288115, 5555.194496798023, 5567.66594004361, 5627.352854421712, 5726.3700992890745, 6221.720022598994, 6223.0, 6281.937180628227, 6292.559520833589, 6305.369463053658, 5649.548036157097, 5652.576056795757, 5662.348829884616, 5664.0, 5691.9715401423555, 5246.134387825966, 5326.467353264643, 5376.677399356051, 5394.91907621386, 5416.322959630046, 5292.603054770831, 6278.780242691484, 6293.465055470245, 6333.145025421509, 6335.556511044183, 5879.235699359056, 6269.028300613412, 6277.843053923689, 6286.339934306832, 6322.241331040304, 6294.905576416266, 6304.905426418699, 6352.205974253218, 6355.205599300088, 6357.4596159326975, 6143.877122457437, 6151.323354431099, 6157.692115394123, 6158.599691020069, 6159.5072394209255, 6102.974033066949, 6164.7842325518395, 6261.405166509175, 6262.749490020348, 6266.717997690206, 6086.573939824169, 6105.308121371012, 6113.567524283718, 6115.072094319962, 6150.415710507495, 6055.333913269449, 6127.601705889103, 6134.78528251509, 6135.07973803933, 6139.478144357601, 5788.855278618068, 5860.970695146497, 5933.436323549256, 5947.851303717442, 5978.551608629283, 5136.640435169652, 5845.561582881386, 6083.756649734138, 6118.938810611837, 6119.632822030692, 5876.500497457764, 5880.088586269135, 5898.55760817949, 5936.0, 5940.346561878104], "value": 5659.22585503482, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.06071932967694, 529.9559867061889, 530.7160669042061, 592.2951687491882, 635.482082103086, 23611.0, 36769.448458273146, 37937.62062379375, 38020.23959520808, 38107.0, 40857.0, 41452.75641730746, 41565.792171039175, 41574.79212603933, 41634.16731665365, 7511.849763004737, 9333.533323333857, 9539.47532885691, 9540.0, 9548.665796697116, 7232.746853860116, 8671.479711217347, 8680.956595217014, 8689.17452841979, 11180.832287515694, 11259.2118551701, 11349.602763903265, 11373.545058197662, 11377.317360958328, 11380.71548211293, 11257.211995160302, 11388.487518061707, 11411.828822567668, 11422.657320280405, 11448.313101213955, 8407.411481196319, 8692.739217823475, 8697.695580654647, 11059.336439813556, 11253.381064041474, 37997.0, 38601.98174237331, 38656.61343386558, 38666.22667546661, 38672.41991370132, 3216.5657636219044, 3216.678332166788, 3249.5938007749032, 3251.561039259693, 3278.032980270802, 27260.045898393462, 27716.30709232265, 27912.860435697894, 27979.0, 28198.0, 2671.7461841125064, 2678.236702539786, 2689.5024420482273, 2697.2852194168563, 2892.8461101533558, 483551.0, 484889.0, 487066.0, 487553.0, 488792.55603721755, 34414.31171376609, 34848.0, 34933.82533087318, 35005.299894001975, 35037.91950167262, 34092.31815363679, 34127.658723412445, 34252.65747342545, 34296.657033429845, 34338.82830585831, 14605.12369257848, 14771.630709232355, 14790.926045369704, 14794.778078328834, 14816.851831481546, 12567.811482827765, 12631.494740210288, 12633.431495582721, 12961.287129207984, 13008.869911300955, 7458.627068646546, 7595.240475952471, 7596.696132154652, 7604.391648668094, 7897.210278972057, 2834.4331133773326, 3326.534285200074, 3334.233126380946, 3341.63242043375, 3371.4100032494216, 911.9316051296179, 989.6536212325628, 1001.324106228293, 1002.0030070080317, 1015.9695209143738, 22795.88602056979, 27947.860260698973, 28306.85846570754, 28760.280992975342, 28762.0, 1157.6121999130385, 1158.8377627132209, 1221.3160630047203, 1242.3229340009716, 1253.790092560665, 15037.097774133586, 15170.393184272727, 15266.748853296813, 15275.388984440498, 15437.768433473508, 551183.0, 571012.0, 571771.0, 572092.0, 574541.0, 51435.48564514307, 52672.0, 57366.7131664339, 59806.70096649576, 59893.0, 1446.8987170898147, 1463.868251857338, 1468.7882496939974, 1479.6744716162427, 1480.5854360778999, 23361.76638233664, 28628.42743145167, 28662.570061448678, 29454.410911782074, 29470.852645736635, 47881.32415365445, 47891.28163077626, 48443.27335090047, 48473.75763121162, 48537.0, 635906.0, 651018.0, 753674.2316288602, 778243.0, 778791.0, 25651.615225772002, 25978.0, 26113.955441782116, 26116.0, 26139.386061393707, 38089.0, 44398.778006109766, 45451.77274113608, 45706.31440528328, 46732.299015514116, 35886.461703073954, 37131.25737485181, 37245.81377093206, 38020.80989595034, 38122.618773811904, 36366.63633363738, 36388.81805590955, 37168.0, 37351.813240933625, 37555.2488950214, 36035.27929441449, 36575.63424365722, 36664.45003324899, 37108.0, 37631.62368376391, 31466.52800207953, 31954.0, 32090.0, 32219.838900805345, 32229.677703222667, 23902.402439939142, 24217.0, 24435.26694199177, 24561.50876982486, 24750.752492475563], "value": 18156.586203289484, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.75649097559517, 393.15864050931003, 398.8364770444118, 402.42855145693113, 407.6758976613594, 19184.0, 19879.602407951832, 20680.862552559604, 20682.96585170741, 22205.333839984825, 20796.896015519902, 21229.787702122972, 21587.0, 21755.021024053916, 21892.343229703136, 5684.630499017571, 6013.639181649115, 6029.216201893748, 6032.758689652409, 6058.0610005449225, 5630.296212973379, 5783.479486846183, 5933.317668468117, 5943.613665111754, 5954.910676339859, 5841.766329346842, 5868.471837534621, 5872.207252020987, 5892.351841297454, 5896.970515147418, 5691.174779656968, 5716.39977802331, 5864.824055278327, 5869.295684517842, 5919.875223707481, 5146.716930568818, 5181.222816577506, 5182.585393168538, 5195.168772996302, 5199.844004679828, 22935.885320573456, 25252.873735631205, 25514.617280740804, 26782.0, 26850.86574567134, 2176.967345489819, 2257.413072601119, 2275.340151356105, 2432.391902024494, 2464.96471481978, 14828.17392683029, 15165.696686066216, 15305.540833775005, 15361.231938403147, 15468.226588670634, 1703.2590822991915, 1714.1172296267432, 1719.7334413165854, 1763.3740022292159, 1795.5331613780381, 117577.0, 120585.58828823462, 124809.0, 124989.0, 125192.6105542088, 9277.469217579024, 9949.403035817873, 10038.949805251073, 10043.548040338203, 10700.127477691372, 12417.44121514534, 12419.006479481619, 12487.875121248853, 12527.56153534622, 12528.81206781899, 8043.356531477468, 8056.154103819132, 8076.353891688651, 8177.0, 8193.016837979481, 8293.792655183668, 8293.95853020731, 8314.542700151549, 8438.704645337384, 8515.0, 3846.673032792244, 3953.9011524711536, 3966.960330396717, 4004.7196696231567, 4005.239004589177, 1905.7331973523717, 1943.5335519475225, 1945.8540609454064, 1956.7260583518319, 1959.617874514456, 835.3985130705883, 869.6912596028445, 912.2975309012023, 943.5423819447562, 974.6393834281362, 16455.0, 19165.712514312298, 21235.68146477804, 21308.784272634344, 21377.144914203567, 940.5250348573926, 954.2175416158683, 956.7850426263178, 970.6408628807245, 1032.7934413117407, 10916.017558419622, 11021.135879200825, 11037.944810275898, 11100.278481898651, 11115.832837552103, 597276.0, 608251.0, 610317.0, 612882.0, 614880.0, 125241.37379313045, 127752.0, 128508.35745821211, 130797.0, 133071.0, 1004.9497525123714, 1005.527402121002, 1027.6505987964129, 1033.850091736694, 1034.2915103154364, 17535.473935781945, 18166.636667266317, 18223.0, 18476.260949561867, 18565.81434185695, 48859.755701221264, 49299.75350123226, 49993.75003124961, 50787.49212507828, 50811.23783143182, 70338.64830676018, 73942.63028685037, 74064.25935740574, 74106.0, 76866.0, 7144.535605185646, 7339.59632220233, 7475.2151024141685, 7518.248175182438, 8412.116727743743, 7980.920190798018, 9246.0, 10349.9482502587, 12093.448017430937, 13173.473061077833, 11071.0, 11166.665000050012, 11224.438778061227, 11490.597829075943, 11662.300261984308, 10504.632337868137, 11018.638492973316, 11032.227744057838, 11232.66302010941, 11274.379909105079, 9844.901550984685, 11405.429728513374, 11586.826197606873, 11740.0, 11746.530138794353, 7355.8896616551865, 7484.550926944401, 7511.5868627223845, 7516.210797866145, 7854.803629909298, 8203.507789532647, 10753.623623173151, 11208.327500350006, 11339.489722962651, 11558.364289964133], "value": 9444.890727719534, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1423.4306277489009, 1589.9602509937254, 1612.6371566397563, 1889.168765743073, 2011.215625905896, 8039.598020098993, 8724.12758724128, 8964.103589641041, 9000.504972226525, 9018.188363047324, 7277.963610181955, 8822.86765698515, 8823.867641985375, 8827.95586022069, 8844.5577721114, 2455.6562081308584, 2481.5781317176125, 2489.4523204894986, 2490.987545062272, 2494.276659768665, 2428.089466450081, 2444.303373538542, 2450.6936632920883, 2452.3378687754293, 2456.6192240202795, 2431.6960379952507, 2457.533068716938, 2486.5772818620876, 2489.825712200147, 2495.0394098272113, 2133.6052830226304, 2430.0765709030543, 2448.069733501276, 2450.5221481811054, 2474.01039584166, 2428.113738485458, 2458.6926634170727, 2466.963875172432, 2468.1608253194, 2476.430421003179, 8653.17794809492, 8815.51514666693, 8901.866472002925, 8920.821583568357, 8924.68763593271, 5332.92000619991, 5520.834374968757, 5535.944640553583, 5539.695316757577, 5560.805371811967, 3491.5635545556806, 3602.225521512861, 3660.8535658573624, 3667.9083022924374, 3693.0028892199225, 927.3091546797611, 1042.213129087534, 1054.9630762923337, 1065.7442213868617, 1075.4837677914566, 11389.658310250707, 11567.768644627144, 11586.536538538448, 11611.419429028514, 11620.244684095507, 3978.8209530571194, 4489.0, 4489.371487991683, 4692.6245900327895, 4703.988642441891, 8942.776430589158, 8954.955225223832, 8978.371513993958, 8980.416272942239, 8990.190882820578, 5275.498827611371, 5291.470852914677, 5298.947010529922, 5305.973470132624, 5339.439358867341, 4151.750894946313, 4315.935260971088, 4476.798544065525, 4618.792154353062, 5013.298138260647, 3284.7372210223602, 3803.2963901678277, 3852.845886164522, 3891.4434226309704, 3913.3151698453225, 1218.7440637466054, 1221.3832014832453, 1222.3277197541317, 1237.3874931908583, 1237.3874931908763, 687.3092541995263, 759.8822182561712, 821.2075347289774, 955.507913424587, 963.419842475888, 5612.635178713371, 5730.226419433407, 5945.554083443759, 5954.196183515257, 6047.12316714074, 562.6820846221872, 586.2671660424469, 589.6255877517722, 590.2268028882178, 694.930506949301, 3620.25784714133, 3820.0640842993644, 3953.881383558498, 4014.2172276406404, 4096.078382364004, 8586.699465518677, 10764.730881727863, 10817.134629229642, 10819.67540973772, 10945.671629851116, 9827.11555959967, 10589.576416943237, 10645.467726613639, 10896.564137434414, 10906.454677266105, 8200.712975045844, 8281.585920704061, 8378.329733621296, 8383.580820958927, 8387.574112400907, 5706.172604972173, 5732.598718089694, 5785.479306862402, 5888.381719919346, 5962.642241465526, 9980.401175929468, 10002.899971000197, 10056.044675755791, 10129.64546240878, 10164.491775411052, 9578.952105239428, 9731.221502279943, 10169.084782369326, 10182.185425166117, 10194.847077293995, 7389.076365454318, 7463.888041679271, 7562.697492100475, 7575.6969721210535, 7580.734674286374, 8094.271515563628, 8213.219744124299, 8253.876191857247, 8266.338692904434, 8291.08798032228, 7403.407727381689, 7547.849043018999, 7549.811254718676, 7591.013168288087, 7711.845763084819, 7411.5553066816165, 7428.9628551856895, 7497.437692173218, 7562.697492100255, 7590.430717696306, 6902.378785909092, 6957.339052790179, 7034.964825175842, 7040.683169257293, 7042.295770422917, 4779.450363208275, 4869.464358920587, 4943.77753001123, 5045.940352525864, 5187.974060129675, 4933.186024305949, 5001.8499445016705, 5043.2435134730085, 5094.6688465249645, 5283.233931080049], "value": 4524.372553510653, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.61240836545954, 312.6654479706713, 317.61409886987315, 319.6148640887729, 324.4646333549644, 4882.877928051796, 4932.408111026682, 5948.702564871755, 5949.791757288495, 6025.517958563315, 5413.810516631918, 5638.210650508932, 5849.941500584993, 5882.882342353161, 5898.7935422260225, 3452.8791492297773, 3783.186614877801, 3854.0557563396865, 4168.874933751992, 4180.927018641296, 3693.3905905525558, 3841.750286231386, 4308.46144231971, 4381.3208952612395, 4406.272964960794, 4373.628241599468, 4377.912441751163, 4390.385346051556, 4419.116176764644, 4422.336649502584, 3891.9102651257695, 4121.52602450716, 4138.461999940018, 4387.407699960512, 4412.698254015073, 3684.8157592120288, 3792.2415516896453, 3809.580946095955, 3818.327170980325, 3818.579956204815, 4887.462379138292, 5507.981023510663, 5642.971785141089, 5674.177244299594, 5696.174054762037, 4927.77824997876, 5088.0, 5090.032893750175, 5090.1855703087695, 5158.2520534522655, 5152.819651312224, 6137.754489820401, 6169.167162433059, 6173.382661733835, 6185.9793134132815, 4230.534641189467, 4230.851920182809, 4230.978845105786, 4244.469441319835, 4250.808713607864, 6302.936970630327, 6505.739770409178, 6514.413702766727, 6523.18460192476, 6527.510436717218, 3812.8093595320124, 4248.808803603845, 4273.166732487197, 4291.82405020289, 4295.570442955679, 4345.391645169679, 4352.216601011785, 4364.323529852878, 4372.825086996484, 4379.1460665170625, 4368.759718215465, 4771.73755443447, 5049.267856160836, 5055.6713813602, 5065.721385323769, 6198.473129784019, 6245.812625621148, 6273.435390814849, 6274.592151510228, 6287.434130928238, 1314.9013823963242, 1342.744878473087, 1424.3732757586629, 1453.941842326295, 1503.7819516170314, 942.330945029037, 997.8203923293731, 1002.9448380339005, 1009.369144284822, 1011.5801942193907, 5178.689278643294, 5216.008958297912, 5522.972385138049, 5617.747201375948, 5828.154917537018, 5169.4572069932865, 5919.526437884959, 6447.4197322241225, 6453.225612926479, 6477.935220647827, 5075.416327122354, 5461.508464238158, 5760.884782304331, 5773.191753154561, 5783.739731712083, 3331.3836940166148, 3363.8486268117995, 3380.797152170878, 3389.694927456541, 3391.1183092396154, 6413.8660915753, 6707.664616769142, 6717.90976150976, 6726.697298621574, 6760.492963027793, 5734.39788822176, 6279.963805972055, 6533.738650454024, 6598.934010659928, 6619.3380661933425, 5502.724863756796, 6133.29467111279, 6683.398494135551, 6706.731730730814, 6725.948585681316, 5625.240592519957, 6038.365971572922, 6042.247748153162, 6066.241719785027, 6369.426751592379, 5296.867578310573, 6235.688215589112, 6240.0, 6284.497240220863, 6331.778387756591, 6422.743090276336, 6684.231313398827, 6717.664116794043, 6783.321667833177, 6819.931800682129, 4027.476428064333, 4151.916961660811, 4155.584441555941, 4188.392683060878, 4194.769287689084, 6277.96861015692, 6420.1974753155855, 6450.258220304538, 6453.580517266362, 6492.623835605553, 6192.938070619416, 6252.687365631609, 6291.874162516816, 6298.685065746603, 6311.842203944938, 5279.604029697711, 6131.63210207389, 6223.128761973237, 6276.372362763687, 6292.590981586182, 5888.528917686661, 5922.920081557652, 6072.271327440735, 6182.969085154546, 6196.535259855439, 5466.808661696821, 5765.279340082489, 6168.35232300602, 6207.255129384323, 6253.155823963707, 5160.48395160481, 6065.727042283196, 6158.643539271018, 6168.290646575701, 6170.598911070766], "value": 4510.429152237003, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.0222035819315, 706.9469789765767, 710.6020628448073, 722.8807246804278, 777.7044723005257, 11979.700507487318, 15459.22703864808, 15476.84523154768, 15483.767743483828, 15491.535253942386, 11879.940600297008, 14040.789388159186, 14139.92930035351, 14174.50389236377, 14219.71560568788, 708.7129712466434, 729.5440349781387, 898.1826537850574, 911.6399022386154, 912.9634814607441, 836.0, 877.0834477970504, 879.160401816264, 880.0803160697087, 880.6917578847384, 955.8231727130503, 973.9902600974006, 976.5361453309692, 976.6679329028129, 977.5210147027941, 751.9323260906544, 776.9067711874554, 776.9300762931307, 958.1711819276311, 969.2149359019216, 684.1482354468718, 712.6508011074588, 714.0431821359404, 714.3428046197458, 716.0083284650744, 15007.774883376758, 15048.473303434437, 15078.246087695681, 15084.547463576111, 15088.24558772057, 12949.741005179938, 12964.48142074316, 13032.41354139066, 13158.210507369493, 13340.533081342106, 10061.223276733192, 10311.587536498531, 10482.423466709328, 10651.30766500175, 10657.893421065844, 3510.78935263885, 3557.5908770491465, 3605.729570282213, 3605.7475976681644, 3673.687736542397, 79186.6040669793, 79474.0, 79560.0, 80045.0, 83544.58227708883, 9690.515474226402, 9695.563699633392, 9818.705438836703, 10014.449205293779, 10124.595016199417, 22362.329130126123, 22392.216272430385, 22394.0, 22407.0, 22415.21546745856, 7426.702931882664, 7554.2445755425115, 7643.694252229849, 7698.34564062061, 7809.3362064225175, 4081.673466122703, 4156.418101465737, 4160.729552579072, 4162.7502349859105, 4231.640310573635, 3521.190126270972, 3592.4790905319105, 3625.0, 3626.4741612466573, 3633.818309084535, 633.4837107757163, 639.3287048598952, 654.3423859021652, 661.1735330836455, 667.552739664426, 540.2706346432363, 557.345119484604, 582.2779753106117, 611.3183800062898, 614.5605891787393, 7719.189485104095, 7737.187595302525, 8038.0, 8045.115037345888, 8315.584220789058, 523.5706720489238, 555.7832445346318, 559.6642014791174, 579.0387955993032, 619.5353484886335, 3687.649673281034, 3768.1898391845884, 3782.867599634055, 3905.9804700976315, 3948.3287841067086, 74115.62942185254, 75633.10917227004, 78483.0, 80981.0, 82031.0, 32242.710291588544, 32573.837130814667, 67072.32927670759, 67470.65058698897, 67840.98238526426, 63910.0, 64235.0, 64761.0, 64813.999999999054, 66476.66761666161, 7432.145303289974, 7533.886991695239, 7590.354819840375, 7639.847203056019, 7681.8463630725955, 32564.51153232751, 41319.96700082522, 42513.0, 42703.57296426996, 43710.56289437065, 45892.77053614844, 57311.42688573061, 59003.739476474184, 59124.70437647956, 59249.40750592439, 10122.8481572775, 10200.948995255274, 10283.742906427398, 10300.536475858755, 10627.840582391425, 10892.0, 10969.0, 11059.83410248863, 11232.0, 11326.83009754871, 8955.910440895508, 10433.843492347773, 10533.683989480327, 10757.13942884583, 10761.623343182951, 10380.377177369382, 10585.52365143555, 10757.677269681923, 10943.179261555575, 11251.606193783506, 9811.313208075366, 10136.290459667753, 10153.25956565667, 10167.339122956984, 10542.736431589272, 8131.9593402032615, 9117.22503587176, 9159.77100572491, 9655.420674759536, 9719.539136723399, 9674.70975870725, 9700.27247956392, 9710.757231068997, 9715.70852874415, 9716.22270218395], "value": 6604.390932951637, "unit": "score"}], "name": "dromaeo_css", "value": 7124.800070464334, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 109.0, 112.0, 113.0, 112.0, 113.0, 117.0, 112.0, 114.0, 114.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 127.0, 144.0, 183.0, 143.0, 147.0, 172.0, 146.0, 206.0, 119.0], "value": 154.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 184.0, 186.0, 185.0, 180.0, 181.0, 182.0, 182.0, 183.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 85.0, 98.0, 81.0, 84.0, 83.0, 81.0, 83.0, 55.0], "value": 79.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 102.0, 136.0, 77.0, 78.0, 80.0, 126.0, 79.0, 78.0, 126.0], "value": 97.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 92.0, 134.0, 117.0, 88.0, 86.0, 90.0, 87.0, 88.0, 86.0], "value": 95.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 100.0, 132.0, 95.0, 94.0, 94.0, 104.0, 149.0, 109.0, 95.0], "value": 106.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 87.0, 84.0, 85.0, 85.0, 85.0, 84.0, 86.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 58.0, 57.0, 58.0, 57.0, 60.0, 76.0, 59.0, 56.0], "value": 59.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 70.0, 70.0, 74.0, 71.0, 72.0, 70.0, 68.0, 68.0, 68.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 105.0, 96.0, 86.0, 99.0, 100.0, 83.0, 87.0, 86.0, 85.0], "value": 92.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 142.0, 146.0, 144.0, 140.0, 143.0, 146.0, 143.0, 177.0, 145.0], "value": 147.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 171.0, 168.0, 166.0, 170.0, 169.0, 167.0, 167.0, 168.0], "value": 168.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 70.0, 70.0, 69.0, 67.0, 68.0, 70.0, 68.0, 69.0], "value": 69.2, "unit": "score"}], "name": "kraken", "value": 1522.2, "alertThreshold": 2.0}]} 09:31:27 INFO - Return code: 0 09:31:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:31:27 ERROR - # TBPL SUCCESS # 09:31:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:31:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:31:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:31:27 INFO - 2016-04-14 09:31:27.082 screenresolution[1965:18934] starting screenresolution argv=screenresolution get 09:31:27 INFO - 2016-04-14 09:31:27.084 screenresolution[1965:18934] Display 0: 1600x1200x32@60 09:31:27 INFO - 2016-04-14 09:31:27.090 screenresolution[1966:18937] starting screenresolution argv=screenresolution list 09:31:27 INFO - Available Modes on Display 0 09:31:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:31:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:31:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:31:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:31:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:31:27 INFO - Intel Iris: 09:31:27 INFO - Chipset Model: Intel Iris 09:31:27 INFO - Type: GPU 09:31:27 INFO - Bus: Built-In 09:31:27 INFO - VRAM (Dynamic, Max): 1536 MB 09:31:27 INFO - Vendor: Intel (0x8086) 09:31:27 INFO - Device ID: 0x0a2e 09:31:27 INFO - Revision ID: 0x0009 09:31:27 INFO - Displays: 09:31:27 INFO - Display: 09:31:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:31:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:31:27 INFO - Display Serial Number: 1600x1200 60 09:31:27 INFO - Main Display: Yes 09:31:27 INFO - Mirror: Off 09:31:27 INFO - Online: Yes 09:31:27 INFO - Rotation: Supported 09:31:27 INFO - Return code: 0 09:31:27 INFO - Running post-action listener: _resource_record_post_action 09:31:27 INFO - Running post-run listener: _resource_record_post_run 09:31:28 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 40960; Write bytes: 693189632; Read time: 1; Write time: 2229 09:31:28 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 375091712; Write bytes: 369357312; Read time: 28136; Write time: 692 09:31:28 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 40960; Write bytes: 296647168; Read time: 1; Write time: 1484 09:31:28 INFO - Running post-run listener: _upload_blobber_files 09:31:28 INFO - Blob upload gear active. 09:31:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:31:28 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:28 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:28 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:28 INFO - (blobuploader) - INFO - Open directory for files ... 09:31:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:31:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:31:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:29 INFO - (blobuploader) - INFO - Done attempting. 09:31:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:31:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:31:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:31 INFO - (blobuploader) - INFO - Done attempting. 09:31:31 INFO - (blobuploader) - INFO - Iteration through files over. 09:31:31 INFO - Return code: 0 09:31:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:31:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:31:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfa945a97951844ea8d12b692f9195845bc6416f980f72f673fad5472f7e329a3f4b179a8f6cfeb0459969834f067cea0684cf0f63a5035551077ae158c33e26", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3bf9aca2c9de211dec106884004822a214ae9175da374ad71a547a230c38fcbf0b2ba028309d3db1cd7f7f6a96dd9cc548ac5e946bd362aa8cb64768931ccc42"} 09:31:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:31:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:31:31 INFO - Contents: 09:31:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfa945a97951844ea8d12b692f9195845bc6416f980f72f673fad5472f7e329a3f4b179a8f6cfeb0459969834f067cea0684cf0f63a5035551077ae158c33e26", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3bf9aca2c9de211dec106884004822a214ae9175da374ad71a547a230c38fcbf0b2ba028309d3db1cd7f7f6a96dd9cc548ac5e946bd362aa8cb64768931ccc42"} 09:31:31 INFO - Running post-run listener: copy_logs_to_upload_dir 09:31:31 INFO - Copying logs to upload dir... 09:31:31 INFO - mkdir: /builds/slave/test/build/upload/logs 09:31:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.021787 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-04-14 09:31:31.587392) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-14 09:31:31.591238) ========= 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.MTDgyfbvxB/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.O8uEUwymUT/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfa945a97951844ea8d12b692f9195845bc6416f980f72f673fad5472f7e329a3f4b179a8f6cfeb0459969834f067cea0684cf0f63a5035551077ae158c33e26", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3bf9aca2c9de211dec106884004822a214ae9175da374ad71a547a230c38fcbf0b2ba028309d3db1cd7f7f6a96dd9cc548ac5e946bd362aa8cb64768931ccc42"} 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.011401 build_url: 'https://queue.taskcluster.net/v1/task/eEHU4yyGRf2AXY--p0xyzQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfa945a97951844ea8d12b692f9195845bc6416f980f72f673fad5472f7e329a3f4b179a8f6cfeb0459969834f067cea0684cf0f63a5035551077ae158c33e26", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3bf9aca2c9de211dec106884004822a214ae9175da374ad71a547a230c38fcbf0b2ba028309d3db1cd7f7f6a96dd9cc548ac5e946bd362aa8cb64768931ccc42"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-14 09:31:31.618639) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 09:31:31.618956) ========= 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.MTDgyfbvxB/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.O8uEUwymUT/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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.005035 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 09:31:31.667813) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 09:31:31.668151) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 09:31:31.668553) ========= ========= Total master_lag: 7.14 =========