builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0226 starttime: 1468057503.89 results: success (0) buildid: 20160709013039 builduid: 33c116035c834f1e971421a4bab83389 revision: fe0ea08656ec28e9d33e381e0653ba65713a7db4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-09 02:45:03.891278) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-09 02:45:03.891781) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-09 02:45:03.892149) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-09 02:45:03.917277) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 02:45:03.917791) ========= 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.samNPNAIuI/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.wCh2HAuewk/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-09 02:45:03-- 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% 17.7M=0.001s 2016-07-09 02:45:04 (17.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.586922 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 02:45:04.620531) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 02:45:04.620883) ========= 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.samNPNAIuI/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.wCh2HAuewk/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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.028565 ========= master_lag: 0.75 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 02:45:05.396040) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-09 02:45:05.396359) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev fe0ea08656ec28e9d33e381e0653ba65713a7db4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev fe0ea08656ec28e9d33e381e0653ba65713a7db4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.samNPNAIuI/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.wCh2HAuewk/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-09 02:45:05,486 truncating revision to first 12 chars 2016-07-09 02:45:05,486 Setting DEBUG logging. 2016-07-09 02:45:05,486 attempt 1/10 2016-07-09 02:45:05,486 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/fe0ea08656ec?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-09 02:45:07,791 unpacking tar archive at: fx-team-fe0ea08656ec/testing/mozharness/ program finished with exit code 0 elapsedTime=2.661958 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-09 02:45:08.138755) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-09 02:45:08.139099) ========= script_repo_revision: fe0ea08656ec28e9d33e381e0653ba65713a7db4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-09 02:45:08.139538) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-09 02:45:08.143398) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-09 02:45:08.265262) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-07-09 02:45:08.265633) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.samNPNAIuI/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.wCh2HAuewk/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 02:45:08 INFO - MultiFileLogger online at 20160709 02:45:08 in /builds/slave/test 02:45:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 02:45:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:45:08 INFO - {'append_to_log': False, 02:45:08 INFO - 'base_work_dir': '/builds/slave/test', 02:45:08 INFO - 'blob_upload_branch': 'Fx-Team', 02:45:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:45:08 INFO - 'branch': 'Fx-Team', 02:45:08 INFO - 'buildbot_json_path': 'buildprops.json', 02:45:08 INFO - 'config_files': ('talos/mac_config.py',), 02:45:08 INFO - 'default_actions': ('clobber', 02:45:08 INFO - 'read-buildbot-config', 02:45:08 INFO - 'download-and-extract', 02:45:08 INFO - 'populate-webroot', 02:45:08 INFO - 'create-virtualenv', 02:45:08 INFO - 'install', 02:45:08 INFO - 'run-tests'), 02:45:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:45:08 INFO - 'download_minidump_stackwalk': True, 02:45:08 INFO - 'download_symbols': 'ondemand', 02:45:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:45:08 INFO - 'tooltool.py': '/tools/tooltool.py', 02:45:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:45:08 INFO - '/tools/misc-python/virtualenv.py')}, 02:45:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:45:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:45:08 INFO - 'installer_path': 'installer.exe', 02:45:08 INFO - 'log_level': 'info', 02:45:08 INFO - 'log_name': 'talos', 02:45:08 INFO - 'log_to_console': True, 02:45:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:45:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:45:08 INFO - 'opt_config_files': (), 02:45:08 INFO - 'pip_index': False, 02:45:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:45:08 INFO - 'cmd': ('bash', 02:45:08 INFO - '-c', 02:45:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:45:08 INFO - 'enabled': True, 02:45:08 INFO - 'halt_on_failure': False, 02:45:08 INFO - 'name': 'check_screen_resolution'},), 02:45:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:45:08 INFO - 'cmd': ('bash', 02:45:08 INFO - '-c', 02:45:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:45:08 INFO - 'enabled': True, 02:45:08 INFO - 'halt_on_failure': False, 02:45:08 INFO - 'name': 'check_screen_resolution'},), 02:45:08 INFO - 'run_cmd_checks_enabled': True, 02:45:08 INFO - 'sps_profile': False, 02:45:08 INFO - 'sps_profile_interval': 0, 02:45:08 INFO - 'suite': 'dromaeojs', 02:45:08 INFO - 'system_bits': '32', 02:45:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:45:08 INFO - 'title': 't-yosemite-r7-0226', 02:45:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:45:08 INFO - 'use_talos_json': True, 02:45:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:45:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:45:08 INFO - 'work_dir': 'build'} 02:45:08 INFO - ##### 02:45:08 INFO - ##### Running clobber step. 02:45:08 INFO - ##### 02:45:08 INFO - Running pre-action listener: _resource_record_pre_action 02:45:08 INFO - Running main action method: clobber 02:45:08 INFO - rmtree: /builds/slave/test/build 02:45:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:45:10 INFO - Running post-action listener: _resource_record_post_action 02:45:10 INFO - ##### 02:45:10 INFO - ##### Finished clobber step (success) 02:45:10 INFO - ##### 02:45:10 INFO - ##### 02:45:10 INFO - ##### Running read-buildbot-config step. 02:45:10 INFO - ##### 02:45:10 INFO - Running pre-action listener: _resource_record_pre_action 02:45:10 INFO - Running main action method: read_buildbot_config 02:45:10 INFO - Using buildbot properties: 02:45:10 INFO - { 02:45:10 INFO - "project": "", 02:45:10 INFO - "product": "firefox", 02:45:10 INFO - "script_repo_revision": "production", 02:45:10 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 02:45:10 INFO - "repository": "", 02:45:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 02:45:10 INFO - "buildid": "20160709013039", 02:45:10 INFO - "pgo_build": "False", 02:45:10 INFO - "basedir": "/builds/slave/test", 02:45:10 INFO - "buildnumber": 880, 02:45:10 INFO - "slavename": "t-yosemite-r7-0226", 02:45:10 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:45:10 INFO - "platform": "yosemite_r7", 02:45:10 INFO - "scheduler": "tests-fx-team-macosx64-talos", 02:45:10 INFO - "branch": "fx-team", 02:45:10 INFO - "repo_path": "integration/fx-team", 02:45:10 INFO - "revision": "fe0ea08656ec28e9d33e381e0653ba65713a7db4", 02:45:10 INFO - "stage_platform": "macosx64", 02:45:10 INFO - "builduid": "33c116035c834f1e971421a4bab83389", 02:45:10 INFO - "slavebuilddir": "test" 02:45:10 INFO - } 02:45:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 02:45:10 INFO - Running post-action listener: _resource_record_post_action 02:45:10 INFO - ##### 02:45:10 INFO - ##### Finished read-buildbot-config step (success) 02:45:10 INFO - ##### 02:45:10 INFO - ##### 02:45:10 INFO - ##### Running download-and-extract step. 02:45:10 INFO - ##### 02:45:10 INFO - Running pre-action listener: _resource_record_pre_action 02:45:10 INFO - Running main action method: download_and_extract 02:45:10 INFO - mkdir: /builds/slave/test/build/tests 02:45:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:45:10 INFO - https://queue.taskcluster.net/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:45:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 02:45:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 02:45:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 02:45:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 02:45:13 INFO - Downloaded 1199 bytes. 02:45:13 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 02:45:13 INFO - Using the following test package requirements: 02:45:13 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 02:45:13 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:45:13 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 02:45:13 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:45:13 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 02:45:13 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:45:13 INFO - u'jsshell-mac.zip'], 02:45:13 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:45:13 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 02:45:13 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 02:45:13 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:45:13 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 02:45:13 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:45:13 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 02:45:13 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:45:13 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 02:45:13 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:45:13 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 02:45:13 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:45: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')]} 02:45:13 INFO - https://queue.taskcluster.net/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:45:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:45:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:45:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:45:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:45:14 INFO - Downloaded 18807119 bytes. 02:45:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:45:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:45:18 INFO - Return code: 0 02:45:18 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 02:45:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:45:18 INFO - https://queue.taskcluster.net/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:45:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:45:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:45:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:45:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:45:24 INFO - Downloaded 18807119 bytes. 02:45:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:45:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:45:28 INFO - Return code: 0 02:45: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')]} 02:45:28 INFO - https://queue.taskcluster.net/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:45:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 02:45:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 02:45:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 02:45:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:45:29 INFO - Downloaded 14403333 bytes. 02:45:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:45:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:45:29 INFO - Return code: 0 02:45: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')]} 02:45:29 INFO - https://queue.taskcluster.net/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 02:45:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:45:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:45:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 02:45:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 02:45:34 INFO - Downloaded 112479327 bytes. 02:45:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:45:34 INFO - mkdir: /builds/slave/test/properties 02:45:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:45:34 INFO - Writing to file /builds/slave/test/properties/build_url 02:45:34 INFO - Contents: 02:45:34 INFO - build_url:https://queue.taskcluster.net/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:45:35 INFO - Running post-action listener: _resource_record_post_action 02:45:35 INFO - Running post-action listener: set_extra_try_arguments 02:45:35 INFO - ##### 02:45:35 INFO - ##### Finished download-and-extract step (success) 02:45:35 INFO - ##### 02:45:35 INFO - ##### 02:45:35 INFO - ##### Running populate-webroot step. 02:45:35 INFO - ##### 02:45:35 INFO - Running pre-action listener: _resource_record_pre_action 02:45:35 INFO - Running main action method: populate_webroot 02:45:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:45:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:45:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:45:35 INFO - '--tppagecycles', 02:45:35 INFO - '7'], 02:45:35 INFO - 'tests': ['tsvgm']}}, 02:45:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:45:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:45:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:45:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:45:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:45:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:45:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:45:35 INFO - 'talos_options': ['--disable-e10s'], 02:45:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:45:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:45:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:45:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:45:35 INFO - 'talos_options': ['--disable-e10s'], 02:45:35 INFO - 'tests': ['damp', 'tps']}, 02:45:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:45:35 INFO - 'tests': ['damp', 'tps']}, 02:45:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:45:35 INFO - 'tests': ['dromaeo_dom']}, 02:45:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:45:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:45:35 INFO - 'tests': ['basic_compositor_video']}, 02:45:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:45:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:45:35 INFO - 'tests': ['a11yr', 02:45:35 INFO - 'ts_paint', 02:45:35 INFO - 'tpaint', 02:45:35 INFO - 'sessionrestore', 02:45:35 INFO - 'sessionrestore_no_auto_restore', 02:45:35 INFO - 'tabpaint']}, 02:45:35 INFO - 'other-e10s': {'tests': ['a11yr', 02:45:35 INFO - 'ts_paint', 02:45:35 INFO - 'tpaint', 02:45:35 INFO - 'sessionrestore', 02:45:35 INFO - 'sessionrestore_no_auto_restore', 02:45:35 INFO - 'tabpaint']}, 02:45:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:45:35 INFO - 'ts_paint', 02:45:35 INFO - 'tpaint', 02:45:35 INFO - 'sessionrestore', 02:45:35 INFO - 'sessionrestore_no_auto_restore', 02:45:35 INFO - 'tabpaint']}, 02:45:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:45:35 INFO - 'ts_paint', 02:45:35 INFO - 'tpaint', 02:45:35 INFO - 'sessionrestore', 02:45:35 INFO - 'sessionrestore_no_auto_restore', 02:45:35 INFO - 'tabpaint']}, 02:45:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:45:35 INFO - 'tests': ['a11yr', 02:45:35 INFO - 'ts_paint', 02:45:35 INFO - 'tpaint', 02:45:35 INFO - 'sessionrestore', 02:45:35 INFO - 'sessionrestore_no_auto_restore', 02:45:35 INFO - 'tabpaint']}, 02:45:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:45:35 INFO - 'tests': ['a11yr', 02:45:35 INFO - 'ts_paint', 02:45:35 INFO - 'tpaint', 02:45:35 INFO - 'sessionrestore', 02:45:35 INFO - 'sessionrestore_no_auto_restore', 02:45:35 INFO - 'tabpaint']}, 02:45:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:45:35 INFO - 'tests': ['tsvgx', 02:45:35 INFO - 'tsvgr_opacity', 02:45:35 INFO - 'tart', 02:45:35 INFO - 'tscrollx', 02:45:35 INFO - 'cart']}, 02:45:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:45:35 INFO - 'tsvgr_opacity', 02:45:35 INFO - 'tart', 02:45:35 INFO - 'tscrollx', 02:45:35 INFO - 'cart']}, 02:45:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:45:35 INFO - 'talos_options': ['--disable-e10s'], 02:45:35 INFO - 'tests': ['tp5o']}, 02:45:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:45:35 INFO - 'tests': ['tp5o']}, 02:45:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:45:35 INFO - 'talos_options': ['--disable-e10s', 02:45:35 INFO - '--xperf_path', 02:45:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:45:35 INFO - 'tests': ['tp5n']}, 02:45:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:45:35 INFO - 'talos_options': ['--xperf_path', 02:45:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:45:35 INFO - 'tests': ['tp5n']}}, 02:45:35 INFO - 'talos.zip': {'path': '', 02:45:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:45:35 INFO - Running post-action listener: _resource_record_post_action 02:45:35 INFO - ##### 02:45:35 INFO - ##### Finished populate-webroot step (success) 02:45:35 INFO - ##### 02:45:35 INFO - ##### 02:45:35 INFO - ##### Running create-virtualenv step. 02:45:35 INFO - ##### 02:45:35 INFO - Running pre-action listener: _resource_record_pre_action 02:45:35 INFO - Running main action method: create_virtualenv 02:45:35 INFO - Creating virtualenv /builds/slave/test/build/venv 02:45:35 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 02:45:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:45:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:45:35 INFO - Using real prefix '/tools/python27' 02:45:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:45:36 INFO - Installing distribute.............................................................................................................................................................................................done. 02:45:38 INFO - Installing pip.................done. 02:45:38 INFO - Return code: 0 02:45:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:45: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')]} 02:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45: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')]} 02:45:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:38 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 0x10ac8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad22618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5c2d820a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf1d30>, '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 0x10a956db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abe9b20>, '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.samNPNAIuI/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.wCh2HAuewk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 02:45:38 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 02:45:38 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 02:45:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.samNPNAIuI/Render', 02:45:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:38 INFO - 'HOME': '/Users/cltbld', 02:45:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:38 INFO - 'LOGNAME': 'cltbld', 02:45:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:38 INFO - 'MOZ_NO_REMOTE': '1', 02:45:38 INFO - 'NO_EM_RESTART': '1', 02:45:38 INFO - 'PAGER': '/bin/cat', 02:45:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:38 INFO - 'PWD': '/builds/slave/test', 02:45:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:38 INFO - 'SHELL': '/bin/bash', 02:45:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wCh2HAuewk/Listeners', 02:45:38 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 02:45:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:38 INFO - 'USER': 'cltbld', 02:45:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:38 INFO - 'XPC_FLAGS': '0x0', 02:45:38 INFO - 'XPC_SERVICE_NAME': '0', 02:45:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:39 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 02:45:39 INFO - Cleaning up... 02:45:39 INFO - Return code: 0 02:45:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:45: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')]} 02:45:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45: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')]} 02:45:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ac8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad22618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5c2d820a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf1d30>, '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 0x10a956db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abe9b20>, '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.samNPNAIuI/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.wCh2HAuewk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 02:45:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:45:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:45:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.samNPNAIuI/Render', 02:45:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:39 INFO - 'HOME': '/Users/cltbld', 02:45:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:39 INFO - 'LOGNAME': 'cltbld', 02:45:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:39 INFO - 'MOZ_NO_REMOTE': '1', 02:45:39 INFO - 'NO_EM_RESTART': '1', 02:45:39 INFO - 'PAGER': '/bin/cat', 02:45:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:39 INFO - 'PWD': '/builds/slave/test', 02:45:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:39 INFO - 'SHELL': '/bin/bash', 02:45:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wCh2HAuewk/Listeners', 02:45:39 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 02:45:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:39 INFO - 'USER': 'cltbld', 02:45:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:39 INFO - 'XPC_FLAGS': '0x0', 02:45:39 INFO - 'XPC_SERVICE_NAME': '0', 02:45:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:39 INFO - Downloading/unpacking psutil>=3.1.1 02:45:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:45:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:45:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:45:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:45:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:45:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:45:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:45:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:45:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:45:42 INFO - Installing collected packages: psutil 02:45:42 INFO - Running setup.py install for psutil 02:45:42 INFO - building 'psutil._psutil_osx' extension 02:45:42 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 02:45:43 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 02:45:43 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 02:45:43 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 02:45:43 INFO - building 'psutil._psutil_posix' extension 02:45:43 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 02:45:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:45:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:45:43 INFO - ^ 02:45:43 INFO - 1 warning generated. 02:45:43 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 02:45:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:45:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:45:43 INFO - Successfully installed psutil 02:45:43 INFO - Cleaning up... 02:45:43 INFO - Return code: 0 02:45:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:45:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:45:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:45:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ac8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad22618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5c2d820a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf1d30>, '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 0x10a956db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abe9b20>, '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.samNPNAIuI/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.wCh2HAuewk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 02:45:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:45:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:45:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.samNPNAIuI/Render', 02:45:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:43 INFO - 'HOME': '/Users/cltbld', 02:45:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:43 INFO - 'LOGNAME': 'cltbld', 02:45:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:43 INFO - 'MOZ_NO_REMOTE': '1', 02:45:43 INFO - 'NO_EM_RESTART': '1', 02:45:43 INFO - 'PAGER': '/bin/cat', 02:45:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:43 INFO - 'PWD': '/builds/slave/test', 02:45:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:43 INFO - 'SHELL': '/bin/bash', 02:45:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wCh2HAuewk/Listeners', 02:45:43 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 02:45:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:43 INFO - 'USER': 'cltbld', 02:45:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:43 INFO - 'XPC_FLAGS': '0x0', 02:45:43 INFO - 'XPC_SERVICE_NAME': '0', 02:45:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:45:43 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 02:45:43 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 02:45:43 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 02:45:43 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 02:45:43 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 02:45:43 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 02:45:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:45:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:45:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:45:46 INFO - Installing collected packages: mozsystemmonitor 02:45:46 INFO - Running setup.py install for mozsystemmonitor 02:45:46 INFO - Successfully installed mozsystemmonitor 02:45:46 INFO - Cleaning up... 02:45:46 INFO - Return code: 0 02:45:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:45:46 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')]} 02:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:46 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')]} 02:45:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ac8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad22618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5c2d820a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf1d30>, '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 0x10a956db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abe9b20>, '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.samNPNAIuI/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.wCh2HAuewk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 02:45:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:45:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:45:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.samNPNAIuI/Render', 02:45:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:46 INFO - 'HOME': '/Users/cltbld', 02:45:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:46 INFO - 'LOGNAME': 'cltbld', 02:45:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:46 INFO - 'MOZ_NO_REMOTE': '1', 02:45:46 INFO - 'NO_EM_RESTART': '1', 02:45:46 INFO - 'PAGER': '/bin/cat', 02:45:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:46 INFO - 'PWD': '/builds/slave/test', 02:45:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:46 INFO - 'SHELL': '/bin/bash', 02:45:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wCh2HAuewk/Listeners', 02:45:46 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 02:45:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:46 INFO - 'USER': 'cltbld', 02:45:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:46 INFO - 'XPC_FLAGS': '0x0', 02:45:46 INFO - 'XPC_SERVICE_NAME': '0', 02:45:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:46 INFO - Downloading/unpacking jsonschema==2.5.1 02:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:45:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:45:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:45:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:45:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:45:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:45:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:45:49 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 02:45:49 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 02:45:49 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 02:45:49 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 02:45:49 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 02:45:49 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 02:45:49 INFO - Downloading functools32-3.2.3-2.tar.gz 02:45:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:45:50 INFO - warning: no files found matching '*.txt' 02:45:50 INFO - no previously-included directories found matching 'build' 02:45:50 INFO - no previously-included directories found matching 'dist' 02:45:50 INFO - no previously-included directories found matching '.git*' 02:45:50 INFO - Installing collected packages: jsonschema, functools32 02:45:50 INFO - Running setup.py install for functools32 02:45:50 INFO - warning: no files found matching '*.txt' 02:45:50 INFO - no previously-included directories found matching 'build' 02:45:50 INFO - no previously-included directories found matching 'dist' 02:45:50 INFO - no previously-included directories found matching '.git*' 02:45:50 INFO - Successfully installed jsonschema functools32 02:45:50 INFO - Cleaning up... 02:45:50 INFO - Return code: 0 02:45:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:45:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:45:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:50 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 0x10ac8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad22618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5c2d820a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf1d30>, '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 0x10a956db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abe9b20>, '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.samNPNAIuI/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.wCh2HAuewk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 02:45:50 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 02:45:50 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 02:45:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.samNPNAIuI/Render', 02:45:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:50 INFO - 'HOME': '/Users/cltbld', 02:45:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:50 INFO - 'LOGNAME': 'cltbld', 02:45:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:50 INFO - 'MOZ_NO_REMOTE': '1', 02:45:50 INFO - 'NO_EM_RESTART': '1', 02:45:50 INFO - 'PAGER': '/bin/cat', 02:45:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:50 INFO - 'PWD': '/builds/slave/test', 02:45:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:50 INFO - 'SHELL': '/bin/bash', 02:45:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wCh2HAuewk/Listeners', 02:45:50 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 02:45:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:50 INFO - 'USER': 'cltbld', 02:45:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:50 INFO - 'XPC_FLAGS': '0x0', 02:45:50 INFO - 'XPC_SERVICE_NAME': '0', 02:45:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:50 INFO - Downloading/unpacking blobuploader==1.2.4 02:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:45:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:45:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:45:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:45:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:45:53 INFO - Downloading blobuploader-1.2.4.tar.gz 02:45:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:45:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:45:53 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 02:45:53 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 02:45:53 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 02:45:53 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 02:45:53 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 02:45:53 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 02:45:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:45:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:45:53 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 02:45:53 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 02:45:53 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 02:45:53 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 02:45:53 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 02:45:53 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 02:45:54 INFO - Downloading docopt-0.6.1.tar.gz 02:45:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:45:54 INFO - Installing collected packages: blobuploader, requests, docopt 02:45:54 INFO - Running setup.py install for blobuploader 02:45:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:45:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:45:54 INFO - Running setup.py install for requests 02:45:54 INFO - Running setup.py install for docopt 02:45:55 INFO - Successfully installed blobuploader requests docopt 02:45:55 INFO - Cleaning up... 02:45:55 INFO - Return code: 0 02:45:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:45: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')]} 02:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45: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')]} 02:45:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:55 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 0x10ac8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad22618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5c2d820a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf1d30>, '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 0x10a956db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abe9b20>, '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.samNPNAIuI/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.wCh2HAuewk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 02:45:55 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 02:45:55 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 02:45:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.samNPNAIuI/Render', 02:45:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:55 INFO - 'HOME': '/Users/cltbld', 02:45:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:55 INFO - 'LOGNAME': 'cltbld', 02:45:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:55 INFO - 'MOZ_NO_REMOTE': '1', 02:45:55 INFO - 'NO_EM_RESTART': '1', 02:45:55 INFO - 'PAGER': '/bin/cat', 02:45:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:55 INFO - 'PWD': '/builds/slave/test', 02:45:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:55 INFO - 'SHELL': '/bin/bash', 02:45:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wCh2HAuewk/Listeners', 02:45:55 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 02:45:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:55 INFO - 'USER': 'cltbld', 02:45:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:55 INFO - 'XPC_FLAGS': '0x0', 02:45:55 INFO - 'XPC_SERVICE_NAME': '0', 02:45:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:45:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-f6nmGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:45:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-ulYeAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:45:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-q6oWj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:45:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-RPHyfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:45:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-6T1xVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:45:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-iNk3DV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:45:56 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-a7fV5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:45:56 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-BSK4N1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:45:56 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-ztRoV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:45:56 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-d5GYKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:45:56 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-xLsT3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:45:56 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-X9P9i7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:45:56 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-vewqrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:45:56 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-oVPi7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:45:56 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-Hh1Upy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:45:56 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-K4_sEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:45:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:45:57 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-w7X0KA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:45:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:45:57 INFO - Running setup.py install for manifestparser 02:45:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:45:57 INFO - Running setup.py install for mozcrash 02:45:57 INFO - Running setup.py install for mozdebug 02:45:57 INFO - Running setup.py install for mozdevice 02:45:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:45:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:45:57 INFO - Running setup.py install for mozfile 02:45:57 INFO - Running setup.py install for mozhttpd 02:45:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:45:58 INFO - Running setup.py install for mozinfo 02:45:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:45:58 INFO - Running setup.py install for mozInstall 02:45:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:45:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:45:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:45:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:45:58 INFO - Running setup.py install for mozleak 02:45:58 INFO - Running setup.py install for mozlog 02:45:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:45:58 INFO - Running setup.py install for moznetwork 02:45:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:45:58 INFO - Running setup.py install for mozprocess 02:45:58 INFO - Running setup.py install for mozprofile 02:45:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:45:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:45:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:45:59 INFO - Running setup.py install for mozrunner 02:45:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:45:59 INFO - Running setup.py install for mozscreenshot 02:45:59 INFO - Running setup.py install for moztest 02:45:59 INFO - Running setup.py install for mozversion 02:45:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:45:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:45:59 INFO - Cleaning up... 02:45:59 INFO - Return code: 0 02:45:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:45: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')]} 02:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45: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')]} 02:45:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:59 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 0x10ac8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad22618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5c2d820a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf1d30>, '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 0x10a956db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abe9b20>, '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.samNPNAIuI/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.wCh2HAuewk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 02:45:59 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 02:45:59 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 02:45:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.samNPNAIuI/Render', 02:45:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:59 INFO - 'HOME': '/Users/cltbld', 02:45:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:59 INFO - 'LOGNAME': 'cltbld', 02:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:59 INFO - 'MOZ_NO_REMOTE': '1', 02:45:59 INFO - 'NO_EM_RESTART': '1', 02:45:59 INFO - 'PAGER': '/bin/cat', 02:45:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:59 INFO - 'PWD': '/builds/slave/test', 02:45:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:59 INFO - 'SHELL': '/bin/bash', 02:45:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wCh2HAuewk/Listeners', 02:45:59 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 02:45:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:59 INFO - 'USER': 'cltbld', 02:45:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:59 INFO - 'XPC_FLAGS': '0x0', 02:45:59 INFO - 'XPC_SERVICE_NAME': '0', 02:45:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:46:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:46:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-rYjrZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:46:00 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)) 02:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:46:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-ROeBnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:46:00 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)) 02:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:46:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-F_m9cd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:46:00 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)) 02:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:46:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-mT6YwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:46:00 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)) 02:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:46:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-KVx0Ai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:46:00 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)) 02:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:46:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-HlYj6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:46:00 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)) 02:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:46:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-4OAMhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:46:00 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)) 02:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:46:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-7BdOfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:46:00 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)) 02:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:46:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-vpB4pJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:46:00 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)) 02:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:46:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-txl8TT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:46:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 02:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:46:01 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-MuRZQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:46:01 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)) 02:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:46:01 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-3WrE4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:46:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:46:01 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-XfwPFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:46:01 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)) 02:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:46:01 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-RVVJui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:46:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:46:01 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-aJc2Bp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:46:01 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)) 02:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:46:01 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-2LqTSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:46:01 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)) 02:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:46:01 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-FR2620-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:46:01 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)) 02:46:01 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)) 02:46:01 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)) 02:46:01 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)) 02:46:01 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)) 02:46:01 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)) 02:46:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:46:01 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 02:46:01 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 02:46:01 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 02:46:01 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 02:46:01 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 02:46:01 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 02:46:04 INFO - Downloading blessings-1.6.tar.gz 02:46:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:46:04 INFO - Installing collected packages: blessings 02:46:04 INFO - Running setup.py install for blessings 02:46:04 INFO - Successfully installed blessings 02:46:04 INFO - Cleaning up... 02:46:04 INFO - Return code: 0 02:46:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:46:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:46:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:46:04 INFO - Reading from file tmpfile_stdout 02:46:04 INFO - Current package versions: 02:46:04 INFO - blessings == 1.6 02:46:04 INFO - blobuploader == 1.2.4 02:46:04 INFO - docopt == 0.6.1 02:46:04 INFO - functools32 == 3.2.3-2 02:46:04 INFO - jsonschema == 2.5.1 02:46:04 INFO - manifestparser == 1.1 02:46:04 INFO - mozInstall == 1.12 02:46:04 INFO - mozcrash == 0.17 02:46:04 INFO - mozdebug == 0.1 02:46:04 INFO - mozdevice == 0.48 02:46:04 INFO - mozfile == 1.2 02:46:04 INFO - mozhttpd == 0.7 02:46:04 INFO - mozinfo == 0.9 02:46:04 INFO - mozleak == 0.1 02:46:04 INFO - mozlog == 3.2 02:46:04 INFO - moznetwork == 0.27 02:46:04 INFO - mozprocess == 0.23 02:46:04 INFO - mozprofile == 0.28 02:46:04 INFO - mozrunner == 6.12 02:46:04 INFO - mozscreenshot == 0.1 02:46:04 INFO - mozsystemmonitor == 0.3 02:46:04 INFO - moztest == 0.7 02:46:04 INFO - mozversion == 1.4 02:46:04 INFO - psutil == 3.1.1 02:46:04 INFO - requests == 1.2.3 02:46:04 INFO - wsgiref == 0.1.2 02:46:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:46: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')]} 02:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:46:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:46: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')]} 02:46:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:46:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:46:04 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 0x10ac8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad22618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5c2d820a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf1d30>, '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 0x10a956db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abe9b20>, '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.samNPNAIuI/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.wCh2HAuewk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 02:46:04 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 02:46:04 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 02:46:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.samNPNAIuI/Render', 02:46:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:46:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:46:04 INFO - 'HOME': '/Users/cltbld', 02:46:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:46:04 INFO - 'LOGNAME': 'cltbld', 02:46:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:46:04 INFO - 'MOZ_NO_REMOTE': '1', 02:46:04 INFO - 'NO_EM_RESTART': '1', 02:46:04 INFO - 'PAGER': '/bin/cat', 02:46:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:46:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:46:04 INFO - 'PWD': '/builds/slave/test', 02:46:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:46:04 INFO - 'SHELL': '/bin/bash', 02:46:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wCh2HAuewk/Listeners', 02:46:04 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 02:46:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:46:04 INFO - 'USER': 'cltbld', 02:46:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:46:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:46:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:46:04 INFO - 'XPC_FLAGS': '0x0', 02:46:04 INFO - 'XPC_SERVICE_NAME': '0', 02:46:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:46:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:46:05 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)) 02:46:05 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)) 02:46:05 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)) 02:46:05 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)) 02:46:05 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)) 02:46:05 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)) 02:46:05 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)) 02:46:05 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)) 02:46:05 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)) 02:46:05 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)) 02:46:05 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)) 02:46:05 INFO - Cleaning up... 02:46:05 INFO - Return code: 0 02:46:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:46:05 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')]} 02:46:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:46:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:46:05 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')]} 02:46:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:46:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:46:05 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 0x10ac8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad22618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5c2d820a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf1d30>, '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 0x10a956db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abe9b20>, '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.samNPNAIuI/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.wCh2HAuewk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 02:46:05 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 02:46:05 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 02:46:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.samNPNAIuI/Render', 02:46:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:46:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:46:05 INFO - 'HOME': '/Users/cltbld', 02:46:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:46:05 INFO - 'LOGNAME': 'cltbld', 02:46:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:46:05 INFO - 'MOZ_NO_REMOTE': '1', 02:46:05 INFO - 'NO_EM_RESTART': '1', 02:46:05 INFO - 'PAGER': '/bin/cat', 02:46:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:46:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:46:05 INFO - 'PWD': '/builds/slave/test', 02:46:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:46:05 INFO - 'SHELL': '/bin/bash', 02:46:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wCh2HAuewk/Listeners', 02:46:05 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 02:46:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:46:05 INFO - 'USER': 'cltbld', 02:46:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:46:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:46:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:46:05 INFO - 'XPC_FLAGS': '0x0', 02:46:05 INFO - 'XPC_SERVICE_NAME': '0', 02:46:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:46:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:46:05 INFO - Cleaning up... 02:46:05 INFO - Return code: 0 02:46:05 INFO - Running post-action listener: _resource_record_post_action 02:46:05 INFO - Running post-action listener: _start_resource_monitoring 02:46:05 INFO - Starting resource monitoring. 02:46:05 INFO - ##### 02:46:05 INFO - ##### Finished create-virtualenv step (success) 02:46:05 INFO - ##### 02:46:05 INFO - ##### 02:46:05 INFO - ##### Running install step. 02:46:05 INFO - ##### 02:46:05 INFO - Running pre-action listener: _resource_record_pre_action 02:46:05 INFO - Running main action method: install 02:46:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:46:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:46:05 INFO - Reading from file tmpfile_stdout 02:46:05 INFO - Detecting whether we're running mozinstall >=1.0... 02:46:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:46:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:46:05 INFO - Reading from file tmpfile_stdout 02:46:05 INFO - Output received: 02:46:05 INFO - Usage: mozinstall [options] installer 02:46:05 INFO - Options: 02:46:05 INFO - -h, --help show this help message and exit 02:46:05 INFO - -d DEST, --destination=DEST 02:46:05 INFO - Directory to install application into. [default: 02:46:05 INFO - "/builds/slave/test"] 02:46:05 INFO - --app=APP Application being installed. [default: firefox] 02:46:05 INFO - mkdir: /builds/slave/test/build/application 02:46:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:46:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 02:46:40 INFO - Reading from file tmpfile_stdout 02:46:40 INFO - Output received: 02:46:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:46:40 INFO - Running post-action listener: _resource_record_post_action 02:46:40 INFO - ##### 02:46:40 INFO - ##### Finished install step (success) 02:46:40 INFO - ##### 02:46:40 INFO - ##### 02:46:40 INFO - ##### Running run-tests step. 02:46:40 INFO - ##### 02:46:40 INFO - Running pre-action listener: _resource_record_pre_action 02:46:40 INFO - Running main action method: run_tests 02:46:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:46:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:46:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:46:40 INFO - 2016-07-09 02:46:40.947 screenresolution[1651:9222] starting screenresolution argv=screenresolution get 02:46:40 INFO - 2016-07-09 02:46:40.950 screenresolution[1651:9222] Display 0: 1600x1200x32@60 02:46:40 INFO - 2016-07-09 02:46:40.956 screenresolution[1652:9224] starting screenresolution argv=screenresolution list 02:46:40 INFO - Available Modes on Display 0 02:46:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:46:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:46:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:46:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:46:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:46:41 INFO - Intel Iris: 02:46:41 INFO - Chipset Model: Intel Iris 02:46:41 INFO - Type: GPU 02:46:41 INFO - Bus: Built-In 02:46:41 INFO - VRAM (Dynamic, Max): 1536 MB 02:46:41 INFO - Vendor: Intel (0x8086) 02:46:41 INFO - Device ID: 0x0a2e 02:46:41 INFO - Revision ID: 0x0009 02:46:41 INFO - Displays: 02:46:41 INFO - Display: 02:46:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:46:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:46:41 INFO - Display Serial Number: 1600x1200 60 02:46:41 INFO - Main Display: Yes 02:46:41 INFO - Mirror: Off 02:46:41 INFO - Online: Yes 02:46:41 INFO - Rotation: Supported 02:46:41 INFO - Return code: 0 02:46:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:46:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:46:41 INFO - Python 2.7.3 02:46:41 INFO - Return code: 0 02:46:41 INFO - grabbing minidump binary from tooltool 02:46:41 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')]} 02:46:41 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf1d30>, '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 0x10a956db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abe9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:46:41 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 02:46:41 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 02:46:41 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:46:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:46:41 INFO - Return code: 0 02:46:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:46:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:46:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:46:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:46:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:46:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--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 02:46:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0226 --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 02:46:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.samNPNAIuI/Render', 02:46:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:46:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:46:41 INFO - 'HOME': '/Users/cltbld', 02:46:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:46:41 INFO - 'LOGNAME': 'cltbld', 02:46:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:46:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:46:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:46:41 INFO - 'MOZ_NO_REMOTE': '1', 02:46:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:46:41 INFO - 'NO_EM_RESTART': '1', 02:46:41 INFO - 'PAGER': '/bin/cat', 02:46:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:46:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:46:41 INFO - 'PWD': '/builds/slave/test', 02:46:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:46:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:46:41 INFO - 'SHELL': '/bin/bash', 02:46:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wCh2HAuewk/Listeners', 02:46:41 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 02:46:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:46:41 INFO - 'USER': 'cltbld', 02:46:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:46:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:46:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:46:41 INFO - 'XPC_FLAGS': '0x0', 02:46:41 INFO - 'XPC_SERVICE_NAME': '0', 02:46:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:46:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--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 02:46:41 INFO - mozversion application_buildid: 20160709013039 02:46:41 INFO - mozversion application_changeset: fe0ea08656ec28e9d33e381e0653ba65713a7db4 02:46:41 INFO - mozversion application_display_name: Nightly 02:46:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:46:41 INFO - mozversion application_name: Firefox 02:46:41 INFO - mozversion application_remotingname: firefox 02:46:41 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 02:46:41 INFO - mozversion application_vendor: Mozilla 02:46:41 INFO - mozversion application_version: 50.0a1 02:46:41 INFO - mozversion platform_buildid: 20160709013039 02:46:41 INFO - mozversion platform_changeset: fe0ea08656ec28e9d33e381e0653ba65713a7db4 02:46:41 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 02:46:41 INFO - mozversion platform_version: 50.0a1 02:46:41 INFO - using testdate: 1468057601 02:46:41 INFO - actual date: 1468057601 02:46:41 INFO - starting webserver on 'localhost:49271' 02:46:41 INFO - SUITE-START | Running 2 tests 02:46:41 INFO - TEST-START | dromaeo_css 02:46:41 INFO - operating with platform_type : mac_ 02:46:41 INFO - Initialising browser for dromaeo_css test... 02:46:41 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpQElh2q/profile http://localhost:49271/getInfo.html) 02:46:43 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 02:46:43 INFO - PROCESS | 1662 | colorDepth:24 02:46:43 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 02:46:43 INFO - PROCESS | 1662 | __metrics 02:46:43 INFO - PROCESS | 1662 | 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" 02:46:43 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:46:44 INFO - TEST-INFO | 1662: exit 0 02:46:44 INFO - Browser initialized. 02:46:44 INFO - Running cycle 1/1 for dromaeo_css test... 02:46:44 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpQElh2q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:46:56 INFO - PROCESS | 1665 | RSS: Main: 243929088 02:46:56 INFO - PROCESS | 1665 | 02:47:48 INFO - PROCESS | 1665 | 1468057668185 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] 02:49:58 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 02:50:00 INFO - PROCESS | 1665 | RSS: Main: 403501056 02:50:00 INFO - PROCESS | 1665 | 02:53:03 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 02:53:04 INFO - PROCESS | 1665 | RSS: Main: 380436480 02:53:04 INFO - PROCESS | 1665 | 02:56:06 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 02:56:07 INFO - PROCESS | 1665 | RSS: Main: 372187136 02:56:07 INFO - PROCESS | 1665 | 02:59:10 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 02:59:10 INFO - PROCESS | 1665 | RSS: Main: 373334016 02:59:10 INFO - PROCESS | 1665 | 03:02:13 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 03:02:14 INFO - PROCESS | 1665 | RSS: Main: 338948096 03:02:14 INFO - PROCESS | 1665 | 03:05:17 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 03:05:17 INFO - PROCESS | 1665 | RSS: Main: 421470208 03:05:17 INFO - PROCESS | 1665 | 03:05:17 INFO - PROCESS | 1665 | __start_tp_report 03:05:17 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 03:05:17 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 03:05:17 INFO - PROCESS | 1665 | |i|pagename|runs| 03:05:17 INFO - PROCESS | 1665 | |0;dojo.html;2656.9070082547114;3375.1055970167895;3380.5943286805577;3420.657934206578;3439.2605589798204;5588.133839254911;5636.577256705748;5732.2261494698205;5740.5120564752;5770.278715160605;5676.1769543416285;5730.713464326777;5732.770689172438;5732.856678583027;5755.079187330028;4328.458942632171;5179.015986962475;5208.0885844977165;5214.452482489341;5284.841454756363;5230.816921407752;5313.468653134694;5321.467853214686;5322.281491998569;5328;5146.845594632148;5183.50756678115;5184.637075404723;5200.635955483118;5322.228276899867;5284.4187139414635;5320.17537281722;5337.11937530307;5344.198370244456;5386.596005299618;5126.512981266774;5129.461406552296;5131.025105229995;5251.527362537352;5252.789888404459;5600.915986260209;5614.1859430382365;5704.629199102045;5719.19931209631;5741.052726300156;6240.687965601702;6244.1570387997945;6247.344028876993;6252.718627661766;6264.624122552661;6094.085887116924;6178.289496707891;6241.095041219043;6290.433860952537;6297.433231009231;5909.704514774244;5915.881682366328;5926.436988486087;5933.525317974638;5946.583739138262;6399.488040956712;6407.327230640715;6409.903851442232;6415.326390728999;6423.389777971085;6006.309274433496;6081.908771368433;6085.6348619083;6128.019516877278;6192.721327540181;6183.134361189437;6196.194494715748;6267.310595834455;6271.498280137669;6275.152854364694;6192.411720886508;6236.844078897973;6240.282367527792;6250.937490625127;6265.248170219603;6116.51838140169;6186.628802271878;6208.906866397007;6288.371162883766;6295.496360291166;4960.950390496121;4962.826301079445;4967.155583550805;4973.1297023020825;4974.37820272466;3791.260704162662;4455.554444555519;4502.144592527475;4503.301988191835;4507.797149128297;5688.317401911797;5800.59395842287;5821.27234095738;5833.562482813721;5905.202797622352;6261.937380626136;6273.592216506004;6310.779122730774;6332.050192471166;6337.493000559944;5779.450952159538;5780.508656764222;5790;5808.796692115756;5834.562407819346;4899.167141585939;4986.975065124651;4998.775055122455;5433.646812957145;5465.262189604432;6341.9740777667;6349.238091428966;6377.3303803100935;6378.202724659418;6385.808425747235;6307.3377295383325;6338.58799178052;6340.7463701451425;6355.046742988588;6368.64972426521;6253.7185826637;6281.937180628318;6310.905336420049;6334.271558770801;6426.646534440651;6238.157848690369;6241.875162496815;6243.656598887105;6257.812265632038;6283.874322513433;6143.941779503916;6307;6327.92425287693;6336.461400780985;6346.270178929298;4990.476000019946;5350.384705758887;6025.337212906665;6073;6122.602030867979;6105.065535583811;6116.974953844038;6165.0444181152;6170.5372097092;6187.81436556904;6031.7587296507645;6044.123602077586;6061.454469097626;6090.908636370546;6091.116788065794;5965.194698715618;6026.517878569616;6151.537138824579;6196.132541444112;6206.658633775186;5930.377310382521;5997.670128142994;6005.099235114593;6010;6018.097285407224 03:05:17 INFO - PROCESS | 1665 | |1;ext.html;473.9905201895962;547;550.5347980956093;551.5008916930178;570.1874828369572;30500.237494062658;31832.522512162308;41393.99999999996;42725.57274427255;42829;44336.77831610837;44625.553744462544;44689.776551117284;44741;44806.551934480645;9799.853002204973;9962.701118966443;9986.750331241705;9994.350367226107;9999.400035997864;8873.68942087027;9059.003509613936;9064.728058158233;9215.95392023039;9231.861522077174;11945.581904633296;12123.696907577292;12125.575604853788;12388.93805530971;12451;12115.818262726067;12217.572384966483;12225.938870305681;12231.021518278516;12243.142979991404;8742.038375778659;9936.80126397468;12070.456829442695;12127;12206.023518118529;42253.57746422558;42495;42570.57429425697;42597.36103958443;42617.78691106556;3479.425894727367;3502.2295095079044;3655.744097913147;3656.926861462756;3675.9264814703824;31680.524792128137;31897.681023189703;31918.840405797822;31990.520142197885;32084.679153208403;2724.346156922344;3152.8265945372996;3181.3319202967173;3230.842664227059;3298.901032969015;415352;493016;496062;496710.51644741907;497980.9999999964;54473.72763136159;55673;55765.605859853014;63809;63904;66703.66648166825;66773;67166.66416667885;72004.63997680083;72106.9183962241;16912.492625221264;16912.74630880513;17046.57383565396;17059.573510662085;17078.658426831393;12653.051021173449;12701.872981270253;12705.110642254953;12705.17416367933;12721.109522333349;17129.14354282281;17451.91274043647;17458.912705436647;17858;19649.31227407034;6696.029075784081;7542.812584350477;8514.361422893307;8561.272291855263;8567.186117318844;894.959726812295;975.23444096384;1085.8208395614793;1140.6920131564598;1158.6350299655583;31067.378652426825;31523;31646.841765791025;32087.839560802047;32294.19264518359;1094.1137678480457;1131.4851742457163;1190.732085280805;1213.7875871722413;1277.2528071078523;15073.773893391608;16110.194490275439;16236.431724889571;16312.429064982669;16661.750073748903;722635.8682065876;728890;730552;731410;731480;42856.78571607122;44240.3363949541;53012.73493632507;53747;53862;1182.9585964491203;1301.7721898667696;1331.2411925202734;1364.8566900475307;1420.9413986580064;28795.136145915658;32095.197620059684;32369.028929132164;32480.512792308607;32668.50997235091;50922.74538627283;51238.74380628073;51610.709732257;51715;51716.74141629268;465307;467817;467992;468825;469737;23801.166959156348;24091.518169636158;24149.396265093514;27672.308192295353;28291.71708282891;47514.27544623242;48946.26580601223;49203.261951070046;49483;50501.74749126231;30040.699593003792;40098.79703608897;40500.1899962005;41287.87690348376;41552;31629.683703162675;33020.33959320752;40726;41385.79307103445;42452;39109;39595.60404395919;39979;40204.59283925049;40216.59783402128;28530.00144994915;34096;35090.12773069049;35362.823185883906;35369;21146;21769.67345489787;26171.214863554123;26679.6660166987;27059.052933148032 03:05:17 INFO - PROCESS | 1665 | |2;jquery.html;303.3689924956092;351.9612842587315;382.87939299120774;389.6863025264661;391.9353306704394;15817.920910395445;16606.584835379108;17177.055261960588;20457.386278411635;20493.385198444033;16386.50840474785;20166.39500814978;20732.378028659165;21143.837088960103;21203;5175.767090480937;5732.31212254529;5867.090600956859;5990.311114221855;5993;3828.9808550957205;4017.2969730297227;4572.97713511432;4971.577415919651;5885.087811389241;5847.386024467433;5929.73316200772;5930.377310382412;5933.22868027158;5940.821775346746;5815;6550.901736473933;6558.93441065588;6563.343665633399;6563.7702680405955;3724.646158614927;5469.56243500519;5724.112762521816;5740.282464691913;5745.2818397700285;13171.143875648148;13186.868131318755;13344.199348039148;13420.396082176325;13432.932835335858;2065.7727649958492;2225.354647152324;2301.7813307735737;2415.8429702069484;2982.1053683894743;9942.9502852486;10023.498825058718;10297.073263406255;10510.842337364946;10517.1060459861;1848.861335399837;1873.9156737946826;1881.5672395349147;1886.7075603281514;1907.0845993922856;96273.07453850954;111218;120768;120901;128858.3557082218;11315.264507806965;11413.942930285295;11540.076793856471;11567.363794991355;11685.941570292094;14244.857551424353;14372.065815722153;14385.712285754227;14392.496262630968;14397.568072957829;8389.664413423394;8544.18830211129;8576.742697719079;8639.654413823502;8695.521746303888;6905.447564194852;8486.108958559262;8533.189347012023;8573.499637563526;8625.568721563897;4003.5195776507007;4268.612700872167;4416.911661766747;4545.886352841139;4551.203539380595;1566.7649852522213;1766.3464518128376;1769.9115044247778;1822.5170329862597;1867.8972656504022;885.8981217159981;954.9474778887228;1011.206203130539;1014.1582486536157;1046.3460337289193;20323.79676203219;20504.69242961357;20528.40752868242;20600.58798824045;20621.175152994012;951.8619800128943;965.2712202287228;978.4569563891966;992.1715367668078;1016.3901659004686;10633.468326583794;10857.022867941765;10886.72783180411;10958.958898904591;10985.066269367033;630251;635064;639941.6005839976;652524;652654;108870.82258354947;122449;124725;129014.99999999812;130708;934.7289286107089;942.844430668932;954.4798085043668;955.7801705607749;983.6065573770549;16559.426854448808;18118.72821907646;18408.447746567625;18426;18855.96292203942;51442;53565.73217134045;54739.72630136824;55240.723796380764;55267.894642107734;70148.94776578253;72025;74835.25164748283;75653.62173189099;76488.61755691409;7827.060752709711;7942.483738556975;7953.562554059582;8251.092379838334;8252.958735206286;8946.731598052069;11636.592719254786;12238.87761122413;12408;12433.937830311152;10990.945045274722;11017.853508907003;11043.889561104286;11068.225224234226;11167.609133680282;10583.682489525327;10736.624218152327;10834.512446939749;10866.945665271624;10917.836232456679;9225.308101892251;11147;11188.552457901918;11316.924892135234;11433.371164586028;6595.835104122435;8013.3589312856;8020.318272946864;8023.759287221393;8134.918650813416;8246.422750407653;9497.47763872966;10592.788144236918;11300.717482063013;11378.374189419661 03:05:17 INFO - PROCESS | 1665 | |3;mootools.html;1550.2248875562223;1588.205897051474;1718.810930797612;1814.7277908313752;1952.1313015708015;8624.310055195585;8907.554622268894;9075.092490750922;9095.408798428105;9106.40808347457;7842.176571460001;8079.111297757242;8480.363972702042;8774.868376974351;8813.074627164151;2308.226744040748;2374.76252374762;2376.8455050421753;2378.54807586558;2378.6550950112223;2330.941726456835;2345.636426353918;2367.4673198530363;2374.6227188230873;2382.0233704181287;2073.917043318273;2155.5365596396773;2276.6243569810963;2351.6354964980455;2357.634566642173;2133.328001679466;2301.7698230177016;2324.4421338878715;2348.8825558721996;2352.929412117639;2265.456290490287;2284.3261237934685;2289.5077558324874;2290.404494831339;2291.713535808024;8813.515256660881;8880.111988801133;8884.777880552972;8890.688825891128;8900.8664870027;5202.635815492917;5320.574354051667;5373.328333958255;5373.623846330758;5392.487713667196;3575.66031227033;3586.2110335726097;3650.8904732858055;3671.834767435472;3674.0447483654175;1077.617445806743;1083.2904447586764;1085.0505807418508;1106.7841770854686;1107.241539545414;11282.492287846984;11487.885121148849;11488.425578721113;11493.655190344387;11506.424678766029;4551.8634440966825;4610.907781844345;4767.570918617341;4772.141014617402;4778.211595086772;9959.601615935282;9986;9996.050375214343;10013;10021;5330.093884039723;5331.120365139708;5334.386545547234;5339.786408543692;5355.116405793078;4746.810127594927;4883.8290659826735;4891.437484689306;4891.510848915151;4941.110600092018;3700.5929347771726;3715.201231735136;3739.719521035933;3772.5284339457567;3796.715246356479;1108;1127.8872112788656;1168.0480408467242;1169.7718944805852;1184.6564496295978;790.7548659915444;816.420341557489;884.8318819424288;897.6319708919311;988.5600907596195;5913.46778789911;6007.8197654070445;6042.969785151046;6120.816375508742;6216.4405203531;926.5089502563652;942.9858552121724;943.3773709351932;974.546835721393;1011.8988101189822;3270.378628060661;3834.1564855731694;4048.400881651731;4063.6545893599377;4080.5511393746665;10575.894241057491;10930.672079837617;10945.507452164671;10963;10994.835077473845;9683.9031609683;10295.94852025735;10731.034206921258;10733.248672592843;10898.346099234072;8138.348932085419;8318.292945099613;8354.164583541718;8365.958170209231;8367.874481882776;5994.760209591742;6071.635701857903;6094.54290928191;6129.509639228941;6140.263168419819;10090.798184036132;10122.443265620166;10123.544440500342;10131.341462804894;10149.79700405973;9606.231501480004;10024.348417352849;10045.296829222176;10129.290949633454;10211;7494.925050749641;7664.271894170045;7670.348020418327;7676.53940763556;7683.308502234826;8178.918210818054;8266.214709602578;8341.416100873123;8347.833043338978;8389.203025712548;6276.7653703677715;7346.5592064476305;7526.736564220226;7533.7363192288;7572.697092116474;7508.662110205163;7546.471746977659;7560.886586701314;7659.463837531319;7697.692092316469;7117.181524124792;7149.356557909813;7176.533525320838;7196.208417074013;7208.35124838767;4267.80794864238;5037.319961805109;5054.949450505448;5079.11115554769;5172.793088276578;5128.564072053917;5131.179011358165;5265.947340526699;5278.551323137576;5304.045271851104 03:05:17 INFO - PROCESS | 1665 | |4;prototype.html;258.07220066106186;299.8515734711318;300.25686426095416;317.3747716997514;317.8490217146854;5478.342598888134;5508.7521061552225;5510.201020851984;5520.696361700105;5548.250986116873;4523.697700678498;4583.335416364625;5087.186050231963;5108.5146911043485;5481.917771233425;3979.7413168143976;4129.194807012634;4165.83336666533;4168.478940132483;4183.372494125882;3446.0867870014467;4026.6979976501734;4178.247915375231;4200.789960501978;4206.495220573535;3214.2285851395613;3920.627540383659;4149.294619914606;4158.480189976253;4220.493540775111;4072.6741860651077;4098.8565400211;4102.405151253051;4108.157827645356;4119.237940980898;3526.390471051429;3539.2213712983103;3573.802776069994;3581.4269716845442;3584.8924532264073;4358.433403657505;5067.467915868854;5241.161414173714;5285.524302812765;5296.311479507639;4658.906821863544;4684.547790185019;4686.859394218179;4707.317438971364;4725.621950243973;5684.573656975744;5712.714364281769;5721.256236689245;5722.170285308607;5727.284089488814;3779.5842457329672;3880.3791393376923;3885.417187421909;3899.883003509871;3909.1790723948006;5234.7644356004075;5574.5506168396105;5676.1902858085095;5843.860447212795;5946.286445626552;3902.434147048662;3904.2972264992004;3915.667168290727;3917.334053210961;3927.7839718815167;3513.718902487795;4007.438958545806;4029.4157347185082;4037.333839916381;4049.2913740095955;3881.4177873319227;4123.3196522574035;4322.5677432256525;4656.533192044563;4668.253079507331;4902.7988142905215;5367.677939323653;5683.403242659544;5708.71456427177;5739.311282646109;1244.8443944506937;1327.2700014991715;1348.5886804524714;1369.2268512276626;1377.6624726941964;807.6446363600114;837.5937670229933;873.9650413983369;891.7057371067532;894.1773568317163;4021.819018144132;4161.52265945591;4411.470623525198;4455.685572756045;4497;4333.349997500337;4336.330512752613;4525.438723640339;4925.5074492551175;4926.802927882844;3755.4742336072973;3893.4549163117185;4232.7354865147;4470.955290447054;4498.910021799546;2286.4855407533505;2502.512010158002;2607.8435293882426;2612.6472926155107;2624.4253447931087;4704.317873908264;5098.872528186825;5105.463926287686;5137.326811329917;5147.819826306136;4298.935515967263;4414.344620767212;4704.506026867198;4752.382190315237;5045.4016181666475;5033.798648054037;5039.823606173766;5062.974685126477;5093.923591146136;5115;4914.91871788206;4934.753262336941;4943;4958.231474121517;4976.825811096594;3829.8468061277235;4356.172327257811;4506.639468842551;4875.805427670101;4913.434954980223;4395.912081758411;4439.733615983051;5049.520295571915;5055.898882022412;5071.340725705634;2950.5131653276967;2955.5566665000424;3404.8638054478533;3421.4183588790465;3452;4381.079973205535;4636.340477819769;4978.581104385233;5363.720418821907;5972.432618901197;4766.997860898362;5392.973035134799;5693.259876216065;5762.567807414377;5763.97118014424;5735.512481439125;5748;5770.4511725289485;5772.39389864075;5795.536357091507;5058.64589478733;5251.343582052244;5322.8935421292135;5464.726763661881;5643.463870932255;4759.428868535798;4908.901821963612;5724.998125328134;5767.673435109864;5781.190633311424;4918.286848407032;5267.341582302212;5301.231321458444;5633.915491267716;5657.773689052392 03:05:17 INFO - PROCESS | 1665 | |5;yui.html;760.9277118673728;775.6742168289318;790.960451977401;805.1586092533302;822.3215846926287;14956.177410242432;15623.453179138733;16318.7552186717;16332.510024699279;16335.591610209736;14854.925725371386;14991.47529836456;15013.174275414847;15029;15043.247837608104;703.0789665538135;733.9376153026999;885.5129678676734;891.2914233184633;914.6067191107821;921.9308551858604;922.9953850230739;928.9814203715922;929.2426672262119;930.2976252929029;978.750418643245;981.3572110267751;982.1553464020936;983.3706427886123;983.8130755156533;743.0377660929109;936.0452338614643;977.8337682593976;978.3445091788519;979.6179489998906;713.2867132867133;713.564725517434;713.5682911838315;714.1002337055296;714.7141143542573;15054.548363549111;15071.47249846261;15074.698506029818;15077;15117.5464736057;14202.289885505787;14368.784468232985;14374.92812535941;14409.78385324221;14550.781738273934;12910.612681619567;12958.416871240817;12976.935115324362;13008.609741707669;13340.933295333558;4522.412086428743;4648.047150334173;4677.7661116944355;4683.2038553446;4789.760511974388;109996.4500177502;110239;110308.9999999992;110569;110801;11213.943930280297;11426.428678566039;11531.13516486267;11563.537458501734;11777.057835373322;22379.216727414787;22628.321150365515;22644.980975856128;22653.88673056657;22667.319980400614;7670.7315243966195;8068.8386232275025;8205.097439281673;8245.79385515367;8249.628766705513;4496.64026877849;4525.547445255448;4619.607333376701;4661.440627124699;4662.417197850269;3483.581970163546;3904.629060239273;4121.752694838319;4122.2579935611875;4157.272477316457;734.8383355661746;745.9477836551389;747.1669088965764;749.2582343479916;749.7675720526654;627.5795217204393;661.4113439039259;671.8723442545999;681.8227260912198;707.3916431868589;9558.378705384128;9594.856077158849;9731.65939192139;9835.311528192959;9916.900830991743;669.819148829823;671.9416918353612;687.5599616245606;692.8060143159923;700.4571457120772;4528.0691545183545;4585.816567337336;4963.59265756108;4977.726225057657;5341.252224688546;131343;156884.21557892367;162472.3752762481;165410.1729491345;165805;110480.26638135414;115178;129164.3541782285;129479.57806328937;140400;111239.21901952516;113043.43478282556;115355.8464415362;115515;117306;8990.434010633488;10047.447390393598;10134;10142.949285253822;10155.64455244063;46958.765206175114;48060.519394805604;55705.442945571645;59497.7025114889;61398;102489.46265806168;104280;104827;106283;106744.46627766812;13536.661583460493;14086.929565352108;14129.646758831112;14240;14256.287185640469;14487.637809054437;14681.779773303622;14929.850701492845;15151.318190681666;15416.691666166838;13855.752982231648;13943.581692548834;14593.416263349347;14766.33551490207;14864;12806.935965320114;13855.722885542435;14212.147271163763;14649.974501784773;14683.045602035834;12746.745065098461;12874.098813082994;12898.742025159256;12966.935165324114;13557.118787278796;11196.440177990906;11489.82765258505;11643.767124657628;11761.117916156152;12398.62804115878;9845.878492592396;9910.950445248016;10806.081483073713;11096.167787416138;11300.208985370946 03:05:17 INFO - PROCESS | 1665 | __end_tp_report 03:05:17 INFO - PROCESS | 1665 | __start_cc_report 03:05:17 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1557 03:05:17 INFO - PROCESS | 1665 | __end_cc_report 03:05:17 INFO - PROCESS | 1665 | __startTimestamp1468058717977__endTimestamp 03:05:17 INFO - PROCESS | 1665 | 03:05:17 INFO - PROCESS | 1665 | ------- Summary: start ------- 03:05:17 INFO - PROCESS | 1665 | Number of tests: 6 03:05:17 INFO - PROCESS | 1665 | 03:05:17 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5757.82 Median:6010.00 stddev:685.12 (11.4%) stddev-sans-first:642.59 03:05:17 INFO - PROCESS | 1665 | Values: 2656.9 3375.1 3380.6 3420.7 3439.3 5588.1 5636.6 5732.2 5740.5 5770.3 5676.2 5730.7 5732.8 5732.9 5755.1 4328.5 5179.0 5208.1 5214.5 5284.8 5230.8 5313.5 5321.5 5322.3 5328.0 5146.8 5183.5 5184.6 5200.6 5322.2 5284.4 5320.2 5337.1 5344.2 5386.6 5126.5 5129.5 5131.0 5251.5 5252.8 5600.9 5614.2 5704.6 5719.2 5741.1 6240.7 6244.2 6247.3 6252.7 6264.6 6094.1 6178.3 6241.1 6290.4 6297.4 5909.7 5915.9 5926.4 5933.5 5946.6 6399.5 6407.3 6409.9 6415.3 6423.4 6006.3 6081.9 6085.6 6128.0 6192.7 6183.1 6196.2 6267.3 6271.5 6275.2 6192.4 6236.8 6240.3 6250.9 6265.2 6116.5 6186.6 6208.9 6288.4 6295.5 4961.0 4962.8 4967.2 4973.1 4974.4 3791.3 4455.6 4502.1 4503.3 4507.8 5688.3 5800.6 5821.3 5833.6 5905.2 6261.9 6273.6 6310.8 6332.1 6337.5 5779.5 5780.5 5790.0 5808.8 5834.6 4899.2 4987.0 4998.8 5433.6 5465.3 6342.0 6349.2 6377.3 6378.2 6385.8 6307.3 6338.6 6340.7 6355.0 6368.6 6253.7 6281.9 6310.9 6334.3 6426.6 6238.2 6241.9 6243.7 6257.8 6283.9 6143.9 6307.0 6327.9 6336.5 6346.3 4990.5 5350.4 6025.3 6073.0 6122.6 6105.1 6117.0 6165.0 6170.5 6187.8 6031.8 6044.1 6061.5 6090.9 6091.1 5965.2 6026.5 6151.5 6196.1 6206.7 5930.4 5997.7 6005.1 6010.0 6018.1 03:05:17 INFO - PROCESS | 1665 | 03:05:17 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:69778.26 Median:21392.46 stddev:153101.06 (715.7%) stddev-sans-first:153442.13 03:05:17 INFO - PROCESS | 1665 | Values: 474.0 547.0 550.5 551.5 570.2 30500.2 31832.5 41394.0 42725.6 42829.0 44336.8 44625.6 44689.8 44741.0 44806.6 9799.9 9962.7 9986.8 9994.4 9999.4 8873.7 9059.0 9064.7 9216.0 9231.9 11945.6 12123.7 12125.6 12388.9 12451.0 12115.8 12217.6 12225.9 12231.0 12243.1 8742.0 9936.8 12070.5 12127.0 12206.0 42253.6 42495.0 42570.6 42597.4 42617.8 3479.4 3502.2 3655.7 3656.9 3675.9 31680.5 31897.7 31918.8 31990.5 32084.7 2724.3 3152.8 3181.3 3230.8 3298.9 415352.0 493016.0 496062.0 496710.5 497981.0 54473.7 55673.0 55765.6 63809.0 63904.0 66703.7 66773.0 67166.7 72004.6 72106.9 16912.5 16912.7 17046.6 17059.6 17078.7 12653.1 12701.9 12705.1 12705.2 12721.1 17129.1 17451.9 17458.9 17858.0 19649.3 6696.0 7542.8 8514.4 8561.3 8567.2 895.0 975.2 1085.8 1140.7 1158.6 31067.4 31523.0 31646.8 32087.8 32294.2 1094.1 1131.5 1190.7 1213.8 1277.3 15073.8 16110.2 16236.4 16312.4 16661.8 722635.9 728890.0 730552.0 731410.0 731480.0 42856.8 44240.3 53012.7 53747.0 53862.0 1183.0 1301.8 1331.2 1364.9 1420.9 28795.1 32095.2 32369.0 32480.5 32668.5 50922.7 51238.7 51610.7 51715.0 51716.7 465307.0 467817.0 467992.0 468825.0 469737.0 23801.2 24091.5 24149.4 27672.3 28291.7 47514.3 48946.3 49203.3 49483.0 50501.7 30040.7 40098.8 40500.2 41287.9 41552.0 31629.7 33020.3 40726.0 41385.8 42452.0 39109.0 39595.6 39979.0 40204.6 40216.6 28530.0 34096.0 35090.1 35362.8 35369.0 21146.0 21769.7 26171.2 26679.7 27059.1 03:05:17 INFO - PROCESS | 1665 | 03:05:17 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:35399.42 Median:2358.81 stddev:106705.39 (4523.7%) stddev-sans-first:106972.19 03:05:17 INFO - PROCESS | 1665 | Values: 303.4 352.0 382.9 389.7 391.9 15817.9 16606.6 17177.1 20457.4 20493.4 16386.5 20166.4 20732.4 21143.8 21203.0 5175.8 5732.3 5867.1 5990.3 5993.0 3829.0 4017.3 4573.0 4971.6 5885.1 5847.4 5929.7 5930.4 5933.2 5940.8 5815.0 6550.9 6558.9 6563.3 6563.8 3724.6 5469.6 5724.1 5740.3 5745.3 13171.1 13186.9 13344.2 13420.4 13432.9 2065.8 2225.4 2301.8 2415.8 2982.1 9943.0 10023.5 10297.1 10510.8 10517.1 1848.9 1873.9 1881.6 1886.7 1907.1 96273.1 111218.0 120768.0 120901.0 128858.4 11315.3 11413.9 11540.1 11567.4 11685.9 14244.9 14372.1 14385.7 14392.5 14397.6 8389.7 8544.2 8576.7 8639.7 8695.5 6905.4 8486.1 8533.2 8573.5 8625.6 4003.5 4268.6 4416.9 4545.9 4551.2 1566.8 1766.3 1769.9 1822.5 1867.9 885.9 954.9 1011.2 1014.2 1046.3 20323.8 20504.7 20528.4 20600.6 20621.2 951.9 965.3 978.5 992.2 1016.4 10633.5 10857.0 10886.7 10959.0 10985.1 630251.0 635064.0 639941.6 652524.0 652654.0 108870.8 122449.0 124725.0 129015.0 130708.0 934.7 942.8 954.5 955.8 983.6 16559.4 18118.7 18408.4 18426.0 18856.0 51442.0 53565.7 54739.7 55240.7 55267.9 70148.9 72025.0 74835.3 75653.6 76488.6 7827.1 7942.5 7953.6 8251.1 8253.0 8946.7 11636.6 12238.9 12408.0 12433.9 10990.9 11017.9 11043.9 11068.2 11167.6 10583.7 10736.6 10834.5 10866.9 10917.8 9225.3 11147.0 11188.6 11316.9 11433.4 6595.8 8013.4 8020.3 8023.8 8134.9 8246.4 9497.5 10592.8 11300.7 11378.4 03:05:17 INFO - PROCESS | 1665 | 03:05:17 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5737.12 Median:4916.31 stddev:3211.41 (65.3%) stddev-sans-first:3205.00 03:05:17 INFO - PROCESS | 1665 | Values: 1550.2 1588.2 1718.8 1814.7 1952.1 8624.3 8907.6 9075.1 9095.4 9106.4 7842.2 8079.1 8480.4 8774.9 8813.1 2308.2 2374.8 2376.8 2378.5 2378.7 2330.9 2345.6 2367.5 2374.6 2382.0 2073.9 2155.5 2276.6 2351.6 2357.6 2133.3 2301.8 2324.4 2348.9 2352.9 2265.5 2284.3 2289.5 2290.4 2291.7 8813.5 8880.1 8884.8 8890.7 8900.9 5202.6 5320.6 5373.3 5373.6 5392.5 3575.7 3586.2 3650.9 3671.8 3674.0 1077.6 1083.3 1085.1 1106.8 1107.2 11282.5 11487.9 11488.4 11493.7 11506.4 4551.9 4610.9 4767.6 4772.1 4778.2 9959.6 9986.0 9996.1 10013.0 10021.0 5330.1 5331.1 5334.4 5339.8 5355.1 4746.8 4883.8 4891.4 4891.5 4941.1 3700.6 3715.2 3739.7 3772.5 3796.7 1108.0 1127.9 1168.0 1169.8 1184.7 790.8 816.4 884.8 897.6 988.6 5913.5 6007.8 6043.0 6120.8 6216.4 926.5 943.0 943.4 974.5 1011.9 3270.4 3834.2 4048.4 4063.7 4080.6 10575.9 10930.7 10945.5 10963.0 10994.8 9683.9 10295.9 10731.0 10733.2 10898.3 8138.3 8318.3 8354.2 8366.0 8367.9 5994.8 6071.6 6094.5 6129.5 6140.3 10090.8 10122.4 10123.5 10131.3 10149.8 9606.2 10024.3 10045.3 10129.3 10211.0 7494.9 7664.3 7670.3 7676.5 7683.3 8178.9 8266.2 8341.4 8347.8 8389.2 6276.8 7346.6 7526.7 7533.7 7572.7 7508.7 7546.5 7560.9 7659.5 7697.7 7117.2 7149.4 7176.5 7196.2 7208.4 4267.8 5037.3 5054.9 5079.1 5172.8 5128.6 5131.2 5265.9 5278.6 5304.0 03:05:17 INFO - PROCESS | 1665 | 03:05:17 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:4321.55 Median:4676.40 stddev:1314.19 (28.1%) stddev-sans-first:1282.00 03:05:17 INFO - PROCESS | 1665 | Values: 258.1 299.9 300.3 317.4 317.8 5478.3 5508.8 5510.2 5520.7 5548.3 4523.7 4583.3 5087.2 5108.5 5481.9 3979.7 4129.2 4165.8 4168.5 4183.4 3446.1 4026.7 4178.2 4200.8 4206.5 3214.2 3920.6 4149.3 4158.5 4220.5 4072.7 4098.9 4102.4 4108.2 4119.2 3526.4 3539.2 3573.8 3581.4 3584.9 4358.4 5067.5 5241.2 5285.5 5296.3 4658.9 4684.5 4686.9 4707.3 4725.6 5684.6 5712.7 5721.3 5722.2 5727.3 3779.6 3880.4 3885.4 3899.9 3909.2 5234.8 5574.6 5676.2 5843.9 5946.3 3902.4 3904.3 3915.7 3917.3 3927.8 3513.7 4007.4 4029.4 4037.3 4049.3 3881.4 4123.3 4322.6 4656.5 4668.3 4902.8 5367.7 5683.4 5708.7 5739.3 1244.8 1327.3 1348.6 1369.2 1377.7 807.6 837.6 874.0 891.7 894.2 4021.8 4161.5 4411.5 4455.7 4497.0 4333.3 4336.3 4525.4 4925.5 4926.8 3755.5 3893.5 4232.7 4471.0 4498.9 2286.5 2502.5 2607.8 2612.6 2624.4 4704.3 5098.9 5105.5 5137.3 5147.8 4298.9 4414.3 4704.5 4752.4 5045.4 5033.8 5039.8 5063.0 5093.9 5115.0 4914.9 4934.8 4943.0 4958.2 4976.8 3829.8 4356.2 4506.6 4875.8 4913.4 4395.9 4439.7 5049.5 5055.9 5071.3 2950.5 2955.6 3404.9 3421.4 3452.0 4381.1 4636.3 4978.6 5363.7 5972.4 4767.0 5393.0 5693.3 5762.6 5764.0 5735.5 5748.0 5770.5 5772.4 5795.5 5058.6 5251.3 5322.9 5464.7 5643.5 4759.4 4908.9 5725.0 5767.7 5781.2 4918.3 5267.3 5301.2 5633.9 5657.8 03:05:17 INFO - PROCESS | 1665 | 03:05:17 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:25520.49 Median:165607.59 stddev:40772.39 (24.6%) stddev-sans-first:40844.39 03:05:18 INFO - PROCESS | 1665 | Values: 760.9 775.7 791.0 805.2 822.3 14956.2 15623.5 16318.8 16332.5 16335.6 14854.9 14991.5 15013.2 15029.0 15043.2 703.1 733.9 885.5 891.3 914.6 921.9 923.0 929.0 929.2 930.3 978.8 981.4 982.2 983.4 983.8 743.0 936.0 977.8 978.3 979.6 713.3 713.6 713.6 714.1 714.7 15054.5 15071.5 15074.7 15077.0 15117.5 14202.3 14368.8 14374.9 14409.8 14550.8 12910.6 12958.4 12976.9 13008.6 13340.9 4522.4 4648.0 4677.8 4683.2 4789.8 109996.5 110239.0 110309.0 110569.0 110801.0 11213.9 11426.4 11531.1 11563.5 11777.1 22379.2 22628.3 22645.0 22653.9 22667.3 7670.7 8068.8 8205.1 8245.8 8249.6 4496.6 4525.5 4619.6 4661.4 4662.4 3483.6 3904.6 4121.8 4122.3 4157.3 734.8 745.9 747.2 749.3 749.8 627.6 661.4 671.9 681.8 707.4 9558.4 9594.9 9731.7 9835.3 9916.9 669.8 671.9 687.6 692.8 700.5 4528.1 4585.8 4963.6 4977.7 5341.3 131343.0 156884.2 162472.4 165410.2 165805.0 110480.3 115178.0 129164.4 129479.6 140400.0 111239.2 113043.4 115355.8 115515.0 117306.0 8990.4 10047.4 10134.0 10142.9 10155.6 46958.8 48060.5 55705.4 59497.7 61398.0 102489.5 104280.0 104827.0 106283.0 106744.5 13536.7 14086.9 14129.6 14240.0 14256.3 14487.6 14681.8 14929.9 15151.3 15416.7 13855.8 13943.6 14593.4 14766.3 14864.0 12806.9 13855.7 14212.1 14650.0 14683.0 12746.7 12874.1 12898.7 12966.9 13557.1 11196.4 11489.8 11643.8 11761.1 12398.6 9845.9 9911.0 10806.1 11096.2 11300.2 03:05:18 INFO - PROCESS | 1665 | -------- Summary: end -------- 03:05:18 INFO - PROCESS | 1665 | 03:05:18 INFO - TEST-INFO | 1665: exit 0 03:05:18 INFO - TEST-OK | dromaeo_css | took 1117030ms 03:05:18 INFO - TEST-START | kraken 03:05:18 INFO - operating with platform_type : mac_ 03:05:18 INFO - Initialising browser for kraken test... 03:05:18 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpNXtkJo/profile http://localhost:49271/getInfo.html) 03:05:20 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 03:05:20 INFO - PROCESS | 1671 | colorDepth:24 03:05:20 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 03:05:20 INFO - PROCESS | 1671 | __metrics 03:05:20 INFO - PROCESS | 1671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:05:20 INFO - PROCESS | 1671 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:05:21 INFO - TEST-INFO | 1671: exit 0 03:05:21 INFO - Browser initialized. 03:05:21 INFO - Running cycle 1/1 for kraken test... 03:05:21 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpNXtkJo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:05:33 INFO - PROCESS | 1672 | RSS: Main: 243851264 03:05:33 INFO - PROCESS | 1672 | 03:05:36 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 03:05:37 INFO - PROCESS | 1672 | RSS: Main: 294461440 03:05:37 INFO - PROCESS | 1672 | 03:05:39 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 03:05:40 INFO - PROCESS | 1672 | RSS: Main: 304685056 03:05:40 INFO - PROCESS | 1672 | 03:05:43 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 03:05:44 INFO - PROCESS | 1672 | RSS: Main: 387194880 03:05:44 INFO - PROCESS | 1672 | 03:05:46 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 03:05:47 INFO - PROCESS | 1672 | RSS: Main: 290164736 03:05:47 INFO - PROCESS | 1672 | 03:05:49 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:05:49 INFO - PROCESS | 1672 | RSS: Main: 293945344 03:05:49 INFO - PROCESS | 1672 | 03:05:52 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 03:05:53 INFO - PROCESS | 1672 | RSS: Main: 331603968 03:05:53 INFO - PROCESS | 1672 | 03:05:55 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 03:05:56 INFO - PROCESS | 1672 | RSS: Main: 333541376 03:05:56 INFO - PROCESS | 1672 | 03:05:59 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 03:06:00 INFO - PROCESS | 1672 | RSS: Main: 331587584 03:06:00 INFO - PROCESS | 1672 | 03:06:01 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:06:02 INFO - PROCESS | 1672 | RSS: Main: 311406592 03:06:02 INFO - PROCESS | 1672 | 03:06:04 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:06:05 INFO - PROCESS | 1672 | RSS: Main: 317906944 03:06:05 INFO - PROCESS | 1672 | 03:06:07 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:06:07 INFO - PROCESS | 1672 | RSS: Main: 320307200 03:06:07 INFO - PROCESS | 1672 | 03:06:10 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:06:11 INFO - PROCESS | 1672 | RSS: Main: 325107712 03:06:11 INFO - PROCESS | 1672 | 03:06:14 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:06:14 INFO - PROCESS | 1672 | RSS: Main: 326627328 03:06:14 INFO - PROCESS | 1672 | 03:06:16 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 03:06:16 INFO - PROCESS | 1672 | RSS: Main: 370208768 03:06:16 INFO - PROCESS | 1672 | 03:06:16 INFO - PROCESS | 1672 | __start_tp_report 03:06:16 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 03:06:16 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 03:06:16 INFO - PROCESS | 1672 | |i|pagename|runs| 03:06:16 INFO - PROCESS | 1672 | |0;ai-astar;101;112;112;112;112;110;112;112;111;112 03:06:16 INFO - PROCESS | 1672 | |1;audio-beat-detection;155;130;129;171;138;128;130;107;138;106 03:06:16 INFO - PROCESS | 1672 | |2;audio-dft;183;182;182;183;183;184;178;182;180;183 03:06:16 INFO - PROCESS | 1672 | |3;audio-fft;77;74;57;106;56;57;60;64;95;57 03:06:16 INFO - PROCESS | 1672 | |4;audio-oscillator;86;89;88;69;70;68;96;69;66;101 03:06:16 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;90;87;90;87;91;86;90;87;109;130 03:06:16 INFO - PROCESS | 1672 | |6;imaging-darkroom;96;95;97;97;97;96;96;96;95;95 03:06:16 INFO - PROCESS | 1672 | |7;imaging-desaturate;101;134;133;85;85;83;84;82;105;143 03:06:16 INFO - PROCESS | 1672 | |8;json-parse-financial;55;56;54;55;53;55;53;54;54;54 03:06:16 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;64;74;73;76;75;94;100;99;77;71 03:06:16 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;83;82;85;85;84;80;85;83;85;85 03:06:16 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;138;136;136;134;131;137;134;135;176;134 03:06:16 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;180;164;164;169;164;164;165;165;163;164 03:06:16 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;66;69;77;87;85;84;69;67;65;64 03:06:16 INFO - PROCESS | 1672 | __end_tp_report 03:06:16 INFO - PROCESS | 1672 | __start_cc_report 03:06:16 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,2005 03:06:16 INFO - PROCESS | 1672 | __end_cc_report 03:06:16 INFO - PROCESS | 1672 | __startTimestamp1468058776840__endTimestamp 03:06:16 INFO - PROCESS | 1672 | 03:06:16 INFO - PROCESS | 1672 | ------- Summary: start ------- 03:06:16 INFO - PROCESS | 1672 | Number of tests: 14 03:06:16 INFO - PROCESS | 1672 | 03:06:16 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:110.60 Median:112.00 stddev:3.44 (3.1%) stddev-sans-first:0.71 03:06:16 INFO - PROCESS | 1672 | Values: 101.0 112.0 112.0 112.0 112.0 110.0 112.0 112.0 111.0 112.0 03:06:16 INFO - PROCESS | 1672 | 03:06:16 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:133.20 Median:134.00 stddev:19.56 (14.6%) stddev-sans-first:19.08 03:06:16 INFO - PROCESS | 1672 | Values: 155.0 130.0 129.0 171.0 138.0 128.0 130.0 107.0 138.0 106.0 03:06:16 INFO - PROCESS | 1672 | 03:06:16 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:182.00 Median:183.00 stddev:1.76 (1.0%) stddev-sans-first:1.83 03:06:16 INFO - PROCESS | 1672 | Values: 183.0 182.0 182.0 183.0 183.0 184.0 178.0 182.0 180.0 183.0 03:06:16 INFO - PROCESS | 1672 | 03:06:16 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:70.30 Median:62.00 stddev:17.71 (28.6%) stddev-sans-first:18.62 03:06:16 INFO - PROCESS | 1672 | Values: 77.0 74.0 57.0 106.0 56.0 57.0 60.0 64.0 95.0 57.0 03:06:16 INFO - PROCESS | 1672 | 03:06:16 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:80.20 Median:78.00 stddev:13.16 (16.9%) stddev-sans-first:13.79 03:06:16 INFO - PROCESS | 1672 | Values: 86.0 89.0 88.0 69.0 70.0 68.0 96.0 69.0 66.0 101.0 03:06:16 INFO - PROCESS | 1672 | 03:06:16 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:94.70 Median:88.50 stddev:14.08 (15.9%) stddev-sans-first:14.83 03:06:16 INFO - PROCESS | 1672 | Values: 90.0 87.0 90.0 87.0 91.0 86.0 90.0 87.0 109.0 130.0 03:06:16 INFO - PROCESS | 1672 | 03:06:16 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:96.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 03:06:16 INFO - PROCESS | 1672 | Values: 96.0 95.0 97.0 97.0 97.0 96.0 96.0 96.0 95.0 95.0 03:06:16 INFO - PROCESS | 1672 | 03:06:16 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:103.50 Median:82.50 stddev:24.30 (29.5%) stddev-sans-first:25.76 03:06:16 INFO - PROCESS | 1672 | Values: 101.0 134.0 133.0 85.0 85.0 83.0 84.0 82.0 105.0 143.0 03:06:16 INFO - PROCESS | 1672 | 03:06:16 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.50 stddev:0.95 (1.7%) stddev-sans-first:0.97 03:06:16 INFO - PROCESS | 1672 | Values: 55.0 56.0 54.0 55.0 53.0 55.0 53.0 54.0 54.0 54.0 03:06:16 INFO - PROCESS | 1672 | 03:06:16 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:80.30 Median:75.50 stddev:12.60 (16.7%) stddev-sans-first:11.90 03:06:16 INFO - PROCESS | 1672 | Values: 64.0 74.0 73.0 76.0 75.0 94.0 100.0 99.0 77.0 71.0 03:06:16 INFO - PROCESS | 1672 | 03:06:16 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:85.00 stddev:1.70 (2.0%) stddev-sans-first:1.79 03:06:16 INFO - PROCESS | 1672 | Values: 83.0 82.0 85.0 85.0 84.0 80.0 85.0 83.0 85.0 85.0 03:06:16 INFO - PROCESS | 1672 | 03:06:16 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:139.10 Median:136.00 stddev:13.11 (9.6%) stddev-sans-first:13.90 03:06:16 INFO - PROCESS | 1672 | Values: 138.0 136.0 136.0 134.0 131.0 137.0 134.0 135.0 176.0 134.0 03:06:16 INFO - PROCESS | 1672 | 03:06:16 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.20 Median:164.50 stddev:5.12 (3.1%) stddev-sans-first:1.73 03:06:16 INFO - PROCESS | 1672 | Values: 180.0 164.0 164.0 169.0 164.0 164.0 165.0 165.0 163.0 164.0 03:06:16 INFO - PROCESS | 1672 | 03:06:16 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:73.00 stddev:9.06 (12.4%) stddev-sans-first:9.21 03:06:16 INFO - PROCESS | 1672 | Values: 66.0 69.0 77.0 87.0 85.0 84.0 69.0 67.0 65.0 64.0 03:06:16 INFO - PROCESS | 1672 | -------- Summary: end -------- 03:06:16 INFO - PROCESS | 1672 | 03:06:17 INFO - TEST-INFO | 1672: exit 0 03:06:17 INFO - TEST-OK | kraken | took 59077ms 03:06:17 INFO - SUITE-END | took 1176s 03:06:18 INFO - Completed test suite (00:19:36) 03:06:18 INFO - javascript benchmark 03:06:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2656.9070082547114, 3375.1055970167895, 3380.5943286805577, 3420.657934206578, 3439.2605589798204, 5588.133839254911, 5636.577256705748, 5732.2261494698205, 5740.5120564752, 5770.278715160605, 5676.1769543416285, 5730.713464326777, 5732.770689172438, 5732.856678583027, 5755.079187330028, 4328.458942632171, 5179.015986962475, 5208.0885844977165, 5214.452482489341, 5284.841454756363, 5230.816921407752, 5313.468653134694, 5321.467853214686, 5322.281491998569, 5328.0, 5146.845594632148, 5183.50756678115, 5184.637075404723, 5200.635955483118, 5322.228276899867, 5284.4187139414635, 5320.17537281722, 5337.11937530307, 5344.198370244456, 5386.596005299618, 5126.512981266774, 5129.461406552296, 5131.025105229995, 5251.527362537352, 5252.789888404459, 5600.915986260209, 5614.1859430382365, 5704.629199102045, 5719.19931209631, 5741.052726300156, 6240.687965601702, 6244.1570387997945, 6247.344028876993, 6252.718627661766, 6264.624122552661, 6094.085887116924, 6178.289496707891, 6241.095041219043, 6290.433860952537, 6297.433231009231, 5909.704514774244, 5915.881682366328, 5926.436988486087, 5933.525317974638, 5946.583739138262, 6399.488040956712, 6407.327230640715, 6409.903851442232, 6415.326390728999, 6423.389777971085, 6006.309274433496, 6081.908771368433, 6085.6348619083, 6128.019516877278, 6192.721327540181, 6183.134361189437, 6196.194494715748, 6267.310595834455, 6271.498280137669, 6275.152854364694, 6192.411720886508, 6236.844078897973, 6240.282367527792, 6250.937490625127, 6265.248170219603, 6116.51838140169, 6186.628802271878, 6208.906866397007, 6288.371162883766, 6295.496360291166, 4960.950390496121, 4962.826301079445, 4967.155583550805, 4973.1297023020825, 4974.37820272466, 3791.260704162662, 4455.554444555519, 4502.144592527475, 4503.301988191835, 4507.797149128297, 5688.317401911797, 5800.59395842287, 5821.27234095738, 5833.562482813721, 5905.202797622352, 6261.937380626136, 6273.592216506004, 6310.779122730774, 6332.050192471166, 6337.493000559944, 5779.450952159538, 5780.508656764222, 5790.0, 5808.796692115756, 5834.562407819346, 4899.167141585939, 4986.975065124651, 4998.775055122455, 5433.646812957145, 5465.262189604432, 6341.9740777667, 6349.238091428966, 6377.3303803100935, 6378.202724659418, 6385.808425747235, 6307.3377295383325, 6338.58799178052, 6340.7463701451425, 6355.046742988588, 6368.64972426521, 6253.7185826637, 6281.937180628318, 6310.905336420049, 6334.271558770801, 6426.646534440651, 6238.157848690369, 6241.875162496815, 6243.656598887105, 6257.812265632038, 6283.874322513433, 6143.941779503916, 6307.0, 6327.92425287693, 6336.461400780985, 6346.270178929298, 4990.476000019946, 5350.384705758887, 6025.337212906665, 6073.0, 6122.602030867979, 6105.065535583811, 6116.974953844038, 6165.0444181152, 6170.5372097092, 6187.81436556904, 6031.7587296507645, 6044.123602077586, 6061.454469097626, 6090.908636370546, 6091.116788065794, 5965.194698715618, 6026.517878569616, 6151.537138824579, 6196.132541444112, 6206.658633775186, 5930.377310382521, 5997.670128142994, 6005.099235114593, 6010.0, 6018.097285407224], "value": 5710.974085675198, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.9905201895962, 547.0, 550.5347980956093, 551.5008916930178, 570.1874828369572, 30500.237494062658, 31832.522512162308, 41393.99999999996, 42725.57274427255, 42829.0, 44336.77831610837, 44625.553744462544, 44689.776551117284, 44741.0, 44806.551934480645, 9799.853002204973, 9962.701118966443, 9986.750331241705, 9994.350367226107, 9999.400035997864, 8873.68942087027, 9059.003509613936, 9064.728058158233, 9215.95392023039, 9231.861522077174, 11945.581904633296, 12123.696907577292, 12125.575604853788, 12388.93805530971, 12451.0, 12115.818262726067, 12217.572384966483, 12225.938870305681, 12231.021518278516, 12243.142979991404, 8742.038375778659, 9936.80126397468, 12070.456829442695, 12127.0, 12206.023518118529, 42253.57746422558, 42495.0, 42570.57429425697, 42597.36103958443, 42617.78691106556, 3479.425894727367, 3502.2295095079044, 3655.744097913147, 3656.926861462756, 3675.9264814703824, 31680.524792128137, 31897.681023189703, 31918.840405797822, 31990.520142197885, 32084.679153208403, 2724.346156922344, 3152.8265945372996, 3181.3319202967173, 3230.842664227059, 3298.901032969015, 415352.0, 493016.0, 496062.0, 496710.51644741907, 497980.9999999964, 54473.72763136159, 55673.0, 55765.605859853014, 63809.0, 63904.0, 66703.66648166825, 66773.0, 67166.66416667885, 72004.63997680083, 72106.9183962241, 16912.492625221264, 16912.74630880513, 17046.57383565396, 17059.573510662085, 17078.658426831393, 12653.051021173449, 12701.872981270253, 12705.110642254953, 12705.17416367933, 12721.109522333349, 17129.14354282281, 17451.91274043647, 17458.912705436647, 17858.0, 19649.31227407034, 6696.029075784081, 7542.812584350477, 8514.361422893307, 8561.272291855263, 8567.186117318844, 894.959726812295, 975.23444096384, 1085.8208395614793, 1140.6920131564598, 1158.6350299655583, 31067.378652426825, 31523.0, 31646.841765791025, 32087.839560802047, 32294.19264518359, 1094.1137678480457, 1131.4851742457163, 1190.732085280805, 1213.7875871722413, 1277.2528071078523, 15073.773893391608, 16110.194490275439, 16236.431724889571, 16312.429064982669, 16661.750073748903, 722635.8682065876, 728890.0, 730552.0, 731410.0, 731480.0, 42856.78571607122, 44240.3363949541, 53012.73493632507, 53747.0, 53862.0, 1182.9585964491203, 1301.7721898667696, 1331.2411925202734, 1364.8566900475307, 1420.9413986580064, 28795.136145915658, 32095.197620059684, 32369.028929132164, 32480.512792308607, 32668.50997235091, 50922.74538627283, 51238.74380628073, 51610.709732257, 51715.0, 51716.74141629268, 465307.0, 467817.0, 467992.0, 468825.0, 469737.0, 23801.166959156348, 24091.518169636158, 24149.396265093514, 27672.308192295353, 28291.71708282891, 47514.27544623242, 48946.26580601223, 49203.261951070046, 49483.0, 50501.74749126231, 30040.699593003792, 40098.79703608897, 40500.1899962005, 41287.87690348376, 41552.0, 31629.683703162675, 33020.33959320752, 40726.0, 41385.79307103445, 42452.0, 39109.0, 39595.60404395919, 39979.0, 40204.59283925049, 40216.59783402128, 28530.00144994915, 34096.0, 35090.12773069049, 35362.823185883906, 35369.0, 21146.0, 21769.67345489787, 26171.214863554123, 26679.6660166987, 27059.052933148032], "value": 20519.034444969504, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.3689924956092, 351.9612842587315, 382.87939299120774, 389.6863025264661, 391.9353306704394, 15817.920910395445, 16606.584835379108, 17177.055261960588, 20457.386278411635, 20493.385198444033, 16386.50840474785, 20166.39500814978, 20732.378028659165, 21143.837088960103, 21203.0, 5175.767090480937, 5732.31212254529, 5867.090600956859, 5990.311114221855, 5993.0, 3828.9808550957205, 4017.2969730297227, 4572.97713511432, 4971.577415919651, 5885.087811389241, 5847.386024467433, 5929.73316200772, 5930.377310382412, 5933.22868027158, 5940.821775346746, 5815.0, 6550.901736473933, 6558.93441065588, 6563.343665633399, 6563.7702680405955, 3724.646158614927, 5469.56243500519, 5724.112762521816, 5740.282464691913, 5745.2818397700285, 13171.143875648148, 13186.868131318755, 13344.199348039148, 13420.396082176325, 13432.932835335858, 2065.7727649958492, 2225.354647152324, 2301.7813307735737, 2415.8429702069484, 2982.1053683894743, 9942.9502852486, 10023.498825058718, 10297.073263406255, 10510.842337364946, 10517.1060459861, 1848.861335399837, 1873.9156737946826, 1881.5672395349147, 1886.7075603281514, 1907.0845993922856, 96273.07453850954, 111218.0, 120768.0, 120901.0, 128858.3557082218, 11315.264507806965, 11413.942930285295, 11540.076793856471, 11567.363794991355, 11685.941570292094, 14244.857551424353, 14372.065815722153, 14385.712285754227, 14392.496262630968, 14397.568072957829, 8389.664413423394, 8544.18830211129, 8576.742697719079, 8639.654413823502, 8695.521746303888, 6905.447564194852, 8486.108958559262, 8533.189347012023, 8573.499637563526, 8625.568721563897, 4003.5195776507007, 4268.612700872167, 4416.911661766747, 4545.886352841139, 4551.203539380595, 1566.7649852522213, 1766.3464518128376, 1769.9115044247778, 1822.5170329862597, 1867.8972656504022, 885.8981217159981, 954.9474778887228, 1011.206203130539, 1014.1582486536157, 1046.3460337289193, 20323.79676203219, 20504.69242961357, 20528.40752868242, 20600.58798824045, 20621.175152994012, 951.8619800128943, 965.2712202287228, 978.4569563891966, 992.1715367668078, 1016.3901659004686, 10633.468326583794, 10857.022867941765, 10886.72783180411, 10958.958898904591, 10985.066269367033, 630251.0, 635064.0, 639941.6005839976, 652524.0, 652654.0, 108870.82258354947, 122449.0, 124725.0, 129014.99999999812, 130708.0, 934.7289286107089, 942.844430668932, 954.4798085043668, 955.7801705607749, 983.6065573770549, 16559.426854448808, 18118.72821907646, 18408.447746567625, 18426.0, 18855.96292203942, 51442.0, 53565.73217134045, 54739.72630136824, 55240.723796380764, 55267.894642107734, 70148.94776578253, 72025.0, 74835.25164748283, 75653.62173189099, 76488.61755691409, 7827.060752709711, 7942.483738556975, 7953.562554059582, 8251.092379838334, 8252.958735206286, 8946.731598052069, 11636.592719254786, 12238.87761122413, 12408.0, 12433.937830311152, 10990.945045274722, 11017.853508907003, 11043.889561104286, 11068.225224234226, 11167.609133680282, 10583.682489525327, 10736.624218152327, 10834.512446939749, 10866.945665271624, 10917.836232456679, 9225.308101892251, 11147.0, 11188.552457901918, 11316.924892135234, 11433.371164586028, 6595.835104122435, 8013.3589312856, 8020.318272946864, 8023.759287221393, 8134.918650813416, 8246.422750407653, 9497.47763872966, 10592.788144236918, 11300.717482063013, 11378.374189419661], "value": 9201.803419050533, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1550.2248875562223, 1588.205897051474, 1718.810930797612, 1814.7277908313752, 1952.1313015708015, 8624.310055195585, 8907.554622268894, 9075.092490750922, 9095.408798428105, 9106.40808347457, 7842.176571460001, 8079.111297757242, 8480.363972702042, 8774.868376974351, 8813.074627164151, 2308.226744040748, 2374.76252374762, 2376.8455050421753, 2378.54807586558, 2378.6550950112223, 2330.941726456835, 2345.636426353918, 2367.4673198530363, 2374.6227188230873, 2382.0233704181287, 2073.917043318273, 2155.5365596396773, 2276.6243569810963, 2351.6354964980455, 2357.634566642173, 2133.328001679466, 2301.7698230177016, 2324.4421338878715, 2348.8825558721996, 2352.929412117639, 2265.456290490287, 2284.3261237934685, 2289.5077558324874, 2290.404494831339, 2291.713535808024, 8813.515256660881, 8880.111988801133, 8884.777880552972, 8890.688825891128, 8900.8664870027, 5202.635815492917, 5320.574354051667, 5373.328333958255, 5373.623846330758, 5392.487713667196, 3575.66031227033, 3586.2110335726097, 3650.8904732858055, 3671.834767435472, 3674.0447483654175, 1077.617445806743, 1083.2904447586764, 1085.0505807418508, 1106.7841770854686, 1107.241539545414, 11282.492287846984, 11487.885121148849, 11488.425578721113, 11493.655190344387, 11506.424678766029, 4551.8634440966825, 4610.907781844345, 4767.570918617341, 4772.141014617402, 4778.211595086772, 9959.601615935282, 9986.0, 9996.050375214343, 10013.0, 10021.0, 5330.093884039723, 5331.120365139708, 5334.386545547234, 5339.786408543692, 5355.116405793078, 4746.810127594927, 4883.8290659826735, 4891.437484689306, 4891.510848915151, 4941.110600092018, 3700.5929347771726, 3715.201231735136, 3739.719521035933, 3772.5284339457567, 3796.715246356479, 1108.0, 1127.8872112788656, 1168.0480408467242, 1169.7718944805852, 1184.6564496295978, 790.7548659915444, 816.420341557489, 884.8318819424288, 897.6319708919311, 988.5600907596195, 5913.46778789911, 6007.8197654070445, 6042.969785151046, 6120.816375508742, 6216.4405203531, 926.5089502563652, 942.9858552121724, 943.3773709351932, 974.546835721393, 1011.8988101189822, 3270.378628060661, 3834.1564855731694, 4048.400881651731, 4063.6545893599377, 4080.5511393746665, 10575.894241057491, 10930.672079837617, 10945.507452164671, 10963.0, 10994.835077473845, 9683.9031609683, 10295.94852025735, 10731.034206921258, 10733.248672592843, 10898.346099234072, 8138.348932085419, 8318.292945099613, 8354.164583541718, 8365.958170209231, 8367.874481882776, 5994.760209591742, 6071.635701857903, 6094.54290928191, 6129.509639228941, 6140.263168419819, 10090.798184036132, 10122.443265620166, 10123.544440500342, 10131.341462804894, 10149.79700405973, 9606.231501480004, 10024.348417352849, 10045.296829222176, 10129.290949633454, 10211.0, 7494.925050749641, 7664.271894170045, 7670.348020418327, 7676.53940763556, 7683.308502234826, 8178.918210818054, 8266.214709602578, 8341.416100873123, 8347.833043338978, 8389.203025712548, 6276.7653703677715, 7346.5592064476305, 7526.736564220226, 7533.7363192288, 7572.697092116474, 7508.662110205163, 7546.471746977659, 7560.886586701314, 7659.463837531319, 7697.692092316469, 7117.181524124792, 7149.356557909813, 7176.533525320838, 7196.208417074013, 7208.35124838767, 4267.80794864238, 5037.319961805109, 5054.949450505448, 5079.11115554769, 5172.793088276578, 5128.564072053917, 5131.179011358165, 5265.947340526699, 5278.551323137576, 5304.045271851104], "value": 4598.054521970379, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [258.07220066106186, 299.8515734711318, 300.25686426095416, 317.3747716997514, 317.8490217146854, 5478.342598888134, 5508.7521061552225, 5510.201020851984, 5520.696361700105, 5548.250986116873, 4523.697700678498, 4583.335416364625, 5087.186050231963, 5108.5146911043485, 5481.917771233425, 3979.7413168143976, 4129.194807012634, 4165.83336666533, 4168.478940132483, 4183.372494125882, 3446.0867870014467, 4026.6979976501734, 4178.247915375231, 4200.789960501978, 4206.495220573535, 3214.2285851395613, 3920.627540383659, 4149.294619914606, 4158.480189976253, 4220.493540775111, 4072.6741860651077, 4098.8565400211, 4102.405151253051, 4108.157827645356, 4119.237940980898, 3526.390471051429, 3539.2213712983103, 3573.802776069994, 3581.4269716845442, 3584.8924532264073, 4358.433403657505, 5067.467915868854, 5241.161414173714, 5285.524302812765, 5296.311479507639, 4658.906821863544, 4684.547790185019, 4686.859394218179, 4707.317438971364, 4725.621950243973, 5684.573656975744, 5712.714364281769, 5721.256236689245, 5722.170285308607, 5727.284089488814, 3779.5842457329672, 3880.3791393376923, 3885.417187421909, 3899.883003509871, 3909.1790723948006, 5234.7644356004075, 5574.5506168396105, 5676.1902858085095, 5843.860447212795, 5946.286445626552, 3902.434147048662, 3904.2972264992004, 3915.667168290727, 3917.334053210961, 3927.7839718815167, 3513.718902487795, 4007.438958545806, 4029.4157347185082, 4037.333839916381, 4049.2913740095955, 3881.4177873319227, 4123.3196522574035, 4322.5677432256525, 4656.533192044563, 4668.253079507331, 4902.7988142905215, 5367.677939323653, 5683.403242659544, 5708.71456427177, 5739.311282646109, 1244.8443944506937, 1327.2700014991715, 1348.5886804524714, 1369.2268512276626, 1377.6624726941964, 807.6446363600114, 837.5937670229933, 873.9650413983369, 891.7057371067532, 894.1773568317163, 4021.819018144132, 4161.52265945591, 4411.470623525198, 4455.685572756045, 4497.0, 4333.349997500337, 4336.330512752613, 4525.438723640339, 4925.5074492551175, 4926.802927882844, 3755.4742336072973, 3893.4549163117185, 4232.7354865147, 4470.955290447054, 4498.910021799546, 2286.4855407533505, 2502.512010158002, 2607.8435293882426, 2612.6472926155107, 2624.4253447931087, 4704.317873908264, 5098.872528186825, 5105.463926287686, 5137.326811329917, 5147.819826306136, 4298.935515967263, 4414.344620767212, 4704.506026867198, 4752.382190315237, 5045.4016181666475, 5033.798648054037, 5039.823606173766, 5062.974685126477, 5093.923591146136, 5115.0, 4914.91871788206, 4934.753262336941, 4943.0, 4958.231474121517, 4976.825811096594, 3829.8468061277235, 4356.172327257811, 4506.639468842551, 4875.805427670101, 4913.434954980223, 4395.912081758411, 4439.733615983051, 5049.520295571915, 5055.898882022412, 5071.340725705634, 2950.5131653276967, 2955.5566665000424, 3404.8638054478533, 3421.4183588790465, 3452.0, 4381.079973205535, 4636.340477819769, 4978.581104385233, 5363.720418821907, 5972.432618901197, 4766.997860898362, 5392.973035134799, 5693.259876216065, 5762.567807414377, 5763.97118014424, 5735.512481439125, 5748.0, 5770.4511725289485, 5772.39389864075, 5795.536357091507, 5058.64589478733, 5251.343582052244, 5322.8935421292135, 5464.726763661881, 5643.463870932255, 4759.428868535798, 4908.901821963612, 5724.998125328134, 5767.673435109864, 5781.190633311424, 4918.286848407032, 5267.341582302212, 5301.231321458444, 5633.915491267716, 5657.773689052392], "value": 3908.1586574089665, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.9277118673728, 775.6742168289318, 790.960451977401, 805.1586092533302, 822.3215846926287, 14956.177410242432, 15623.453179138733, 16318.7552186717, 16332.510024699279, 16335.591610209736, 14854.925725371386, 14991.47529836456, 15013.174275414847, 15029.0, 15043.247837608104, 703.0789665538135, 733.9376153026999, 885.5129678676734, 891.2914233184633, 914.6067191107821, 921.9308551858604, 922.9953850230739, 928.9814203715922, 929.2426672262119, 930.2976252929029, 978.750418643245, 981.3572110267751, 982.1553464020936, 983.3706427886123, 983.8130755156533, 743.0377660929109, 936.0452338614643, 977.8337682593976, 978.3445091788519, 979.6179489998906, 713.2867132867133, 713.564725517434, 713.5682911838315, 714.1002337055296, 714.7141143542573, 15054.548363549111, 15071.47249846261, 15074.698506029818, 15077.0, 15117.5464736057, 14202.289885505787, 14368.784468232985, 14374.92812535941, 14409.78385324221, 14550.781738273934, 12910.612681619567, 12958.416871240817, 12976.935115324362, 13008.609741707669, 13340.933295333558, 4522.412086428743, 4648.047150334173, 4677.7661116944355, 4683.2038553446, 4789.760511974388, 109996.4500177502, 110239.0, 110308.9999999992, 110569.0, 110801.0, 11213.943930280297, 11426.428678566039, 11531.13516486267, 11563.537458501734, 11777.057835373322, 22379.216727414787, 22628.321150365515, 22644.980975856128, 22653.88673056657, 22667.319980400614, 7670.7315243966195, 8068.8386232275025, 8205.097439281673, 8245.79385515367, 8249.628766705513, 4496.64026877849, 4525.547445255448, 4619.607333376701, 4661.440627124699, 4662.417197850269, 3483.581970163546, 3904.629060239273, 4121.752694838319, 4122.2579935611875, 4157.272477316457, 734.8383355661746, 745.9477836551389, 747.1669088965764, 749.2582343479916, 749.7675720526654, 627.5795217204393, 661.4113439039259, 671.8723442545999, 681.8227260912198, 707.3916431868589, 9558.378705384128, 9594.856077158849, 9731.65939192139, 9835.311528192959, 9916.900830991743, 669.819148829823, 671.9416918353612, 687.5599616245606, 692.8060143159923, 700.4571457120772, 4528.0691545183545, 4585.816567337336, 4963.59265756108, 4977.726225057657, 5341.252224688546, 131343.0, 156884.21557892367, 162472.3752762481, 165410.1729491345, 165805.0, 110480.26638135414, 115178.0, 129164.3541782285, 129479.57806328937, 140400.0, 111239.21901952516, 113043.43478282556, 115355.8464415362, 115515.0, 117306.0, 8990.434010633488, 10047.447390393598, 10134.0, 10142.949285253822, 10155.64455244063, 46958.765206175114, 48060.519394805604, 55705.442945571645, 59497.7025114889, 61398.0, 102489.46265806168, 104280.0, 104827.0, 106283.0, 106744.46627766812, 13536.661583460493, 14086.929565352108, 14129.646758831112, 14240.0, 14256.287185640469, 14487.637809054437, 14681.779773303622, 14929.850701492845, 15151.318190681666, 15416.691666166838, 13855.752982231648, 13943.581692548834, 14593.416263349347, 14766.33551490207, 14864.0, 12806.935965320114, 13855.722885542435, 14212.147271163763, 14649.974501784773, 14683.045602035834, 12746.745065098461, 12874.098813082994, 12898.742025159256, 12966.935165324114, 13557.118787278796, 11196.440177990906, 11489.82765258505, 11643.767124657628, 11761.117916156152, 12398.62804115878, 9845.878492592396, 9910.950445248016, 10806.081483073713, 11096.167787416138, 11300.208985370946], "value": 8183.475888768459, "unit": "score"}], "name": "dromaeo_css", "value": 7357.193391785632, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 112.0, 112.0, 112.0, 110.0, 112.0, 112.0, 111.0, 112.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 130.0, 129.0, 171.0, 138.0, 128.0, 130.0, 107.0, 138.0, 106.0], "value": 133.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 182.0, 183.0, 183.0, 184.0, 178.0, 182.0, 180.0, 183.0], "value": 182.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 57.0, 106.0, 56.0, 57.0, 60.0, 64.0, 95.0, 57.0], "value": 70.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 88.0, 69.0, 70.0, 68.0, 96.0, 69.0, 66.0, 101.0], "value": 80.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 87.0, 91.0, 86.0, 90.0, 87.0, 109.0, 130.0], "value": 94.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 97.0, 97.0, 97.0, 96.0, 96.0, 96.0, 95.0, 95.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 134.0, 133.0, 85.0, 85.0, 83.0, 84.0, 82.0, 105.0, 143.0], "value": 103.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 54.0, 55.0, 53.0, 55.0, 53.0, 54.0, 54.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 73.0, 76.0, 75.0, 94.0, 100.0, 99.0, 77.0, 71.0], "value": 80.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 85.0, 85.0, 84.0, 80.0, 85.0, 83.0, 85.0, 85.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 136.0, 134.0, 131.0, 137.0, 134.0, 135.0, 176.0, 134.0], "value": 139.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 164.0, 164.0, 169.0, 164.0, 164.0, 165.0, 165.0, 163.0, 164.0], "value": 166.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 77.0, 87.0, 85.0, 84.0, 69.0, 67.0, 65.0, 64.0], "value": 73.3, "unit": "score"}], "name": "kraken", "value": 1467.3999999999999, "alertThreshold": 2.0}]} 03:06:18 INFO - Return code: 0 03:06:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:06:18 INFO - # TBPL SUCCESS # 03:06:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:06:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:06:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:06:18 INFO - 2016-07-09 03:06:18.147 screenresolution[1676:11569] starting screenresolution argv=screenresolution get 03:06:18 INFO - 2016-07-09 03:06:18.150 screenresolution[1676:11569] Display 0: 1600x1200x32@60 03:06:18 INFO - 2016-07-09 03:06:18.156 screenresolution[1677:11572] starting screenresolution argv=screenresolution list 03:06:18 INFO - Available Modes on Display 0 03:06:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:06:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:06:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:06:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:06:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:06:18 INFO - Intel Iris: 03:06:18 INFO - Chipset Model: Intel Iris 03:06:18 INFO - Type: GPU 03:06:18 INFO - Bus: Built-In 03:06:18 INFO - VRAM (Dynamic, Max): 1536 MB 03:06:18 INFO - Vendor: Intel (0x8086) 03:06:18 INFO - Device ID: 0x0a2e 03:06:18 INFO - Revision ID: 0x0009 03:06:18 INFO - Displays: 03:06:18 INFO - Display: 03:06:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:06:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:06:18 INFO - Display Serial Number: 1600x1200 60 03:06:18 INFO - Main Display: Yes 03:06:18 INFO - Mirror: Off 03:06:18 INFO - Online: Yes 03:06:18 INFO - Rotation: Supported 03:06:18 INFO - Return code: 0 03:06:18 INFO - Running post-action listener: _resource_record_post_action 03:06:18 INFO - ##### 03:06:18 INFO - ##### Finished run-tests step (success) 03:06:18 INFO - ##### 03:06:18 INFO - Running post-run listener: _resource_record_post_run 03:06:18 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 31636992; Write bytes: 709271040; Read time: 194; Write time: 1965 03:06:18 INFO - TinderboxPrint: CPU usage
53.4% 03:06:18 INFO - TinderboxPrint: I/O read bytes / time
31,636,992 / 194 03:06:18 INFO - TinderboxPrint: I/O write bytes / time
709,271,040 / 1,965 03:06:18 INFO - TinderboxPrint: CPU idle
2,259.4 (46.6%) 03:06:18 INFO - TinderboxPrint: CPU system
964.8 (19.9%) 03:06:18 INFO - TinderboxPrint: CPU user
1,625.5 (33.5%) 03:06:18 INFO - TinderboxPrint: Swap in / out
434,241,536 / 0 03:06:18 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 400792064; Write bytes: 396821504; Read time: 30137; Write time: 810 03:06:18 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 29605376; Write bytes: 296835584; Read time: 176; Write time: 1114 03:06:18 INFO - Running post-run listener: _upload_blobber_files 03:06:18 INFO - Blob upload gear active. 03:06:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:06:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:06:18 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:06:18 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:06:19 INFO - (blobuploader) - INFO - Open directory for files ... 03:06:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:06:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:06:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:06:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:06:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:06:20 INFO - (blobuploader) - INFO - Done attempting. 03:06:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:06:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:06:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:06:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:06:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:06:21 INFO - (blobuploader) - INFO - Done attempting. 03:06:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:06:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:06:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:06:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:06:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:06:23 INFO - (blobuploader) - INFO - Done attempting. 03:06:23 INFO - (blobuploader) - INFO - Iteration through files over. 03:06:23 INFO - Return code: 0 03:06:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:06:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:06:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8c1e38074e709873f228ad31c797c1037f0ec0ca58d0fa509ce536644d9ef0c5c02072b4a9a4d00f48de850dbbe7b49a20eddaf688201cf6729d87eb418567d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/74d946eea87f1e76430d3b5d0272e6ba08abc7bb213c6ce713f73ccb973bf79b72df5d309b05173e7b897e6a488be7eb33da800fb36929da8b64dcf7dbf2bb3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/558c331c80d889f37410d98b8319441aa1a5b0c9df9f07f1037536e3a5669c54200be2cb654bab62d067eef196d6681623c535830e1f2c4a5333c9c9713fdb2e"} 03:06:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:06:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:06:23 INFO - Contents: 03:06:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8c1e38074e709873f228ad31c797c1037f0ec0ca58d0fa509ce536644d9ef0c5c02072b4a9a4d00f48de850dbbe7b49a20eddaf688201cf6729d87eb418567d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/74d946eea87f1e76430d3b5d0272e6ba08abc7bb213c6ce713f73ccb973bf79b72df5d309b05173e7b897e6a488be7eb33da800fb36929da8b64dcf7dbf2bb3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/558c331c80d889f37410d98b8319441aa1a5b0c9df9f07f1037536e3a5669c54200be2cb654bab62d067eef196d6681623c535830e1f2c4a5333c9c9713fdb2e"} 03:06:23 INFO - Running post-run listener: copy_logs_to_upload_dir 03:06:23 INFO - Copying logs to upload dir... 03:06:23 INFO - mkdir: /builds/slave/test/build/upload/logs 03:06:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.106725 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-07-09 03:06:23.411821) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-09 03:06:23.415421) ========= 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.samNPNAIuI/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.wCh2HAuewk/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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/Fx-Team/sha512/8c1e38074e709873f228ad31c797c1037f0ec0ca58d0fa509ce536644d9ef0c5c02072b4a9a4d00f48de850dbbe7b49a20eddaf688201cf6729d87eb418567d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/74d946eea87f1e76430d3b5d0272e6ba08abc7bb213c6ce713f73ccb973bf79b72df5d309b05173e7b897e6a488be7eb33da800fb36929da8b64dcf7dbf2bb3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/558c331c80d889f37410d98b8319441aa1a5b0c9df9f07f1037536e3a5669c54200be2cb654bab62d067eef196d6681623c535830e1f2c4a5333c9c9713fdb2e"} build_url:https://queue.taskcluster.net/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011301 build_url: 'https://queue.taskcluster.net/v1/task/Cw6XFYNhTVOGIws_AmNqEg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8c1e38074e709873f228ad31c797c1037f0ec0ca58d0fa509ce536644d9ef0c5c02072b4a9a4d00f48de850dbbe7b49a20eddaf688201cf6729d87eb418567d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/74d946eea87f1e76430d3b5d0272e6ba08abc7bb213c6ce713f73ccb973bf79b72df5d309b05173e7b897e6a488be7eb33da800fb36929da8b64dcf7dbf2bb3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/558c331c80d889f37410d98b8319441aa1a5b0c9df9f07f1037536e3a5669c54200be2cb654bab62d067eef196d6681623c535830e1f2c4a5333c9c9713fdb2e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-09 03:06:23.448235) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 03:06:23.448673) ========= 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.samNPNAIuI/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.wCh2HAuewk/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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.004669 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 03:06:23.496894) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-09 03:06:23.497253) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-09 03:06:23.497586) ========= ========= Total master_lag: 1.05 =========