builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0262 starttime: 1467487219.67 results: success (0) buildid: 20160702021622 builduid: db25b141bf5e417ab9dc2b107658f7d3 revision: 39dffbba764210b25bfc1e749b4f16db77fa0d46 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-02 12:20:19.667927) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-02 12:20:19.668268) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-02 12:20:19.668540) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-02 12:20:19.711623) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 12:20:19.711894) ========= 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.dJTJf4n5NB/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.aZRgTIwHDJ/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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-02 12:20:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 165M=0s 2016-07-02 12:20:20 (165 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.572674 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 12:20:20.311850) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 12:20:20.312184) ========= 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.dJTJf4n5NB/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.aZRgTIwHDJ/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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.019446 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 12:20:20.364357) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-02 12:20:20.364905) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 39dffbba764210b25bfc1e749b4f16db77fa0d46 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 39dffbba764210b25bfc1e749b4f16db77fa0d46 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dJTJf4n5NB/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.aZRgTIwHDJ/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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-02 12:20:20,444 truncating revision to first 12 chars 2016-07-02 12:20:20,445 Setting DEBUG logging. 2016-07-02 12:20:20,445 attempt 1/10 2016-07-02 12:20:20,445 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/39dffbba7642?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-02 12:20:21,660 unpacking tar archive at: mozilla-central-39dffbba7642/testing/mozharness/ program finished with exit code 0 elapsedTime=1.509146 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-02 12:20:21.899377) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-02 12:20:21.899739) ========= script_repo_revision: 39dffbba764210b25bfc1e749b4f16db77fa0d46 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-02 12:20:21.900177) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-02 12:20:21.900483) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-02 12:20:21.918874) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 27 secs) (at 2016-07-02 12:20:21.919352) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dJTJf4n5NB/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.aZRgTIwHDJ/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 12:20:22 INFO - MultiFileLogger online at 20160702 12:20:22 in /builds/slave/test 12:20:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:20:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:20:22 INFO - {'append_to_log': False, 12:20:22 INFO - 'base_work_dir': '/builds/slave/test', 12:20:22 INFO - 'blob_upload_branch': 'Firefox', 12:20:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:20:22 INFO - 'branch': 'Firefox', 12:20:22 INFO - 'buildbot_json_path': 'buildprops.json', 12:20:22 INFO - 'config_files': ('talos/mac_config.py',), 12:20:22 INFO - 'default_actions': ('clobber', 12:20:22 INFO - 'read-buildbot-config', 12:20:22 INFO - 'download-and-extract', 12:20:22 INFO - 'populate-webroot', 12:20:22 INFO - 'create-virtualenv', 12:20:22 INFO - 'install', 12:20:22 INFO - 'run-tests'), 12:20:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:20:22 INFO - 'download_minidump_stackwalk': True, 12:20:22 INFO - 'download_symbols': 'ondemand', 12:20:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:20:22 INFO - 'tooltool.py': '/tools/tooltool.py', 12:20:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:20:22 INFO - '/tools/misc-python/virtualenv.py')}, 12:20:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:20:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:20:22 INFO - 'installer_path': 'installer.exe', 12:20:22 INFO - 'log_level': 'info', 12:20:22 INFO - 'log_name': 'talos', 12:20:22 INFO - 'log_to_console': True, 12:20:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:20:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:20:22 INFO - 'opt_config_files': (), 12:20:22 INFO - 'pip_index': False, 12:20:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:20:22 INFO - 'cmd': ('bash', 12:20:22 INFO - '-c', 12:20:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:20:22 INFO - 'enabled': True, 12:20:22 INFO - 'halt_on_failure': False, 12:20:22 INFO - 'name': 'check_screen_resolution'},), 12:20:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:20:22 INFO - 'cmd': ('bash', 12:20:22 INFO - '-c', 12:20:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:20:22 INFO - 'enabled': True, 12:20:22 INFO - 'halt_on_failure': False, 12:20:22 INFO - 'name': 'check_screen_resolution'},), 12:20:22 INFO - 'run_cmd_checks_enabled': True, 12:20:22 INFO - 'sps_profile': False, 12:20:22 INFO - 'sps_profile_interval': 0, 12:20:22 INFO - 'suite': 'dromaeojs', 12:20:22 INFO - 'system_bits': '32', 12:20:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:20:22 INFO - 'title': 't-yosemite-r7-0262', 12:20:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:20:22 INFO - 'use_talos_json': True, 12:20:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:20:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:20:22 INFO - 'work_dir': 'build'} 12:20:22 INFO - ##### 12:20:22 INFO - ##### Running clobber step. 12:20:22 INFO - ##### 12:20:22 INFO - Running pre-action listener: _resource_record_pre_action 12:20:22 INFO - Running main action method: clobber 12:20:22 INFO - rmtree: /builds/slave/test/build 12:20:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:20:23 INFO - Running post-action listener: _resource_record_post_action 12:20:23 INFO - ##### 12:20:23 INFO - ##### Finished clobber step (success) 12:20:23 INFO - ##### 12:20:23 INFO - ##### 12:20:23 INFO - ##### Running read-buildbot-config step. 12:20:23 INFO - ##### 12:20:23 INFO - Running pre-action listener: _resource_record_pre_action 12:20:23 INFO - Running main action method: read_buildbot_config 12:20:23 INFO - Using buildbot properties: 12:20:23 INFO - { 12:20:23 INFO - "project": "", 12:20:23 INFO - "product": "firefox", 12:20:23 INFO - "script_repo_revision": "production", 12:20:23 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 12:20:23 INFO - "repository": "", 12:20:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 12:20:23 INFO - "buildid": "20160702021622", 12:20:23 INFO - "pgo_build": "False", 12:20:23 INFO - "basedir": "/builds/slave/test", 12:20:23 INFO - "buildnumber": 0, 12:20:23 INFO - "slavename": "t-yosemite-r7-0262", 12:20:23 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 12:20:23 INFO - "platform": "yosemite_r7", 12:20:23 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:20:23 INFO - "branch": "mozilla-central", 12:20:23 INFO - "repo_path": "mozilla-central", 12:20:23 INFO - "revision": "39dffbba764210b25bfc1e749b4f16db77fa0d46", 12:20:23 INFO - "stage_platform": "macosx64", 12:20:23 INFO - "builduid": "db25b141bf5e417ab9dc2b107658f7d3", 12:20:23 INFO - "slavebuilddir": "test" 12:20:23 INFO - } 12:20:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 12:20:23 INFO - Running post-action listener: _resource_record_post_action 12:20:23 INFO - ##### 12:20:23 INFO - ##### Finished read-buildbot-config step (success) 12:20:23 INFO - ##### 12:20:23 INFO - ##### 12:20:23 INFO - ##### Running download-and-extract step. 12:20:23 INFO - ##### 12:20:23 INFO - Running pre-action listener: _resource_record_pre_action 12:20:23 INFO - Running main action method: download_and_extract 12:20:23 INFO - mkdir: /builds/slave/test/build/tests 12:20:23 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')]} 12:20:23 INFO - https://queue.taskcluster.net/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:20:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 12:20:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 12:20:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/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 12:20:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/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 12:20:53 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json: timed out 12:20:53 INFO - retry: attempt #1 caught exception: timed out 12:20:53 INFO - retry: Failed, sleeping 30 seconds before retrying 12:21:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/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 #2 12:21:33 INFO - Downloaded 1199 bytes. 12:21:33 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 12:21:33 INFO - Using the following test package requirements: 12:21:33 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 12:21:33 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:21:33 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 12:21:33 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:21:33 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 12:21:33 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:21:33 INFO - u'jsshell-mac.zip'], 12:21:33 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:21:33 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 12:21:33 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 12:21:33 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:21:33 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 12:21:33 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:21:33 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 12:21:33 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:21:33 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 12:21:33 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:21:33 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 12:21:33 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:21:33 INFO - https://queue.taskcluster.net/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:21:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:21:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:21:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/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 12:21:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/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 12:21:47 INFO - Downloaded 18791314 bytes. 12:21:47 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'] 12:21:47 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 12:21:50 INFO - Return code: 0 12:21:50 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 12:21: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')]} 12:21:50 INFO - https://queue.taskcluster.net/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:21:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:21:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:21:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/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 12:21:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/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 12:21:52 INFO - Downloaded 18791314 bytes. 12:21:52 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'] 12:21:52 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 12:21:56 INFO - Return code: 0 12:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:21:56 INFO - https://queue.taskcluster.net/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:21:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:21:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:21:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/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 12:21:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/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 12:21:57 INFO - Downloaded 14405327 bytes. 12:21:57 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'] 12:21:57 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 12:21:57 INFO - Return code: 0 12:21:57 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')]} 12:21:57 INFO - https://queue.taskcluster.net/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:21:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:21:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:21:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 12:21:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYllZ4cRQtqW8ceq5G3k2g/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 12:22:01 INFO - Downloaded 112453332 bytes. 12:22:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:22:01 INFO - mkdir: /builds/slave/test/properties 12:22:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:22:01 INFO - Writing to file /builds/slave/test/properties/build_url 12:22:01 INFO - Contents: 12:22:01 INFO - build_url:https://queue.taskcluster.net/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:22:02 INFO - Running post-action listener: _resource_record_post_action 12:22:02 INFO - Running post-action listener: set_extra_try_arguments 12:22:02 INFO - ##### 12:22:02 INFO - ##### Finished download-and-extract step (success) 12:22:02 INFO - ##### 12:22:02 INFO - ##### 12:22:02 INFO - ##### Running populate-webroot step. 12:22:02 INFO - ##### 12:22:02 INFO - Running pre-action listener: _resource_record_pre_action 12:22:02 INFO - Running main action method: populate_webroot 12:22:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:22:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:22:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:22:02 INFO - '--tppagecycles', 12:22:02 INFO - '7'], 12:22:02 INFO - 'tests': ['tsvgm']}}, 12:22:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:22:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:22:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:22:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:22:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:22:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:22:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:02 INFO - 'talos_options': ['--disable-e10s'], 12:22:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:22:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:22:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:02 INFO - 'talos_options': ['--disable-e10s'], 12:22:02 INFO - 'tests': ['damp', 'tps']}, 12:22:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:02 INFO - 'tests': ['damp', 'tps']}, 12:22:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:22:02 INFO - 'tests': ['dromaeo_dom']}, 12:22:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:22:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:22:02 INFO - 'tests': ['basic_compositor_video']}, 12:22:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:22:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:22:02 INFO - 'tests': ['a11yr', 12:22:02 INFO - 'ts_paint', 12:22:02 INFO - 'tpaint', 12:22:02 INFO - 'sessionrestore', 12:22:02 INFO - 'sessionrestore_no_auto_restore', 12:22:02 INFO - 'tabpaint']}, 12:22:02 INFO - 'other-e10s': {'tests': ['a11yr', 12:22:02 INFO - 'ts_paint', 12:22:02 INFO - 'tpaint', 12:22:02 INFO - 'sessionrestore', 12:22:02 INFO - 'sessionrestore_no_auto_restore', 12:22:02 INFO - 'tabpaint']}, 12:22:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:22:02 INFO - 'ts_paint', 12:22:02 INFO - 'tpaint', 12:22:02 INFO - 'sessionrestore', 12:22:02 INFO - 'sessionrestore_no_auto_restore', 12:22:02 INFO - 'tabpaint']}, 12:22:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:22:02 INFO - 'ts_paint', 12:22:02 INFO - 'tpaint', 12:22:02 INFO - 'sessionrestore', 12:22:02 INFO - 'sessionrestore_no_auto_restore', 12:22:02 INFO - 'tabpaint']}, 12:22:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:22:02 INFO - 'tests': ['a11yr', 12:22:02 INFO - 'ts_paint', 12:22:02 INFO - 'tpaint', 12:22:02 INFO - 'sessionrestore', 12:22:02 INFO - 'sessionrestore_no_auto_restore', 12:22:02 INFO - 'tabpaint']}, 12:22:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:22:02 INFO - 'tests': ['a11yr', 12:22:02 INFO - 'ts_paint', 12:22:02 INFO - 'tpaint', 12:22:02 INFO - 'sessionrestore', 12:22:02 INFO - 'sessionrestore_no_auto_restore', 12:22:02 INFO - 'tabpaint']}, 12:22:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:22:02 INFO - 'tests': ['tsvgx', 12:22:02 INFO - 'tsvgr_opacity', 12:22:02 INFO - 'tart', 12:22:02 INFO - 'tscrollx', 12:22:02 INFO - 'cart']}, 12:22:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:22:02 INFO - 'tsvgr_opacity', 12:22:02 INFO - 'tart', 12:22:02 INFO - 'tscrollx', 12:22:02 INFO - 'cart']}, 12:22:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:02 INFO - 'talos_options': ['--disable-e10s'], 12:22:02 INFO - 'tests': ['tp5o']}, 12:22:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:02 INFO - 'tests': ['tp5o']}, 12:22:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:02 INFO - 'talos_options': ['--disable-e10s', 12:22:02 INFO - '--xperf_path', 12:22:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:22:02 INFO - 'tests': ['tp5n']}, 12:22:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:02 INFO - 'talos_options': ['--xperf_path', 12:22:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:22:02 INFO - 'tests': ['tp5n']}}, 12:22:02 INFO - 'talos.zip': {'path': '', 12:22:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:22:02 INFO - Running post-action listener: _resource_record_post_action 12:22:02 INFO - ##### 12:22:02 INFO - ##### Finished populate-webroot step (success) 12:22:02 INFO - ##### 12:22:02 INFO - ##### 12:22:02 INFO - ##### Running create-virtualenv step. 12:22:02 INFO - ##### 12:22:02 INFO - Running pre-action listener: _resource_record_pre_action 12:22:02 INFO - Running main action method: create_virtualenv 12:22:02 INFO - Creating virtualenv /builds/slave/test/build/venv 12:22:02 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 12:22:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:22:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:22:03 INFO - Using real prefix '/tools/python27' 12:22:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:22:03 INFO - Installing distribute.............................................................................................................................................................................................done. 12:22:06 INFO - Installing pip.................done. 12:22:06 INFO - Return code: 0 12:22:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103fc8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10405d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b00d82760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f2cd30>, '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 0x103c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f24b20>, '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.dJTJf4n5NB/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.aZRgTIwHDJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 12:22:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:22:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:22:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJTJf4n5NB/Render', 12:22:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:06 INFO - 'HOME': '/Users/cltbld', 12:22:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:06 INFO - 'LOGNAME': 'cltbld', 12:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:06 INFO - 'MOZ_NO_REMOTE': '1', 12:22:06 INFO - 'NO_EM_RESTART': '1', 12:22:06 INFO - 'PAGER': '/bin/cat', 12:22:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:06 INFO - 'PWD': '/builds/slave/test', 12:22:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:06 INFO - 'SHELL': '/bin/bash', 12:22:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZRgTIwHDJ/Listeners', 12:22:06 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 12:22:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:06 INFO - 'USER': 'cltbld', 12:22:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:06 INFO - 'XPC_FLAGS': '0x0', 12:22:06 INFO - 'XPC_SERVICE_NAME': '0', 12:22:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:06 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 12:22:06 INFO - Cleaning up... 12:22:06 INFO - Return code: 0 12:22:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103fc8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10405d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b00d82760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f2cd30>, '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 0x103c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f24b20>, '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.dJTJf4n5NB/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.aZRgTIwHDJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 12:22:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:22:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:22:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJTJf4n5NB/Render', 12:22:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:06 INFO - 'HOME': '/Users/cltbld', 12:22:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:06 INFO - 'LOGNAME': 'cltbld', 12:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:06 INFO - 'MOZ_NO_REMOTE': '1', 12:22:06 INFO - 'NO_EM_RESTART': '1', 12:22:06 INFO - 'PAGER': '/bin/cat', 12:22:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:06 INFO - 'PWD': '/builds/slave/test', 12:22:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:06 INFO - 'SHELL': '/bin/bash', 12:22:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZRgTIwHDJ/Listeners', 12:22:06 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 12:22:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:06 INFO - 'USER': 'cltbld', 12:22:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:06 INFO - 'XPC_FLAGS': '0x0', 12:22:06 INFO - 'XPC_SERVICE_NAME': '0', 12:22:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:07 INFO - Downloading/unpacking psutil>=3.1.1 12:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:22:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:22:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:22:10 INFO - Installing collected packages: psutil 12:22:10 INFO - Running setup.py install for psutil 12:22:10 INFO - building 'psutil._psutil_osx' extension 12:22:10 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 12:22:10 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 12:22:10 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 12:22:11 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:22:11 INFO - building 'psutil._psutil_posix' extension 12:22:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:22:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:22:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:22:11 INFO - ^ 12:22:11 INFO - 1 warning generated. 12:22:11 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:22:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:22:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:22:11 INFO - Successfully installed psutil 12:22:11 INFO - Cleaning up... 12:22:11 INFO - Return code: 0 12:22:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103fc8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10405d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b00d82760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f2cd30>, '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 0x103c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f24b20>, '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.dJTJf4n5NB/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.aZRgTIwHDJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 12:22:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:22:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:22:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJTJf4n5NB/Render', 12:22:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:11 INFO - 'HOME': '/Users/cltbld', 12:22:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:11 INFO - 'LOGNAME': 'cltbld', 12:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:11 INFO - 'MOZ_NO_REMOTE': '1', 12:22:11 INFO - 'NO_EM_RESTART': '1', 12:22:11 INFO - 'PAGER': '/bin/cat', 12:22:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:11 INFO - 'PWD': '/builds/slave/test', 12:22:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:11 INFO - 'SHELL': '/bin/bash', 12:22:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZRgTIwHDJ/Listeners', 12:22:11 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 12:22:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:11 INFO - 'USER': 'cltbld', 12:22:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:11 INFO - 'XPC_FLAGS': '0x0', 12:22:11 INFO - 'XPC_SERVICE_NAME': '0', 12:22:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:22:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:22:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:22:14 INFO - Installing collected packages: mozsystemmonitor 12:22:14 INFO - Running setup.py install for mozsystemmonitor 12:22:14 INFO - Successfully installed mozsystemmonitor 12:22:14 INFO - Cleaning up... 12:22:14 INFO - Return code: 0 12:22:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:22:14 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')]} 12:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:14 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')]} 12:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:14 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 0x103fc8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10405d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b00d82760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f2cd30>, '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 0x103c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f24b20>, '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.dJTJf4n5NB/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.aZRgTIwHDJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 12:22:14 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 12:22:14 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 12:22:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJTJf4n5NB/Render', 12:22:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:14 INFO - 'HOME': '/Users/cltbld', 12:22:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:14 INFO - 'LOGNAME': 'cltbld', 12:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:14 INFO - 'MOZ_NO_REMOTE': '1', 12:22:14 INFO - 'NO_EM_RESTART': '1', 12:22:14 INFO - 'PAGER': '/bin/cat', 12:22:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:14 INFO - 'PWD': '/builds/slave/test', 12:22:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:14 INFO - 'SHELL': '/bin/bash', 12:22:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZRgTIwHDJ/Listeners', 12:22:14 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 12:22:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:14 INFO - 'USER': 'cltbld', 12:22:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:14 INFO - 'XPC_FLAGS': '0x0', 12:22:14 INFO - 'XPC_SERVICE_NAME': '0', 12:22:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:14 INFO - Downloading/unpacking jsonschema==2.5.1 12:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:22:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:17 INFO - Downloading functools32-3.2.3-2.tar.gz 12:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:22:17 INFO - warning: no files found matching '*.txt' 12:22:17 INFO - no previously-included directories found matching 'build' 12:22:17 INFO - no previously-included directories found matching 'dist' 12:22:17 INFO - no previously-included directories found matching '.git*' 12:22:17 INFO - Installing collected packages: jsonschema, functools32 12:22:17 INFO - Running setup.py install for functools32 12:22:17 INFO - warning: no files found matching '*.txt' 12:22:17 INFO - no previously-included directories found matching 'build' 12:22:17 INFO - no previously-included directories found matching 'dist' 12:22:17 INFO - no previously-included directories found matching '.git*' 12:22:17 INFO - Successfully installed jsonschema functools32 12:22:17 INFO - Cleaning up... 12:22:17 INFO - Return code: 0 12:22:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:22:17 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')]} 12:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:17 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')]} 12:22:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:17 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 0x103fc8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10405d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b00d82760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f2cd30>, '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 0x103c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f24b20>, '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.dJTJf4n5NB/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.aZRgTIwHDJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 12:22:17 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 12:22:17 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 12:22:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJTJf4n5NB/Render', 12:22:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:17 INFO - 'HOME': '/Users/cltbld', 12:22:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:17 INFO - 'LOGNAME': 'cltbld', 12:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:17 INFO - 'MOZ_NO_REMOTE': '1', 12:22:17 INFO - 'NO_EM_RESTART': '1', 12:22:17 INFO - 'PAGER': '/bin/cat', 12:22:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:17 INFO - 'PWD': '/builds/slave/test', 12:22:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:17 INFO - 'SHELL': '/bin/bash', 12:22:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZRgTIwHDJ/Listeners', 12:22:17 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 12:22:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:17 INFO - 'USER': 'cltbld', 12:22:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:17 INFO - 'XPC_FLAGS': '0x0', 12:22:17 INFO - 'XPC_SERVICE_NAME': '0', 12:22:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:18 INFO - Downloading/unpacking blobuploader==1.2.4 12:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:20 INFO - Downloading blobuploader-1.2.4.tar.gz 12:22:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:22:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:22:20 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 12:22:20 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 12:22:20 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 12:22:20 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 12:22:20 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 12:22:20 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 12:22:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:22:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:22:21 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 12:22:21 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 12:22:21 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 12:22:21 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 12:22:21 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 12:22:21 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 12:22:21 INFO - Downloading docopt-0.6.1.tar.gz 12:22:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:22:21 INFO - Installing collected packages: blobuploader, requests, docopt 12:22:21 INFO - Running setup.py install for blobuploader 12:22:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:22:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:22:21 INFO - Running setup.py install for requests 12:22:22 INFO - Running setup.py install for docopt 12:22:22 INFO - Successfully installed blobuploader requests docopt 12:22:22 INFO - Cleaning up... 12:22:22 INFO - Return code: 0 12:22:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:22 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 0x103fc8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10405d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b00d82760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f2cd30>, '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 0x103c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f24b20>, '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.dJTJf4n5NB/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.aZRgTIwHDJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 12:22:22 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 12:22:22 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 12:22:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJTJf4n5NB/Render', 12:22:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:22 INFO - 'HOME': '/Users/cltbld', 12:22:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:22 INFO - 'LOGNAME': 'cltbld', 12:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:22 INFO - 'MOZ_NO_REMOTE': '1', 12:22:22 INFO - 'NO_EM_RESTART': '1', 12:22:22 INFO - 'PAGER': '/bin/cat', 12:22:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:22 INFO - 'PWD': '/builds/slave/test', 12:22:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:22 INFO - 'SHELL': '/bin/bash', 12:22:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZRgTIwHDJ/Listeners', 12:22:22 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 12:22:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:22 INFO - 'USER': 'cltbld', 12:22:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:22 INFO - 'XPC_FLAGS': '0x0', 12:22:22 INFO - 'XPC_SERVICE_NAME': '0', 12:22:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:22:22 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-SD6FVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:22:23 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-3WAUVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:22:23 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-HjxxqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:22:23 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-NwWhuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:22:23 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-v21g5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:22:23 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-mEV2Gq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:22:23 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-3Bvxfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:22:23 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-cyYZXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:22:23 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-MCWO8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:22:23 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-5YwsiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:22:23 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-Eeg1p5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:22:24 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-c9HX_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:22:24 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-C8PuX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:22:24 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-94wBw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:24 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-5BCi3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:22:24 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-dwiEvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:22:24 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-G1L95q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:22:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:22:24 INFO - Running setup.py install for manifestparser 12:22:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:22:24 INFO - Running setup.py install for mozcrash 12:22:25 INFO - Running setup.py install for mozdebug 12:22:25 INFO - Running setup.py install for mozdevice 12:22:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:22:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:22:25 INFO - Running setup.py install for mozfile 12:22:25 INFO - Running setup.py install for mozhttpd 12:22:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:22:25 INFO - Running setup.py install for mozinfo 12:22:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:22:25 INFO - Running setup.py install for mozInstall 12:22:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:22:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:22:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:22:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:22:25 INFO - Running setup.py install for mozleak 12:22:26 INFO - Running setup.py install for mozlog 12:22:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:22:26 INFO - Running setup.py install for moznetwork 12:22:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:22:26 INFO - Running setup.py install for mozprocess 12:22:26 INFO - Running setup.py install for mozprofile 12:22:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:22:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:22:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:22:26 INFO - Running setup.py install for mozrunner 12:22:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:22:26 INFO - Running setup.py install for mozscreenshot 12:22:26 INFO - Running setup.py install for moztest 12:22:27 INFO - Running setup.py install for mozversion 12:22:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:22:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:22:27 INFO - Cleaning up... 12:22:27 INFO - Return code: 0 12:22:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:22:27 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')]} 12:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:27 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')]} 12:22:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:27 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 0x103fc8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10405d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b00d82760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f2cd30>, '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 0x103c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f24b20>, '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.dJTJf4n5NB/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.aZRgTIwHDJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 12:22:27 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 12:22:27 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 12:22:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJTJf4n5NB/Render', 12:22:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:27 INFO - 'HOME': '/Users/cltbld', 12:22:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:27 INFO - 'LOGNAME': 'cltbld', 12:22:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:27 INFO - 'MOZ_NO_REMOTE': '1', 12:22:27 INFO - 'NO_EM_RESTART': '1', 12:22:27 INFO - 'PAGER': '/bin/cat', 12:22:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:27 INFO - 'PWD': '/builds/slave/test', 12:22:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:27 INFO - 'SHELL': '/bin/bash', 12:22:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZRgTIwHDJ/Listeners', 12:22:27 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 12:22:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:27 INFO - 'USER': 'cltbld', 12:22:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:27 INFO - 'XPC_FLAGS': '0x0', 12:22:27 INFO - 'XPC_SERVICE_NAME': '0', 12:22:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:22:27 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-qySJqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:22:27 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)) 12:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:22:27 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-1mqSAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:22:27 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)) 12:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:22:27 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-40eMFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:22:27 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)) 12:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:22:27 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-wuMW0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:22:27 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)) 12:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:22:28 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-erVQRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:22:28 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)) 12:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:22:28 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-VVWAPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:22:28 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)) 12:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:22:28 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-oAyhE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:22:28 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)) 12:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:22:28 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-jdzfcX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:22:28 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)) 12:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:22:28 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-yRcJaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:22:28 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)) 12:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:22:28 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-9_jlfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:22:28 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)) 12:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:22:28 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-T1r5IW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:22:28 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)) 12:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:22:28 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-ccYRAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:22:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:22:28 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-nm0lyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:22:28 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)) 12:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:22:28 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-NSSSTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:22:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:29 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-EuN0Mv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:29 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)) 12:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:22:29 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-HQk_bG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:22:29 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)) 12:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:22:29 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-7ok0NH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:22:29 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)) 12:22:29 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)) 12:22:29 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)) 12:22:29 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)) 12:22:29 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)) 12:22:29 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)) 12:22:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:31 INFO - Downloading blessings-1.6.tar.gz 12:22:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:22:31 INFO - Installing collected packages: blessings 12:22:31 INFO - Running setup.py install for blessings 12:22:32 INFO - Successfully installed blessings 12:22:32 INFO - Cleaning up... 12:22:32 INFO - Return code: 0 12:22:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:22:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:22:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:22:32 INFO - Reading from file tmpfile_stdout 12:22:32 INFO - Current package versions: 12:22:32 INFO - blessings == 1.6 12:22:32 INFO - blobuploader == 1.2.4 12:22:32 INFO - docopt == 0.6.1 12:22:32 INFO - functools32 == 3.2.3-2 12:22:32 INFO - jsonschema == 2.5.1 12:22:32 INFO - manifestparser == 1.1 12:22:32 INFO - mozInstall == 1.12 12:22:32 INFO - mozcrash == 0.17 12:22:32 INFO - mozdebug == 0.1 12:22:32 INFO - mozdevice == 0.48 12:22:32 INFO - mozfile == 1.2 12:22:32 INFO - mozhttpd == 0.7 12:22:32 INFO - mozinfo == 0.9 12:22:32 INFO - mozleak == 0.1 12:22:32 INFO - mozlog == 3.2 12:22:32 INFO - moznetwork == 0.27 12:22:32 INFO - mozprocess == 0.22 12:22:32 INFO - mozprofile == 0.28 12:22:32 INFO - mozrunner == 6.11 12:22:32 INFO - mozscreenshot == 0.1 12:22:32 INFO - mozsystemmonitor == 0.3 12:22:32 INFO - moztest == 0.7 12:22:32 INFO - mozversion == 1.4 12:22:32 INFO - psutil == 3.1.1 12:22:32 INFO - requests == 1.2.3 12:22:32 INFO - wsgiref == 0.1.2 12:22:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:22:32 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')]} 12:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:32 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')]} 12:22:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:32 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 0x103fc8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10405d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b00d82760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f2cd30>, '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 0x103c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f24b20>, '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.dJTJf4n5NB/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.aZRgTIwHDJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 12:22:32 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 12:22:32 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 12:22:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJTJf4n5NB/Render', 12:22:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:32 INFO - 'HOME': '/Users/cltbld', 12:22:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:32 INFO - 'LOGNAME': 'cltbld', 12:22:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:32 INFO - 'MOZ_NO_REMOTE': '1', 12:22:32 INFO - 'NO_EM_RESTART': '1', 12:22:32 INFO - 'PAGER': '/bin/cat', 12:22:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:32 INFO - 'PWD': '/builds/slave/test', 12:22:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:32 INFO - 'SHELL': '/bin/bash', 12:22:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZRgTIwHDJ/Listeners', 12:22:32 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 12:22:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:32 INFO - 'USER': 'cltbld', 12:22:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:32 INFO - 'XPC_FLAGS': '0x0', 12:22:32 INFO - 'XPC_SERVICE_NAME': '0', 12:22:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:32 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)) 12:22:32 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)) 12:22:32 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)) 12:22:32 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)) 12:22:32 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)) 12:22:32 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)) 12:22:32 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)) 12:22:32 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)) 12:22:32 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)) 12:22:32 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)) 12:22:32 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)) 12:22:32 INFO - Cleaning up... 12:22:32 INFO - Return code: 0 12:22:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:22:32 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')]} 12:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:32 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')]} 12:22:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:32 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 0x103fc8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10405d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b00d82760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f2cd30>, '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 0x103c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f24b20>, '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.dJTJf4n5NB/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.aZRgTIwHDJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 12:22:32 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 12:22:32 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 12:22:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJTJf4n5NB/Render', 12:22:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:32 INFO - 'HOME': '/Users/cltbld', 12:22:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:32 INFO - 'LOGNAME': 'cltbld', 12:22:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:32 INFO - 'MOZ_NO_REMOTE': '1', 12:22:32 INFO - 'NO_EM_RESTART': '1', 12:22:32 INFO - 'PAGER': '/bin/cat', 12:22:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:32 INFO - 'PWD': '/builds/slave/test', 12:22:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:32 INFO - 'SHELL': '/bin/bash', 12:22:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZRgTIwHDJ/Listeners', 12:22:32 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 12:22:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:32 INFO - 'USER': 'cltbld', 12:22:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:32 INFO - 'XPC_FLAGS': '0x0', 12:22:32 INFO - 'XPC_SERVICE_NAME': '0', 12:22:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:22:32 INFO - Cleaning up... 12:22:32 INFO - Return code: 0 12:22:32 INFO - Running post-action listener: _resource_record_post_action 12:22:32 INFO - Running post-action listener: _start_resource_monitoring 12:22:32 INFO - Starting resource monitoring. 12:22:32 INFO - ##### 12:22:32 INFO - ##### Finished create-virtualenv step (success) 12:22:32 INFO - ##### 12:22:32 INFO - ##### 12:22:32 INFO - ##### Running install step. 12:22:32 INFO - ##### 12:22:32 INFO - Running pre-action listener: _resource_record_pre_action 12:22:32 INFO - Running main action method: install 12:22:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:22:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:22:33 INFO - Reading from file tmpfile_stdout 12:22:33 INFO - Detecting whether we're running mozinstall >=1.0... 12:22:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:22:33 INFO - Reading from file tmpfile_stdout 12:22:33 INFO - Output received: 12:22:33 INFO - Usage: mozinstall [options] installer 12:22:33 INFO - Options: 12:22:33 INFO - -h, --help show this help message and exit 12:22:33 INFO - -d DEST, --destination=DEST 12:22:33 INFO - Directory to install application into. [default: 12:22:33 INFO - "/builds/slave/test"] 12:22:33 INFO - --app=APP Application being installed. [default: firefox] 12:22:33 INFO - mkdir: /builds/slave/test/build/application 12:22:33 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'] 12:22:33 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 12:23:07 INFO - Reading from file tmpfile_stdout 12:23:07 INFO - Output received: 12:23:07 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:23:07 INFO - Running post-action listener: _resource_record_post_action 12:23:07 INFO - ##### 12:23:07 INFO - ##### Finished install step (success) 12:23:07 INFO - ##### 12:23:07 INFO - ##### 12:23:07 INFO - ##### Running run-tests step. 12:23:07 INFO - ##### 12:23:07 INFO - Running pre-action listener: _resource_record_pre_action 12:23:07 INFO - Running main action method: run_tests 12:23:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:23:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:23:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:23:07 INFO - 2016-07-02 12:23:07.480 screenresolution[1933:21071] starting screenresolution argv=screenresolution get 12:23:07 INFO - 2016-07-02 12:23:07.483 screenresolution[1933:21071] Display 0: 1600x1200x32@60 12:23:07 INFO - 2016-07-02 12:23:07.488 screenresolution[1934:21073] starting screenresolution argv=screenresolution list 12:23:07 INFO - Available Modes on Display 0 12:23:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:23:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:23:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:23:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:23:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:23:07 INFO - Intel Iris: 12:23:07 INFO - Chipset Model: Intel Iris 12:23:07 INFO - Type: GPU 12:23:07 INFO - Bus: Built-In 12:23:07 INFO - VRAM (Dynamic, Max): 1536 MB 12:23:07 INFO - Vendor: Intel (0x8086) 12:23:07 INFO - Device ID: 0x0a2e 12:23:07 INFO - Revision ID: 0x0009 12:23:07 INFO - Displays: 12:23:07 INFO - Display: 12:23:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:23:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:23:07 INFO - Display Serial Number: 1600x1200 60 12:23:07 INFO - Main Display: Yes 12:23:07 INFO - Mirror: Off 12:23:07 INFO - Online: Yes 12:23:07 INFO - Rotation: Supported 12:23:07 INFO - Return code: 0 12:23:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:23:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:23:07 INFO - Python 2.7.3 12:23:07 INFO - Return code: 0 12:23:07 INFO - grabbing minidump binary from tooltool 12:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:07 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 0x103f2cd30>, '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 0x103c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f24b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:23:07 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 12:23:07 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 12:23:07 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 12:23:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:23:07 INFO - Return code: 0 12:23:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:23:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:23:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:23:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:23:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:23:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0262', '--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 12:23:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0262 --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 12:23:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJTJf4n5NB/Render', 12:23:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:07 INFO - 'HOME': '/Users/cltbld', 12:23:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:07 INFO - 'LOGNAME': 'cltbld', 12:23:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:23:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:23:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:07 INFO - 'MOZ_NO_REMOTE': '1', 12:23:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:23:07 INFO - 'NO_EM_RESTART': '1', 12:23:07 INFO - 'PAGER': '/bin/cat', 12:23:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:07 INFO - 'PWD': '/builds/slave/test', 12:23:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:23:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:07 INFO - 'SHELL': '/bin/bash', 12:23:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZRgTIwHDJ/Listeners', 12:23:07 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 12:23:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:07 INFO - 'USER': 'cltbld', 12:23:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:07 INFO - 'XPC_FLAGS': '0x0', 12:23:07 INFO - 'XPC_SERVICE_NAME': '0', 12:23:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0262', '--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 12:23:08 INFO - mozversion application_buildid: 20160702021622 12:23:08 INFO - mozversion application_changeset: 39dffbba764210b25bfc1e749b4f16db77fa0d46 12:23:08 INFO - mozversion application_display_name: Nightly 12:23:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:23:08 INFO - mozversion application_name: Firefox 12:23:08 INFO - mozversion application_remotingname: firefox 12:23:08 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:23:08 INFO - mozversion application_vendor: Mozilla 12:23:08 INFO - mozversion application_version: 50.0a1 12:23:08 INFO - mozversion platform_buildid: 20160702021622 12:23:08 INFO - mozversion platform_changeset: 39dffbba764210b25bfc1e749b4f16db77fa0d46 12:23:08 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:23:08 INFO - mozversion platform_version: 50.0a1 12:23:08 INFO - using testdate: 1467487388 12:23:08 INFO - actual date: 1467487388 12:23:08 INFO - starting webserver on 'localhost:54155' 12:23:08 INFO - SUITE-START | Running 2 tests 12:23:08 INFO - TEST-START | dromaeo_css 12:23:08 INFO - operating with platform_type : mac_ 12:23:08 INFO - Initialising browser for dromaeo_css test... 12:23:08 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/tmpUwdpvf/profile http://localhost:54155/getInfo.html) 12:23:10 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 12:23:10 INFO - PROCESS | 1944 | colorDepth:24 12:23:10 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 12:23:10 INFO - PROCESS | 1944 | __metrics 12:23:10 INFO - PROCESS | 1944 | 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" 12:23:10 INFO - PROCESS | 1944 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:23:11 INFO - TEST-INFO | 1944: exit 0 12:23:11 INFO - Browser initialized. 12:23:11 INFO - Running cycle 1/1 for dromaeo_css test... 12:23:11 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/tmpUwdpvf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:23:23 INFO - PROCESS | 1946 | RSS: Main: 244006912 12:23:23 INFO - PROCESS | 1946 | 12:24:14 INFO - PROCESS | 1946 | 1467487454120 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] 12:26:25 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:54155/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54155/tests/dromaeo/cssquery-ext.html) 12:26:26 INFO - PROCESS | 1946 | RSS: Main: 331583488 12:26:26 INFO - PROCESS | 1946 | 12:29:29 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:54155/tests/dromaeo/cssquery-ext.html (next: http://localhost:54155/tests/dromaeo/cssquery-jquery.html) 12:29:30 INFO - PROCESS | 1946 | RSS: Main: 372465664 12:29:30 INFO - PROCESS | 1946 | 12:32:32 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:54155/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54155/tests/dromaeo/cssquery-mootools.html) 12:32:33 INFO - PROCESS | 1946 | RSS: Main: 369090560 12:32:33 INFO - PROCESS | 1946 | 12:35:35 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:54155/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54155/tests/dromaeo/cssquery-prototype.html) 12:35:36 INFO - PROCESS | 1946 | RSS: Main: 366682112 12:35:36 INFO - PROCESS | 1946 | 12:38:39 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:54155/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54155/tests/dromaeo/cssquery-yui.html) 12:38:40 INFO - PROCESS | 1946 | RSS: Main: 338096128 12:38:40 INFO - PROCESS | 1946 | 12:41:43 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:54155/tests/dromaeo/cssquery-yui.html (next: http://localhost:54155/tests/dromaeo/cssquery-dojo.html) 12:41:43 INFO - PROCESS | 1946 | RSS: Main: 393773056 12:41:43 INFO - PROCESS | 1946 | 12:41:43 INFO - PROCESS | 1946 | __start_tp_report 12:41:43 INFO - PROCESS | 1946 | _x_x_mozilla_page_load 12:41:43 INFO - PROCESS | 1946 | _x_x_mozilla_page_load_details 12:41:43 INFO - PROCESS | 1946 | |i|pagename|runs| 12:41:43 INFO - PROCESS | 1946 | |0;dojo.html;3352.698257156856;3368.8147151906637;3369.932601347972;3398.133475963629;3440.243146507769;5663.5186009189265;5707.828765137042;5733.627314224573;5807.709614519273;5821.359650438459;5709.143628455734;5774.797882074128;5777.133429985504;5778;5794.159846822208;5186.092433824094;5213.58291336694;5226.24219488174;5246.947530524703;5301.628885977983;5285.603579731516;5293.761780719858;5311.176767601028;5312.681239125664;5314.963582101492;5193.818216362428;5204.76578553966;5219.060569097561;5238.842834714965;5272.6836389816735;5276.15581506959;5309.5752339812725;5318.734063296858;5339.733013349356;5373.005993891143;5123.897522049539;5126.461721519224;5134.460881607452;5240.266362709223;5240.34495688039;5713.628614140068;5717.628354156966;5720.542356611461;5745.741441635137;5787.768489260425;6316.368363163693;6326.905096423558;6331.683415829236;6334.049892516114;6345.17512723343;6140.662263575501;6232.439080482778;6257.968710156466;6334.3032266450655;6337.017762246813;5836;5853.414658534198;5868.41315868414;5885.117232415087;5890;6288.308286088527;6294.150289710922;6306.211723534559;6313.621182729051;6321.778737744157;6053.7578496860515;6069.969650151721;6123.989541725566;6190;6209.751609935643;6330.3669633036325;6342.207224096988;6345.07996340528;6345.587536810092;6347.492200623939;6311.558190926591;6315.652639104894;6329.113924050636;6331.620102793755;6350.872982540323;6108.694565271719;6192.133101365813;6196.938030619636;6257.0614407838275;6269.49844012487;4917.188663870493;4952.380952380952;4957.206846904478;4967.528084831935;4989.176785830297;4469.217886869785;4473.6868419211;4482.574155455227;4513.526079761644;4524.253498172765;5704.201411802351;5736.4550367715;5776.624519406225;5848.093545500455;5865.589408741433;6276.184096067482;6318.905216421757;6326.114343991845;6332.810015699536;6347.650879201596;5841.532677385799;5851.239338885917;5856.12158176277;5857.711303513221;5924.822255332347;5307.893842123136;5395.73021348931;5398.757055932492;5447.836564903059;5454.400015998237;5131.846044618667;5544.500994910478;6246.843828904313;6337.873242535124;6393.2967373587935;6291.591046581958;6300.527460440577;6310.842228944313;6338.619682819046;6342.334054924351;5286.867828304323;6224.097505861715;6246.9062964054665;6259.62442253448;6342.175517182736;5181.326427564392;6221.657808820558;6237.12680224778;6248.062790581449;6267.2479302484;6242.500599951902;6256.624602523863;6277.30949595535;6278.309385967631;6322.083297921868;5543.916841247304;5621.437856214346;6028.638281703112;6098.390160983866;6149.600275982047;6130.325664176848;6143.170671959407;6154.799814036399;6222.253329600477;6237.594556353823;6054.63672179653;6063.666498342457;6076.696165191811;6085.1480792689945;6125.418085281891;5044.697318160922;6049;6057.394260573908;6132.20254427538;6223.813285601439;6035.969820150871;6084.361142080018;6091.177691011834;6109.725062372293;6115.8776824462375 12:41:43 INFO - PROCESS | 1946 | |1;ext.html;455.9156556037131;460.6430016737028;480.3923037357743;496.85094471658493;531.5295963072681;31080.378392432165;33586;33646.83176584117;33655.326893462174;33725.819596314104;39129.60870391295;40894.18211635765;44431.33352999708;47253.0549389012;47385;9802.754931126743;9882.752931176707;9940.850887236697;9966.103050725433;9967.651132210374;8761.474311541297;8959.865602015974;8962.37263391563;9788.119069283763;9914.652987145451;8997.280217582578;9062.682806101788;11773.175877688565;12001.279923204593;12049.638510844688;11954.043676505902;12074.396280186043;12084.93957530211;12093.879061209363;12101.757964840743;11938.22401543897;12027;12039.518419263219;12047.879521204763;12059.276443413422;28014.859925700446;32011.679883201105;32314.35371292561;32345.838270808494;40805;3052.1100426025796;3460.2387474755515;3596.44255140451;3874.670652994499;4278.187144442546;32110.678893211236;32699;33103.50344744831;33698.83150584231;33803.32393352144;3245.5131730240655;3280.3931272714385;3289.144822346207;3303.9504407433906;3384.4077286474767;539285;539373.5156621076;540879;542270;545431;56886.7155664219;58624.706876466196;64793;65097;65114.674426627564;56669.14996275059;59106;64517.35482645207;65954;68106;16165.272562734704;16680.74978875318;16683.249253783608;16700.58248543796;16714.331426743036;12777.680557986125;12958.222506649443;12967.092303538662;12974.35128243584;12977.091603587658;15606.765898511532;17702.468925932244;17736;17797.288108475776;17866.91066544659;5474.39781624021;6415.326390728999;6471.417572418505;6493.960966245378;6579.111819904348;919.6919032124314;1041.5729550884253;1111.9777604447866;1112.27597224302;1118.6588090632436;27916.441671166463;30095.54856677108;31083.378332433225;31408.05775826683;31479.685203147674;1029.7374169586858;1044.300318786415;1104.9944750276197;1116.9050630696481;1231.2858542045592;14135.15189088658;15495.668431287328;15778.447754328772;15787.526374208792;15900.284487198103;620023;634771;637343;639909;642441;51810.22284665733;52430.213546796826;58805;61006.38993610096;61675.38324616786;1388.965275868091;1422.7012327410946;1433.3120102350929;1433.62725691321;1434.5552878607666;31585;31733.682663173073;31909.521357180125;32195.51706724354;32598.947163189743;53985.73007135096;54589.45410546003;54849.45150548603;54891.725541372034;54985.450145499635;450136.49863502255;626850;628984;633146;881004;26790.59814102829;27036.91852325885;27084.864575676995;27106;27160.456790864468;47717;48045.75977120092;48389.27416088691;49281.75359123181;50467;39813.60186398099;39979;40155.397669034406;40783.796081019405;40915.59084409118;39335.40996885106;39913.600863990985;41020.1795964085;41111.38332924949;41449.79275103605;39607;40138.1972360557;40322.395164071975;40415.39376909407;40531.189376212904;31076;34262.54586903687;35452.29095418129;35680.82159589185;35791.46312805258;26233.475330493664;26253.34366640849;26352.60471092897;26406.207813765617;27034.864825675744 12:41:43 INFO - PROCESS | 1946 | |2;jquery.html;318.26639595731837;358.63956723492885;385.8977370996686;393.64768532163714;409.33483089978785;15546.45587404441;16282.104484253361;20873.68689469659;20933.371998840023;21406;20483.590328193426;20705.689414658755;21211.363659090213;21302.89348553259;21414.03636836342;5140;5988.580799344048;6244.625322480643;6261.843453913643;6900;6926.619035953021;6929;6992.930070699279;6997.790066298019;7006.754763583275;5386.757595908193;6683.732650693966;6781.2540620531945;6863.4852386071;6875.140607424072;6684.2647308795995;6834.21406538247;6834.521583489158;6858.862822743567;6881;5800.941990580082;5964.821055368302;5990.490808281302;5998.880022399571;6002.189704389895;22372.657640541456;25442.491150177077;25667.229983100537;26747.86626066877;26797.92808287666;2230.3309007297908;2250.22367283287;2275.9883231903354;2285.8628482291115;2403.9158629447884;15174.772378414333;15212.46756363522;15926.920365398215;16125.91937040319;16199.190040497928;1823.8023182065579;1877.6856200659593;1886;1934.3229869545687;1974.8123928226796;101702;104897.47551262271;105252.47373763082;105425;105951;10576.894231057744;10819.67540973772;11023.61417350389;11074.944625276821;11205.439728013567;13837.100588461717;13894.305284735723;13908.235047072509;13916.02587818843;13944.2330671812;8508;8714.91285087141;8723.52020638871;8846.823063538694;8850.77873053166;8777.736667899973;8820.470771753715;8846.15961483658;8947.731568052968;8952.776180595538;6646.435053020451;6793.388595026372;6798.490113241527;6815;6818.965905170442;1702.455214331402;1806.0066963170204;1819.6451691920213;1849.6948003579528;1850.12119243361;755.7506023012393;924.7641851328003;987.9209663226925;989.5448093876753;993.145894530703;21430;21819.563608727738;22275;22357.664635030487;22481;968.4334664221368;1001.3391161833155;1012.6455740490917;1022.0648106982177;1057.8889216632297;9726.562304696306;11322.830157547643;11597.014253788353;11658.06735461161;12187.45156467961;466242;519524;549691;558815;563700;103606.96393036123;108092.45953770181;108508.91491085145;108978.45510772396;110752;749.793806703161;935.9517340578459;1007.7833265847878;1033.235405799718;1035.8394448860438;15643.826712996799;19946.800531995075;19958.401247962585;19998.600027999066;20065.398038058298;46457.76771116123;47023.529764703286;47596.2860557085;48102.75948620235;48182.5181748178;63964;64826.05521834353;65087.349126510024;65247.34752652603;65433.67283163554;7854.253845884632;8164.959175204086;8231.506109633441;8281.21328473794;8314.293285070837;12671.936640317108;13013.609591712264;13115.868841311465;13309.866901330863;13366.86633133695;9188.49463279526;10789.759177694667;11411.25826821254;11467.541298347973;11515.654530364103;11208.55165793359;11295.3787541686;11308.83036754433;11339.489722962651;11607.593734219261;11498.482568284615;11640.476178571815;11754;11804.996575291196;11875.990540804129;5868.2664666916635;6911.790436673663;6978.965105174441;7581.393488521016;8431.662733490592;8251.504909705436;11031.558737650405;11238;11240.4379781009;11427.542898283975 12:41:43 INFO - PROCESS | 1946 | |3;mootools.html;1445.522977417452;1475.070705455563;1622.2132265851067;1741.6429631925448;1932.7197556354338;7844;8089.474184178024;8469.364797640172;8930.776730581738;8952.77618059548;7028;7104.857902841953;8616.181462761044;8662;8760.474371537697;2359.9410014749596;2389.3190440724397;2391.473875747333;2392.126873691099;2392.4018995251186;1869.523271565749;2090.8918273315167;2310.8266879983985;2372.5492156490213;2386.844855084414;2360.114956891166;2379.4527258730504;2381.666566680666;2386.85678859269;2388.7133543974746;2282.8744419056943;2341.227394959676;2345.683332750074;2346.9061237550477;2349.7885190332954;2340.1926335414255;2345.3901985483726;2360.433495960957;2364.621660534306;2366.668666386707;8787.560621968876;8815.647374105029;8864.202221800004;8878.934527856634;8899.510526921016;4568.885777855547;5165.199394093921;5352.170413585901;5386.78452861885;5390.730463476811;2958.689337619562;3595.442706380515;3710.666040056381;3711.4432835074685;3722.0694826293425;985.4087547471531;1018.5620183321168;1094.578587243909;1109.6227282723912;1117.9273347232404;11741.882581174164;11759.647210583611;11762.353070581115;11763.058955283557;11856;4658.650601204923;4683.461401938753;4825;4840.661153719277;4850.733209673432;9854.852177217348;9933.453660048766;9945.30382873192;9962.601495940082;9974.551145198484;5354.678719276856;5357.651752636066;5359.624826262124;5364.088105022156;5366.302380690485;4411.2941929291155;4832.685875418087;4920.434150072716;4934.087193869074;4936.062148191832;3709.573399059089;3808;3867.7679339239735;3930.272899513599;3940.822662980173;1089.6894385100209;1212.7028877924965;1281.935903204836;1289.9032572556907;1291.5673249461536;829.2246749289434;843.3680827598616;871.8299931513294;913.3195769151891;971.3394891473865;5903.793367232212;5947.762089516457;6046.213992181077;6091.086337049478;6212.596181248205;927.6428574998539;936.8922573904089;948.5636607160645;984.4585477987079;989.7426669066094;3722.218334149859;3841.7898362015876;4098.877033688994;4193.433886425293;4299.935500967488;10306.433146177033;10471.790564188674;10693.893061069446;10706.411147386969;10796.298240614335;10517.9474102629;10657.25399222047;10682.252242343116;10693.732656683645;10702.357858528514;7729.149793522708;8097.514149151069;8103.7568872932725;8128.309093727099;8347.290480309242;5964.314103877936;6024.969875150597;6214.5960512566535;6281.241252449321;6285.214348206474;9867.11195992364;9890.85163722559;10072.597096116366;10075.798484030132;10080.89919100829;9742.9025709742;10043.799124017625;10302.742431439274;10349.534270957674;10351.223658225814;7562.697492100255;7775.22247775218;8234.341252699889;8405.117462666332;8417.705380311905;7473.439492038227;7893.052833659997;7905.56519391439;7917.802054948673;8000.91999080025;7003.719851205895;7385.150707668687;7543.547387156788;7603.7338693145475;7675.884861727191;7347.302006309392;7396.889046664197;7435.182129965591;7534.585597792174;7599.544027358376;6976.232614412309;7023.613701246481;7089.82275443118;7096.687112884133;7096.78709638691;5056.82301119459;5068.49315068505;5072.797088116434;5149.279100925798;5175.068487672255;5108.61685373591;5124.205748109049;5161.922571161361;5272.841814745564;5310.8141215057285 12:41:43 INFO - PROCESS | 1946 | |4;prototype.html;305.6179775280899;311.4160948222084;316.2599517129916;317.1848349741166;319.72024478581244;4221.514525829534;5530.617345663584;5556.361018482876;5558.638688485245;5644.717764111793;5027.622928280384;5442.156465747806;5475.315585551806;5497.752601132948;5573.470520300565;3234.644189139193;3656.5063716398213;4084.14233011068;4155.4182414462;4187.183499217654;4084.65280451162;4098.221337945795;4136.8965775855695;4144.8134833932545;4185.162967406531;3348.665133486656;3349.346877358916;3603.297357015383;3887.494625698655;4144.917101657965;3480.1995541025453;3597.952303658384;3794.260119276742;3819.3125237457384;4131.97934010331;2918.8248705077763;3036.498977668682;3532.7880327180196;3539.451385035298;3547.929041419183;4357.607815296638;4511.977440112812;5051.444341122474;5121.692698438105;5183.859550898796;3916.7649941003633;4376.540463251376;4458.286674132156;4530.864074077783;4728.338032675429;5706.115552089433;5730.885382292331;5751.367349591542;5760.423957604248;5863.413658634145;3134.8275844828527;3463.341965026637;3916.608339166089;3935.960640393617;3956.5449973252867;5828.7468194338;6068.969655151696;6094.8171554853425;6114.449699527019;6129.969350153266;3973.6225058619384;3986.8604598839484;3989.5611482736876;3992.86024989124;4011.4756392570634;3063.7702172337163;4031.7580945143386;4033.7176397651883;4038.414429907647;4075.4294398784195;4373.606375426227;4591.803823691434;4708.858734237979;4737.3841400618385;4769.451513075971;5192.896142077213;5721.771129154788;5773;5829.764480713245;5831.446012628793;1110.711215084084;1346.501794336102;1373.7910638637934;1376.5870238928283;1389.6248013036422;789.8902042630114;819.2626636027622;828.5732847583499;837.5552172502506;847.4745657692271;4830.66185367028;5087.567556757643;5195.558377537876;5299.364076310867;5469.7573994662;5515.834524964257;6022.247219097613;6027.517798576104;6047.455728984412;6060.090986352083;4537.931931021038;5120.359955005624;5402.513773760302;5421.457854214547;5496.642718223303;3065.5861458703234;3074.5080787074407;3103.8758449662214;3108.393863196655;3123.2816452216116;5122.846314610493;5195.818146364934;6093.482054025369;6127.693615319216;6226.906596401058;5763.827085187451;5783.66975595607;6111.633302001894;6217.906731398942;6284.24589049317;5210.348706411774;5451.400345962021;6011.008183649737;6200.968995155175;6305.464035556937;5541.058020136667;5765.789184271182;6061.635961974745;6095.329513753572;6193.411625895533;5408.945910540844;5941.553898738634;5965.582409231312;5992.910106348321;6068.757249710139;5621.875624875123;5909.23179986599;6254;6290.716917738804;6360.427561519486;3473.2532505511435;3495.877644282438;3659.634036596319;3952.584978577208;3986.4219688145636;4443.600075993176;5947.691507868262;5975.880482390241;5992.310884248192;6004.949133901637;5388;5765.279340082489;5767.942320576741;5770.307563092456;5817.650940943557;5614.438556144353;5641.576881733969;5689.715514224355;5703.372629010722;5752.338481074563;4680.929786053144;5228.529432351107;5333.97333013348;5541.944580554143;5583.69289689071;5054.2923990642075;5262.394824595221;5473.972630136824;5598.832035038955;5760.452756988079;4716.410448693913;5631.211630371833;5660.245323949589;5666.716664166857;5672.319321681424 12:41:43 INFO - PROCESS | 1946 | |5;yui.html;697.4559843322209;792.3027735592677;805.1626308639015;842.2882664148792;849.3035710717211;14972.775408368869;15532.378704851806;16258.593535161626;16263.512094637164;16284.674306513863;14652;14877.107373557594;14891.478798242064;14897.702045959075;14920.62698432537;711.3917600451617;739.7632757517597;864.4208380385126;873.3013589128703;903.259327351572;874.6588830356167;883.2360008592553;885.2431171348469;886.8049029213563;893.008760276093;943.1794338925145;944.6457578408097;945.1115951006033;947.5072962059725;947.6446332625266;734.9669264883094;905.1988989744058;942.1426501883287;944.475815922163;953.0279115302422;696.6899729620321;697.5117417807504;697.7732237022988;698.4272896225098;698.7938558125365;15086.698266034618;15153.469628563058;15155.545333640008;15162.545123646309;15226.923865380713;12756.617301480877;13061.281629510373;14097.506587269396;14319.35562899672;14332.785008224884;12728.74542509154;12842.678933026657;13017.544385946447;13164.736705265937;13401.798973015413;4466.932996005063;4494.988627558806;4583.633309335245;4642.930356044628;4667.22990706533;106547;107748.461257694;108688;109138;112308.438457808;11196.66410007701;11247.94376028131;11293;11702.76594468106;11718.88281117195;18002;21793;21874.671879921814;22058.88970555169;22609.773902261095;8085.272325490734;8100.025293980496;8107.959460202779;8257.504549726915;8306.127856574973;4487.371767952489;4520.2315606347;4523.479799823062;4675.438947326343;4698.3187437821325;3463.5843698755807;3513.4905438711244;3551.982240088783;3573.338932297533;3602.4776407420777;629.527854109418;640.8878446271884;644.1014784375769;658.344946777959;659.7629444791016;612.6477275566479;623.8253289079065;636.4971672378862;640.4332166033001;647.1425361396169;8652.95673521641;8823.426477278956;9022.233110185576;9046.773830654154;9063.802037961661;607.9452849243501;610.5390430225241;629.408356145222;636.8312397214742;638.2341190571333;4127.4634297541725;4324.6240210686865;4402.163588918095;4466.553344665507;4490.39998812064;84029.57985210157;91660.54169729241;93584;146362;153945;80436.19563804404;80442.59778701069;103370.48314758528;119664.80335196537;123213.22327272086;97107;98758.51862222073;103456;108501;110151.44924275488;8566.400351975302;8567.785805354915;8686.565671716515;8710.91289087101;8830.514321712368;60691.08963365641;60885;61826.69086654538;62452.06321905266;62708.686456567426;82877.58561207396;88915;93649;94141;94754.52622736842;11563.152180917668;11784.292942423483;11799.469023894117;11830.940845295718;12286.877131228573;12101.455434505644;12229.877701222875;12253.938730306292;12545.49818007306;12820.23078615286;9871.901280987098;11906.345151016778;11958.517986186262;11979.640610781691;12293.262404255773;9728.221742260745;9770.56032478526;11654.650360489199;12085.81871271914;12287.262764234174;11661.300321980709;11736.237144585575;11740.58907938218;11774.587889423829;11827.349495777815;9344.766380840261;10063.899361006297;10203.38779673222;10249.590016399261;10278.486075696035;9693.515324233902;10092.091711745978;10104.49477526106;10112.494375281354;10112.949435252776 12:41:43 INFO - PROCESS | 1946 | __end_tp_report 12:41:43 INFO - PROCESS | 1946 | __start_cc_report 12:41:43 INFO - PROCESS | 1946 | _x_x_mozilla_cycle_collect,1326 12:41:43 INFO - PROCESS | 1946 | __end_cc_report 12:41:43 INFO - PROCESS | 1946 | __startTimestamp1467488503662__endTimestamp 12:41:43 INFO - PROCESS | 1946 | 12:41:43 INFO - PROCESS | 1946 | ------- Summary: start ------- 12:41:43 INFO - PROCESS | 1946 | Number of tests: 6 12:41:43 INFO - PROCESS | 1946 | 12:41:43 INFO - PROCESS | 1946 | [#0] dojo.html Cycles:165 Average:5765.14 Median:6028.64 stddev:644.04 (10.7%) stddev-sans-first:617.58 12:41:43 INFO - PROCESS | 1946 | Values: 3352.7 3368.8 3369.9 3398.1 3440.2 5663.5 5707.8 5733.6 5807.7 5821.4 5709.1 5774.8 5777.1 5778.0 5794.2 5186.1 5213.6 5226.2 5246.9 5301.6 5285.6 5293.8 5311.2 5312.7 5315.0 5193.8 5204.8 5219.1 5238.8 5272.7 5276.2 5309.6 5318.7 5339.7 5373.0 5123.9 5126.5 5134.5 5240.3 5240.3 5713.6 5717.6 5720.5 5745.7 5787.8 6316.4 6326.9 6331.7 6334.0 6345.2 6140.7 6232.4 6258.0 6334.3 6337.0 5836.0 5853.4 5868.4 5885.1 5890.0 6288.3 6294.2 6306.2 6313.6 6321.8 6053.8 6070.0 6124.0 6190.0 6209.8 6330.4 6342.2 6345.1 6345.6 6347.5 6311.6 6315.7 6329.1 6331.6 6350.9 6108.7 6192.1 6196.9 6257.1 6269.5 4917.2 4952.4 4957.2 4967.5 4989.2 4469.2 4473.7 4482.6 4513.5 4524.3 5704.2 5736.5 5776.6 5848.1 5865.6 6276.2 6318.9 6326.1 6332.8 6347.7 5841.5 5851.2 5856.1 5857.7 5924.8 5307.9 5395.7 5398.8 5447.8 5454.4 5131.8 5544.5 6246.8 6337.9 6393.3 6291.6 6300.5 6310.8 6338.6 6342.3 5286.9 6224.1 6246.9 6259.6 6342.2 5181.3 6221.7 6237.1 6248.1 6267.2 6242.5 6256.6 6277.3 6278.3 6322.1 5543.9 5621.4 6028.6 6098.4 6149.6 6130.3 6143.2 6154.8 6222.3 6237.6 6054.6 6063.7 6076.7 6085.1 6125.4 5044.7 6049.0 6057.4 6132.2 6223.8 6036.0 6084.4 6091.2 6109.7 6115.9 12:41:43 INFO - PROCESS | 1946 | 12:41:43 INFO - PROCESS | 1946 | [#1] ext.html Cycles:180 Average:73726.58 Median:35566.56 stddev:164361.45 (462.1%) stddev-sans-first:164730.47 12:41:43 INFO - PROCESS | 1946 | Values: 455.9 460.6 480.4 496.9 531.5 31080.4 33586.0 33646.8 33655.3 33725.8 39129.6 40894.2 44431.3 47253.1 47385.0 9802.8 9882.8 9940.9 9966.1 9967.7 8761.5 8959.9 8962.4 9788.1 9914.7 8997.3 9062.7 11773.2 12001.3 12049.6 11954.0 12074.4 12084.9 12093.9 12101.8 11938.2 12027.0 12039.5 12047.9 12059.3 28014.9 32011.7 32314.4 32345.8 40805.0 3052.1 3460.2 3596.4 3874.7 4278.2 32110.7 32699.0 33103.5 33698.8 33803.3 3245.5 3280.4 3289.1 3304.0 3384.4 539285.0 539373.5 540879.0 542270.0 545431.0 56886.7 58624.7 64793.0 65097.0 65114.7 56669.1 59106.0 64517.4 65954.0 68106.0 16165.3 16680.7 16683.2 16700.6 16714.3 12777.7 12958.2 12967.1 12974.4 12977.1 15606.8 17702.5 17736.0 17797.3 17866.9 5474.4 6415.3 6471.4 6494.0 6579.1 919.7 1041.6 1112.0 1112.3 1118.7 27916.4 30095.5 31083.4 31408.1 31479.7 1029.7 1044.3 1105.0 1116.9 1231.3 14135.2 15495.7 15778.4 15787.5 15900.3 620023.0 634771.0 637343.0 639909.0 642441.0 51810.2 52430.2 58805.0 61006.4 61675.4 1389.0 1422.7 1433.3 1433.6 1434.6 31585.0 31733.7 31909.5 32195.5 32598.9 53985.7 54589.5 54849.5 54891.7 54985.5 450136.5 626850.0 628984.0 633146.0 881004.0 26790.6 27036.9 27084.9 27106.0 27160.5 47717.0 48045.8 48389.3 49281.8 50467.0 39813.6 39979.0 40155.4 40783.8 40915.6 39335.4 39913.6 41020.2 41111.4 41449.8 39607.0 40138.2 40322.4 40415.4 40531.2 31076.0 34262.5 35452.3 35680.8 35791.5 26233.5 26253.3 26352.6 26406.2 27034.9 12:41:43 INFO - PROCESS | 1946 | 12:41:43 INFO - PROCESS | 1946 | [#2] jquery.html Cycles:180 Average:32058.61 Median:12365.61 stddev:88469.94 (715.5%) stddev-sans-first:88686.02 12:41:43 INFO - PROCESS | 1946 | Values: 318.3 358.6 385.9 393.6 409.3 15546.5 16282.1 20873.7 20933.4 21406.0 20483.6 20705.7 21211.4 21302.9 21414.0 5140.0 5988.6 6244.6 6261.8 6900.0 6926.6 6929.0 6992.9 6997.8 7006.8 5386.8 6683.7 6781.3 6863.5 6875.1 6684.3 6834.2 6834.5 6858.9 6881.0 5800.9 5964.8 5990.5 5998.9 6002.2 22372.7 25442.5 25667.2 26747.9 26797.9 2230.3 2250.2 2276.0 2285.9 2403.9 15174.8 15212.5 15926.9 16125.9 16199.2 1823.8 1877.7 1886.0 1934.3 1974.8 101702.0 104897.5 105252.5 105425.0 105951.0 10576.9 10819.7 11023.6 11074.9 11205.4 13837.1 13894.3 13908.2 13916.0 13944.2 8508.0 8714.9 8723.5 8846.8 8850.8 8777.7 8820.5 8846.2 8947.7 8952.8 6646.4 6793.4 6798.5 6815.0 6819.0 1702.5 1806.0 1819.6 1849.7 1850.1 755.8 924.8 987.9 989.5 993.1 21430.0 21819.6 22275.0 22357.7 22481.0 968.4 1001.3 1012.6 1022.1 1057.9 9726.6 11322.8 11597.0 11658.1 12187.5 466242.0 519524.0 549691.0 558815.0 563700.0 103607.0 108092.5 108508.9 108978.5 110752.0 749.8 936.0 1007.8 1033.2 1035.8 15643.8 19946.8 19958.4 19998.6 20065.4 46457.8 47023.5 47596.3 48102.8 48182.5 63964.0 64826.1 65087.3 65247.3 65433.7 7854.3 8165.0 8231.5 8281.2 8314.3 12671.9 13013.6 13115.9 13309.9 13366.9 9188.5 10789.8 11411.3 11467.5 11515.7 11208.6 11295.4 11308.8 11339.5 11607.6 11498.5 11640.5 11754.0 11805.0 11876.0 5868.3 6911.8 6979.0 7581.4 8431.7 8251.5 11031.6 11238.0 11240.4 11427.5 12:41:43 INFO - PROCESS | 1946 | 12:41:43 INFO - PROCESS | 1946 | [#3] mootools.html Cycles:180 Average:5719.05 Median:5116.41 stddev:3189.83 (62.3%) stddev-sans-first:3182.60 12:41:43 INFO - PROCESS | 1946 | Values: 1445.5 1475.1 1622.2 1741.6 1932.7 7844.0 8089.5 8469.4 8930.8 8952.8 7028.0 7104.9 8616.2 8662.0 8760.5 2359.9 2389.3 2391.5 2392.1 2392.4 1869.5 2090.9 2310.8 2372.5 2386.8 2360.1 2379.5 2381.7 2386.9 2388.7 2282.9 2341.2 2345.7 2346.9 2349.8 2340.2 2345.4 2360.4 2364.6 2366.7 8787.6 8815.6 8864.2 8878.9 8899.5 4568.9 5165.2 5352.2 5386.8 5390.7 2958.7 3595.4 3710.7 3711.4 3722.1 985.4 1018.6 1094.6 1109.6 1117.9 11741.9 11759.6 11762.4 11763.1 11856.0 4658.7 4683.5 4825.0 4840.7 4850.7 9854.9 9933.5 9945.3 9962.6 9974.6 5354.7 5357.7 5359.6 5364.1 5366.3 4411.3 4832.7 4920.4 4934.1 4936.1 3709.6 3808.0 3867.8 3930.3 3940.8 1089.7 1212.7 1281.9 1289.9 1291.6 829.2 843.4 871.8 913.3 971.3 5903.8 5947.8 6046.2 6091.1 6212.6 927.6 936.9 948.6 984.5 989.7 3722.2 3841.8 4098.9 4193.4 4299.9 10306.4 10471.8 10693.9 10706.4 10796.3 10517.9 10657.3 10682.3 10693.7 10702.4 7729.1 8097.5 8103.8 8128.3 8347.3 5964.3 6025.0 6214.6 6281.2 6285.2 9867.1 9890.9 10072.6 10075.8 10080.9 9742.9 10043.8 10302.7 10349.5 10351.2 7562.7 7775.2 8234.3 8405.1 8417.7 7473.4 7893.1 7905.6 7917.8 8000.9 7003.7 7385.2 7543.5 7603.7 7675.9 7347.3 7396.9 7435.2 7534.6 7599.5 6976.2 7023.6 7089.8 7096.7 7096.8 5056.8 5068.5 5072.8 5149.3 5175.1 5108.6 5124.2 5161.9 5272.8 5310.8 12:41:43 INFO - PROCESS | 1946 | 12:41:43 INFO - PROCESS | 1946 | [#4] prototype.html Cycles:180 Average:4629.10 Median:5245.46 stddev:1488.38 (28.4%) stddev-sans-first:1456.75 12:41:43 INFO - PROCESS | 1946 | Values: 305.6 311.4 316.3 317.2 319.7 4221.5 5530.6 5556.4 5558.6 5644.7 5027.6 5442.2 5475.3 5497.8 5573.5 3234.6 3656.5 4084.1 4155.4 4187.2 4084.7 4098.2 4136.9 4144.8 4185.2 3348.7 3349.3 3603.3 3887.5 4144.9 3480.2 3598.0 3794.3 3819.3 4132.0 2918.8 3036.5 3532.8 3539.5 3547.9 4357.6 4512.0 5051.4 5121.7 5183.9 3916.8 4376.5 4458.3 4530.9 4728.3 5706.1 5730.9 5751.4 5760.4 5863.4 3134.8 3463.3 3916.6 3936.0 3956.5 5828.7 6069.0 6094.8 6114.4 6130.0 3973.6 3986.9 3989.6 3992.9 4011.5 3063.8 4031.8 4033.7 4038.4 4075.4 4373.6 4591.8 4708.9 4737.4 4769.5 5192.9 5721.8 5773.0 5829.8 5831.4 1110.7 1346.5 1373.8 1376.6 1389.6 789.9 819.3 828.6 837.6 847.5 4830.7 5087.6 5195.6 5299.4 5469.8 5515.8 6022.2 6027.5 6047.5 6060.1 4537.9 5120.4 5402.5 5421.5 5496.6 3065.6 3074.5 3103.9 3108.4 3123.3 5122.8 5195.8 6093.5 6127.7 6226.9 5763.8 5783.7 6111.6 6217.9 6284.2 5210.3 5451.4 6011.0 6201.0 6305.5 5541.1 5765.8 6061.6 6095.3 6193.4 5408.9 5941.6 5965.6 5992.9 6068.8 5621.9 5909.2 6254.0 6290.7 6360.4 3473.3 3495.9 3659.6 3952.6 3986.4 4443.6 5947.7 5975.9 5992.3 6004.9 5388.0 5765.3 5767.9 5770.3 5817.7 5614.4 5641.6 5689.7 5703.4 5752.3 4680.9 5228.5 5334.0 5541.9 5583.7 5054.3 5262.4 5474.0 5598.8 5760.5 4716.4 5631.2 5660.2 5666.7 5672.3 12:41:43 INFO - PROCESS | 1946 | 12:41:43 INFO - PROCESS | 1946 | [#5] yui.html Cycles:180 Average:22617.43 Median:4507.61 stddev:34907.21 (774.4%) stddev-sans-first:34966.33 12:41:43 INFO - PROCESS | 1946 | Values: 697.5 792.3 805.2 842.3 849.3 14972.8 15532.4 16258.6 16263.5 16284.7 14652.0 14877.1 14891.5 14897.7 14920.6 711.4 739.8 864.4 873.3 903.3 874.7 883.2 885.2 886.8 893.0 943.2 944.6 945.1 947.5 947.6 735.0 905.2 942.1 944.5 953.0 696.7 697.5 697.8 698.4 698.8 15086.7 15153.5 15155.5 15162.5 15226.9 12756.6 13061.3 14097.5 14319.4 14332.8 12728.7 12842.7 13017.5 13164.7 13401.8 4466.9 4495.0 4583.6 4642.9 4667.2 106547.0 107748.5 108688.0 109138.0 112308.4 11196.7 11247.9 11293.0 11702.8 11718.9 18002.0 21793.0 21874.7 22058.9 22609.8 8085.3 8100.0 8108.0 8257.5 8306.1 4487.4 4520.2 4523.5 4675.4 4698.3 3463.6 3513.5 3552.0 3573.3 3602.5 629.5 640.9 644.1 658.3 659.8 612.6 623.8 636.5 640.4 647.1 8653.0 8823.4 9022.2 9046.8 9063.8 607.9 610.5 629.4 636.8 638.2 4127.5 4324.6 4402.2 4466.6 4490.4 84029.6 91660.5 93584.0 146362.0 153945.0 80436.2 80442.6 103370.5 119664.8 123213.2 97107.0 98758.5 103456.0 108501.0 110151.4 8566.4 8567.8 8686.6 8710.9 8830.5 60691.1 60885.0 61826.7 62452.1 62708.7 82877.6 88915.0 93649.0 94141.0 94754.5 11563.2 11784.3 11799.5 11830.9 12286.9 12101.5 12229.9 12253.9 12545.5 12820.2 9871.9 11906.3 11958.5 11979.6 12293.3 9728.2 9770.6 11654.7 12085.8 12287.3 11661.3 11736.2 11740.6 11774.6 11827.3 9344.8 10063.9 10203.4 10249.6 10278.5 9693.5 10092.1 10104.5 10112.5 10112.9 12:41:43 INFO - PROCESS | 1946 | -------- Summary: end -------- 12:41:43 INFO - PROCESS | 1946 | 12:41:44 INFO - TEST-INFO | 1946: exit 0 12:41:44 INFO - TEST-OK | dromaeo_css | took 1116230ms 12:41:44 INFO - TEST-START | kraken 12:41:44 INFO - operating with platform_type : mac_ 12:41:44 INFO - Initialising browser for kraken test... 12:41:44 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/tmpRHqUfW/profile http://localhost:54155/getInfo.html) 12:41:46 INFO - PROCESS | 1950 | __metrics Screen width/height:1600/1200 12:41:46 INFO - PROCESS | 1950 | colorDepth:24 12:41:46 INFO - PROCESS | 1950 | Browser inner width/height: 1024/689 12:41:46 INFO - PROCESS | 1950 | __metrics 12:41:46 INFO - PROCESS | 1950 | 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" 12:41:46 INFO - PROCESS | 1950 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:41:47 INFO - TEST-INFO | 1950: exit 0 12:41:47 INFO - Browser initialized. 12:41:47 INFO - Running cycle 1/1 for kraken test... 12:41:47 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/tmpRHqUfW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:41:59 INFO - PROCESS | 1951 | RSS: Main: 243691520 12:41:59 INFO - PROCESS | 1951 | 12:42:02 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54155/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54155/tests/kraken/driver.html?testName=audio-beat-detection) 12:42:03 INFO - PROCESS | 1951 | RSS: Main: 294678528 12:42:03 INFO - PROCESS | 1951 | 12:42:05 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54155/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54155/tests/kraken/driver.html?testName=audio-dft) 12:42:06 INFO - PROCESS | 1951 | RSS: Main: 304754688 12:42:06 INFO - PROCESS | 1951 | 12:42:09 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54155/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54155/tests/kraken/driver.html?testName=audio-fft) 12:42:10 INFO - PROCESS | 1951 | RSS: Main: 387690496 12:42:10 INFO - PROCESS | 1951 | 12:42:12 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54155/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54155/tests/kraken/driver.html?testName=audio-oscillator) 12:42:12 INFO - PROCESS | 1951 | RSS: Main: 289423360 12:42:12 INFO - PROCESS | 1951 | 12:42:14 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54155/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54155/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:42:15 INFO - PROCESS | 1951 | RSS: Main: 293355520 12:42:15 INFO - PROCESS | 1951 | 12:42:18 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54155/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54155/tests/kraken/driver.html?testName=imaging-darkroom) 12:42:19 INFO - PROCESS | 1951 | RSS: Main: 330457088 12:42:19 INFO - PROCESS | 1951 | 12:42:21 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54155/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54155/tests/kraken/driver.html?testName=imaging-desaturate) 12:42:22 INFO - PROCESS | 1951 | RSS: Main: 330661888 12:42:22 INFO - PROCESS | 1951 | 12:42:25 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54155/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54155/tests/kraken/driver.html?testName=json-parse-financial) 12:42:26 INFO - PROCESS | 1951 | RSS: Main: 328728576 12:42:26 INFO - PROCESS | 1951 | 12:42:27 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54155/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54155/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:42:28 INFO - PROCESS | 1951 | RSS: Main: 312004608 12:42:28 INFO - PROCESS | 1951 | 12:42:30 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54155/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54155/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:42:30 INFO - PROCESS | 1951 | RSS: Main: 318976000 12:42:30 INFO - PROCESS | 1951 | 12:42:32 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54155/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54155/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:42:33 INFO - PROCESS | 1951 | RSS: Main: 317403136 12:42:33 INFO - PROCESS | 1951 | 12:42:36 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54155/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54155/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:42:36 INFO - PROCESS | 1951 | RSS: Main: 325763072 12:42:36 INFO - PROCESS | 1951 | 12:42:39 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54155/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54155/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:42:40 INFO - PROCESS | 1951 | RSS: Main: 328634368 12:42:40 INFO - PROCESS | 1951 | 12:42:42 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54155/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54155/tests/kraken/driver.html?testName=ai-astar) 12:42:42 INFO - PROCESS | 1951 | RSS: Main: 372346880 12:42:42 INFO - PROCESS | 1951 | 12:42:42 INFO - PROCESS | 1951 | __start_tp_report 12:42:42 INFO - PROCESS | 1951 | _x_x_mozilla_page_load 12:42:42 INFO - PROCESS | 1951 | _x_x_mozilla_page_load_details 12:42:42 INFO - PROCESS | 1951 | |i|pagename|runs| 12:42:42 INFO - PROCESS | 1951 | |0;ai-astar;101;114;113;113;112;113;113;113;113;114 12:42:42 INFO - PROCESS | 1951 | |1;audio-beat-detection;144;109;107;159;104;110;128;105;139;107 12:42:42 INFO - PROCESS | 1951 | |2;audio-dft;181;184;185;183;183;181;181;185;185;183 12:42:42 INFO - PROCESS | 1951 | |3;audio-fft;79;74;59;110;58;57;60;69;81;56 12:42:42 INFO - PROCESS | 1951 | |4;audio-oscillator;82;96;93;70;76;68;99;72;67;102 12:42:42 INFO - PROCESS | 1951 | |5;imaging-gaussian-blur;91;84;81;130;134;84;88;85;87;84 12:42:42 INFO - PROCESS | 1951 | |6;imaging-darkroom;93;93;94;98;96;93;94;95;93;93 12:42:42 INFO - PROCESS | 1951 | |7;imaging-desaturate;83;85;84;84;84;83;83;102;135;133 12:42:42 INFO - PROCESS | 1951 | |8;json-parse-financial;57;54;53;55;54;54;54;54;54;53 12:42:42 INFO - PROCESS | 1951 | |9;json-stringify-tinderbox;65;72;71;81;77;73;69;70;70;70 12:42:42 INFO - PROCESS | 1951 | |10;stanford-crypto-aes;81;82;80;83;82;83;82;83;86;84 12:42:42 INFO - PROCESS | 1951 | |11;stanford-crypto-ccm;135;139;136;133;131;137;136;134;166;121 12:42:42 INFO - PROCESS | 1951 | |12;stanford-crypto-pbkdf2;162;162;159;160;161;160;217;174;161;161 12:42:42 INFO - PROCESS | 1951 | |13;stanford-crypto-sha256-iterative;66;64;68;65;66;66;66;66;63;72 12:42:42 INFO - PROCESS | 1951 | __end_tp_report 12:42:42 INFO - PROCESS | 1951 | __start_cc_report 12:42:42 INFO - PROCESS | 1951 | _x_x_mozilla_cycle_collect,2013 12:42:42 INFO - PROCESS | 1951 | __end_cc_report 12:42:42 INFO - PROCESS | 1951 | __startTimestamp1467488562371__endTimestamp 12:42:42 INFO - PROCESS | 1951 | 12:42:42 INFO - PROCESS | 1951 | ------- Summary: start ------- 12:42:42 INFO - PROCESS | 1951 | Number of tests: 14 12:42:42 INFO - PROCESS | 1951 | 12:42:42 INFO - PROCESS | 1951 | [#0] ai-astar Cycles:10 Average:111.90 Median:113.00 stddev:3.87 (3.4%) stddev-sans-first:0.60 12:42:42 INFO - PROCESS | 1951 | Values: 101.0 114.0 113.0 113.0 112.0 113.0 113.0 113.0 113.0 114.0 12:42:42 INFO - PROCESS | 1951 | 12:42:42 INFO - PROCESS | 1951 | [#1] audio-beat-detection Cycles:10 Average:121.20 Median:119.00 stddev:19.85 (16.7%) stddev-sans-first:19.27 12:42:42 INFO - PROCESS | 1951 | Values: 144.0 109.0 107.0 159.0 104.0 110.0 128.0 105.0 139.0 107.0 12:42:42 INFO - PROCESS | 1951 | 12:42:42 INFO - PROCESS | 1951 | [#2] audio-dft Cycles:10 Average:183.10 Median:183.50 stddev:1.66 (0.9%) stddev-sans-first:1.58 12:42:42 INFO - PROCESS | 1951 | Values: 181.0 184.0 185.0 183.0 183.0 181.0 181.0 185.0 185.0 183.0 12:42:42 INFO - PROCESS | 1951 | 12:42:42 INFO - PROCESS | 1951 | [#3] audio-fft Cycles:10 Average:70.30 Median:64.50 stddev:16.83 (26.1%) stddev-sans-first:17.55 12:42:42 INFO - PROCESS | 1951 | Values: 79.0 74.0 59.0 110.0 58.0 57.0 60.0 69.0 81.0 56.0 12:42:42 INFO - PROCESS | 1951 | 12:42:42 INFO - PROCESS | 1951 | [#4] audio-oscillator Cycles:10 Average:82.50 Median:79.00 stddev:13.76 (17.4%) stddev-sans-first:14.60 12:42:42 INFO - PROCESS | 1951 | Values: 82.0 96.0 93.0 70.0 76.0 68.0 99.0 72.0 67.0 102.0 12:42:42 INFO - PROCESS | 1951 | 12:42:42 INFO - PROCESS | 1951 | [#5] imaging-gaussian-blur Cycles:10 Average:94.80 Median:84.50 stddev:19.81 (23.4%) stddev-sans-first:20.97 12:42:42 INFO - PROCESS | 1951 | Values: 91.0 84.0 81.0 130.0 134.0 84.0 88.0 85.0 87.0 84.0 12:42:42 INFO - PROCESS | 1951 | 12:42:42 INFO - PROCESS | 1951 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:94.00 stddev:1.69 (1.8%) stddev-sans-first:1.73 12:42:42 INFO - PROCESS | 1951 | Values: 93.0 93.0 94.0 98.0 96.0 93.0 94.0 95.0 93.0 93.0 12:42:42 INFO - PROCESS | 1951 | 12:42:42 INFO - PROCESS | 1951 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:83.50 stddev:21.04 (25.2%) stddev-sans-first:21.82 12:42:42 INFO - PROCESS | 1951 | Values: 83.0 85.0 84.0 84.0 84.0 83.0 83.0 102.0 135.0 133.0 12:42:42 INFO - PROCESS | 1951 | 12:42:42 INFO - PROCESS | 1951 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.00 stddev:1.14 (2.1%) stddev-sans-first:0.60 12:42:42 INFO - PROCESS | 1951 | Values: 57.0 54.0 53.0 55.0 54.0 54.0 54.0 54.0 54.0 53.0 12:42:42 INFO - PROCESS | 1951 | 12:42:42 INFO - PROCESS | 1951 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:71.50 stddev:4.44 (6.2%) stddev-sans-first:3.97 12:42:42 INFO - PROCESS | 1951 | Values: 65.0 72.0 71.0 81.0 77.0 73.0 69.0 70.0 70.0 70.0 12:42:42 INFO - PROCESS | 1951 | 12:42:42 INFO - PROCESS | 1951 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:83.00 stddev:1.65 (2.0%) stddev-sans-first:1.64 12:42:42 INFO - PROCESS | 1951 | Values: 81.0 82.0 80.0 83.0 82.0 83.0 82.0 83.0 86.0 84.0 12:42:42 INFO - PROCESS | 1951 | 12:42:42 INFO - PROCESS | 1951 | [#11] stanford-crypto-ccm Cycles:10 Average:136.80 Median:136.00 stddev:11.39 (8.4%) stddev-sans-first:12.06 12:42:42 INFO - PROCESS | 1951 | Values: 135.0 139.0 136.0 133.0 131.0 137.0 136.0 134.0 166.0 121.0 12:42:42 INFO - PROCESS | 1951 | 12:42:42 INFO - PROCESS | 1951 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.70 Median:161.50 stddev:17.84 (11.0%) stddev-sans-first:18.80 12:42:42 INFO - PROCESS | 1951 | Values: 162.0 162.0 159.0 160.0 161.0 160.0 217.0 174.0 161.0 161.0 12:42:42 INFO - PROCESS | 1951 | 12:42:42 INFO - PROCESS | 1951 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.00 stddev:2.44 (3.7%) stddev-sans-first:2.59 12:42:42 INFO - PROCESS | 1951 | Values: 66.0 64.0 68.0 65.0 66.0 66.0 66.0 66.0 63.0 72.0 12:42:42 INFO - PROCESS | 1951 | -------- Summary: end -------- 12:42:42 INFO - PROCESS | 1951 | 12:42:43 INFO - TEST-INFO | 1951: exit 0 12:42:43 INFO - TEST-OK | kraken | took 58992ms 12:42:43 INFO - SUITE-END | took 1175s 12:42:43 INFO - Completed test suite (00:19:35) 12:42:43 INFO - javascript benchmark 12:42:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3352.698257156856, 3368.8147151906637, 3369.932601347972, 3398.133475963629, 3440.243146507769, 5663.5186009189265, 5707.828765137042, 5733.627314224573, 5807.709614519273, 5821.359650438459, 5709.143628455734, 5774.797882074128, 5777.133429985504, 5778.0, 5794.159846822208, 5186.092433824094, 5213.58291336694, 5226.24219488174, 5246.947530524703, 5301.628885977983, 5285.603579731516, 5293.761780719858, 5311.176767601028, 5312.681239125664, 5314.963582101492, 5193.818216362428, 5204.76578553966, 5219.060569097561, 5238.842834714965, 5272.6836389816735, 5276.15581506959, 5309.5752339812725, 5318.734063296858, 5339.733013349356, 5373.005993891143, 5123.897522049539, 5126.461721519224, 5134.460881607452, 5240.266362709223, 5240.34495688039, 5713.628614140068, 5717.628354156966, 5720.542356611461, 5745.741441635137, 5787.768489260425, 6316.368363163693, 6326.905096423558, 6331.683415829236, 6334.049892516114, 6345.17512723343, 6140.662263575501, 6232.439080482778, 6257.968710156466, 6334.3032266450655, 6337.017762246813, 5836.0, 5853.414658534198, 5868.41315868414, 5885.117232415087, 5890.0, 6288.308286088527, 6294.150289710922, 6306.211723534559, 6313.621182729051, 6321.778737744157, 6053.7578496860515, 6069.969650151721, 6123.989541725566, 6190.0, 6209.751609935643, 6330.3669633036325, 6342.207224096988, 6345.07996340528, 6345.587536810092, 6347.492200623939, 6311.558190926591, 6315.652639104894, 6329.113924050636, 6331.620102793755, 6350.872982540323, 6108.694565271719, 6192.133101365813, 6196.938030619636, 6257.0614407838275, 6269.49844012487, 4917.188663870493, 4952.380952380952, 4957.206846904478, 4967.528084831935, 4989.176785830297, 4469.217886869785, 4473.6868419211, 4482.574155455227, 4513.526079761644, 4524.253498172765, 5704.201411802351, 5736.4550367715, 5776.624519406225, 5848.093545500455, 5865.589408741433, 6276.184096067482, 6318.905216421757, 6326.114343991845, 6332.810015699536, 6347.650879201596, 5841.532677385799, 5851.239338885917, 5856.12158176277, 5857.711303513221, 5924.822255332347, 5307.893842123136, 5395.73021348931, 5398.757055932492, 5447.836564903059, 5454.400015998237, 5131.846044618667, 5544.500994910478, 6246.843828904313, 6337.873242535124, 6393.2967373587935, 6291.591046581958, 6300.527460440577, 6310.842228944313, 6338.619682819046, 6342.334054924351, 5286.867828304323, 6224.097505861715, 6246.9062964054665, 6259.62442253448, 6342.175517182736, 5181.326427564392, 6221.657808820558, 6237.12680224778, 6248.062790581449, 6267.2479302484, 6242.500599951902, 6256.624602523863, 6277.30949595535, 6278.309385967631, 6322.083297921868, 5543.916841247304, 5621.437856214346, 6028.638281703112, 6098.390160983866, 6149.600275982047, 6130.325664176848, 6143.170671959407, 6154.799814036399, 6222.253329600477, 6237.594556353823, 6054.63672179653, 6063.666498342457, 6076.696165191811, 6085.1480792689945, 6125.418085281891, 5044.697318160922, 6049.0, 6057.394260573908, 6132.20254427538, 6223.813285601439, 6035.969820150871, 6084.361142080018, 6091.177691011834, 6109.725062372293, 6115.8776824462375], "value": 5725.131581468519, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.9156556037131, 460.6430016737028, 480.3923037357743, 496.85094471658493, 531.5295963072681, 31080.378392432165, 33586.0, 33646.83176584117, 33655.326893462174, 33725.819596314104, 39129.60870391295, 40894.18211635765, 44431.33352999708, 47253.0549389012, 47385.0, 9802.754931126743, 9882.752931176707, 9940.850887236697, 9966.103050725433, 9967.651132210374, 8761.474311541297, 8959.865602015974, 8962.37263391563, 9788.119069283763, 9914.652987145451, 8997.280217582578, 9062.682806101788, 11773.175877688565, 12001.279923204593, 12049.638510844688, 11954.043676505902, 12074.396280186043, 12084.93957530211, 12093.879061209363, 12101.757964840743, 11938.22401543897, 12027.0, 12039.518419263219, 12047.879521204763, 12059.276443413422, 28014.859925700446, 32011.679883201105, 32314.35371292561, 32345.838270808494, 40805.0, 3052.1100426025796, 3460.2387474755515, 3596.44255140451, 3874.670652994499, 4278.187144442546, 32110.678893211236, 32699.0, 33103.50344744831, 33698.83150584231, 33803.32393352144, 3245.5131730240655, 3280.3931272714385, 3289.144822346207, 3303.9504407433906, 3384.4077286474767, 539285.0, 539373.5156621076, 540879.0, 542270.0, 545431.0, 56886.7155664219, 58624.706876466196, 64793.0, 65097.0, 65114.674426627564, 56669.14996275059, 59106.0, 64517.35482645207, 65954.0, 68106.0, 16165.272562734704, 16680.74978875318, 16683.249253783608, 16700.58248543796, 16714.331426743036, 12777.680557986125, 12958.222506649443, 12967.092303538662, 12974.35128243584, 12977.091603587658, 15606.765898511532, 17702.468925932244, 17736.0, 17797.288108475776, 17866.91066544659, 5474.39781624021, 6415.326390728999, 6471.417572418505, 6493.960966245378, 6579.111819904348, 919.6919032124314, 1041.5729550884253, 1111.9777604447866, 1112.27597224302, 1118.6588090632436, 27916.441671166463, 30095.54856677108, 31083.378332433225, 31408.05775826683, 31479.685203147674, 1029.7374169586858, 1044.300318786415, 1104.9944750276197, 1116.9050630696481, 1231.2858542045592, 14135.15189088658, 15495.668431287328, 15778.447754328772, 15787.526374208792, 15900.284487198103, 620023.0, 634771.0, 637343.0, 639909.0, 642441.0, 51810.22284665733, 52430.213546796826, 58805.0, 61006.38993610096, 61675.38324616786, 1388.965275868091, 1422.7012327410946, 1433.3120102350929, 1433.62725691321, 1434.5552878607666, 31585.0, 31733.682663173073, 31909.521357180125, 32195.51706724354, 32598.947163189743, 53985.73007135096, 54589.45410546003, 54849.45150548603, 54891.725541372034, 54985.450145499635, 450136.49863502255, 626850.0, 628984.0, 633146.0, 881004.0, 26790.59814102829, 27036.91852325885, 27084.864575676995, 27106.0, 27160.456790864468, 47717.0, 48045.75977120092, 48389.27416088691, 49281.75359123181, 50467.0, 39813.60186398099, 39979.0, 40155.397669034406, 40783.796081019405, 40915.59084409118, 39335.40996885106, 39913.600863990985, 41020.1795964085, 41111.38332924949, 41449.79275103605, 39607.0, 40138.1972360557, 40322.395164071975, 40415.39376909407, 40531.189376212904, 31076.0, 34262.54586903687, 35452.29095418129, 35680.82159589185, 35791.46312805258, 26233.475330493664, 26253.34366640849, 26352.60471092897, 26406.207813765617, 27034.864825675744], "value": 20501.96133425078, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.26639595731837, 358.63956723492885, 385.8977370996686, 393.64768532163714, 409.33483089978785, 15546.45587404441, 16282.104484253361, 20873.68689469659, 20933.371998840023, 21406.0, 20483.590328193426, 20705.689414658755, 21211.363659090213, 21302.89348553259, 21414.03636836342, 5140.0, 5988.580799344048, 6244.625322480643, 6261.843453913643, 6900.0, 6926.619035953021, 6929.0, 6992.930070699279, 6997.790066298019, 7006.754763583275, 5386.757595908193, 6683.732650693966, 6781.2540620531945, 6863.4852386071, 6875.140607424072, 6684.2647308795995, 6834.21406538247, 6834.521583489158, 6858.862822743567, 6881.0, 5800.941990580082, 5964.821055368302, 5990.490808281302, 5998.880022399571, 6002.189704389895, 22372.657640541456, 25442.491150177077, 25667.229983100537, 26747.86626066877, 26797.92808287666, 2230.3309007297908, 2250.22367283287, 2275.9883231903354, 2285.8628482291115, 2403.9158629447884, 15174.772378414333, 15212.46756363522, 15926.920365398215, 16125.91937040319, 16199.190040497928, 1823.8023182065579, 1877.6856200659593, 1886.0, 1934.3229869545687, 1974.8123928226796, 101702.0, 104897.47551262271, 105252.47373763082, 105425.0, 105951.0, 10576.894231057744, 10819.67540973772, 11023.61417350389, 11074.944625276821, 11205.439728013567, 13837.100588461717, 13894.305284735723, 13908.235047072509, 13916.02587818843, 13944.2330671812, 8508.0, 8714.91285087141, 8723.52020638871, 8846.823063538694, 8850.77873053166, 8777.736667899973, 8820.470771753715, 8846.15961483658, 8947.731568052968, 8952.776180595538, 6646.435053020451, 6793.388595026372, 6798.490113241527, 6815.0, 6818.965905170442, 1702.455214331402, 1806.0066963170204, 1819.6451691920213, 1849.6948003579528, 1850.12119243361, 755.7506023012393, 924.7641851328003, 987.9209663226925, 989.5448093876753, 993.145894530703, 21430.0, 21819.563608727738, 22275.0, 22357.664635030487, 22481.0, 968.4334664221368, 1001.3391161833155, 1012.6455740490917, 1022.0648106982177, 1057.8889216632297, 9726.562304696306, 11322.830157547643, 11597.014253788353, 11658.06735461161, 12187.45156467961, 466242.0, 519524.0, 549691.0, 558815.0, 563700.0, 103606.96393036123, 108092.45953770181, 108508.91491085145, 108978.45510772396, 110752.0, 749.793806703161, 935.9517340578459, 1007.7833265847878, 1033.235405799718, 1035.8394448860438, 15643.826712996799, 19946.800531995075, 19958.401247962585, 19998.600027999066, 20065.398038058298, 46457.76771116123, 47023.529764703286, 47596.2860557085, 48102.75948620235, 48182.5181748178, 63964.0, 64826.05521834353, 65087.349126510024, 65247.34752652603, 65433.67283163554, 7854.253845884632, 8164.959175204086, 8231.506109633441, 8281.21328473794, 8314.293285070837, 12671.936640317108, 13013.609591712264, 13115.868841311465, 13309.866901330863, 13366.86633133695, 9188.49463279526, 10789.759177694667, 11411.25826821254, 11467.541298347973, 11515.654530364103, 11208.55165793359, 11295.3787541686, 11308.83036754433, 11339.489722962651, 11607.593734219261, 11498.482568284615, 11640.476178571815, 11754.0, 11804.996575291196, 11875.990540804129, 5868.2664666916635, 6911.790436673663, 6978.965105174441, 7581.393488521016, 8431.662733490592, 8251.504909705436, 11031.558737650405, 11238.0, 11240.4379781009, 11427.542898283975], "value": 9744.672693003598, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1445.522977417452, 1475.070705455563, 1622.2132265851067, 1741.6429631925448, 1932.7197556354338, 7844.0, 8089.474184178024, 8469.364797640172, 8930.776730581738, 8952.77618059548, 7028.0, 7104.857902841953, 8616.181462761044, 8662.0, 8760.474371537697, 2359.9410014749596, 2389.3190440724397, 2391.473875747333, 2392.126873691099, 2392.4018995251186, 1869.523271565749, 2090.8918273315167, 2310.8266879983985, 2372.5492156490213, 2386.844855084414, 2360.114956891166, 2379.4527258730504, 2381.666566680666, 2386.85678859269, 2388.7133543974746, 2282.8744419056943, 2341.227394959676, 2345.683332750074, 2346.9061237550477, 2349.7885190332954, 2340.1926335414255, 2345.3901985483726, 2360.433495960957, 2364.621660534306, 2366.668666386707, 8787.560621968876, 8815.647374105029, 8864.202221800004, 8878.934527856634, 8899.510526921016, 4568.885777855547, 5165.199394093921, 5352.170413585901, 5386.78452861885, 5390.730463476811, 2958.689337619562, 3595.442706380515, 3710.666040056381, 3711.4432835074685, 3722.0694826293425, 985.4087547471531, 1018.5620183321168, 1094.578587243909, 1109.6227282723912, 1117.9273347232404, 11741.882581174164, 11759.647210583611, 11762.353070581115, 11763.058955283557, 11856.0, 4658.650601204923, 4683.461401938753, 4825.0, 4840.661153719277, 4850.733209673432, 9854.852177217348, 9933.453660048766, 9945.30382873192, 9962.601495940082, 9974.551145198484, 5354.678719276856, 5357.651752636066, 5359.624826262124, 5364.088105022156, 5366.302380690485, 4411.2941929291155, 4832.685875418087, 4920.434150072716, 4934.087193869074, 4936.062148191832, 3709.573399059089, 3808.0, 3867.7679339239735, 3930.272899513599, 3940.822662980173, 1089.6894385100209, 1212.7028877924965, 1281.935903204836, 1289.9032572556907, 1291.5673249461536, 829.2246749289434, 843.3680827598616, 871.8299931513294, 913.3195769151891, 971.3394891473865, 5903.793367232212, 5947.762089516457, 6046.213992181077, 6091.086337049478, 6212.596181248205, 927.6428574998539, 936.8922573904089, 948.5636607160645, 984.4585477987079, 989.7426669066094, 3722.218334149859, 3841.7898362015876, 4098.877033688994, 4193.433886425293, 4299.935500967488, 10306.433146177033, 10471.790564188674, 10693.893061069446, 10706.411147386969, 10796.298240614335, 10517.9474102629, 10657.25399222047, 10682.252242343116, 10693.732656683645, 10702.357858528514, 7729.149793522708, 8097.514149151069, 8103.7568872932725, 8128.309093727099, 8347.290480309242, 5964.314103877936, 6024.969875150597, 6214.5960512566535, 6281.241252449321, 6285.214348206474, 9867.11195992364, 9890.85163722559, 10072.597096116366, 10075.798484030132, 10080.89919100829, 9742.9025709742, 10043.799124017625, 10302.742431439274, 10349.534270957674, 10351.223658225814, 7562.697492100255, 7775.22247775218, 8234.341252699889, 8405.117462666332, 8417.705380311905, 7473.439492038227, 7893.052833659997, 7905.56519391439, 7917.802054948673, 8000.91999080025, 7003.719851205895, 7385.150707668687, 7543.547387156788, 7603.7338693145475, 7675.884861727191, 7347.302006309392, 7396.889046664197, 7435.182129965591, 7534.585597792174, 7599.544027358376, 6976.232614412309, 7023.613701246481, 7089.82275443118, 7096.687112884133, 7096.78709638691, 5056.82301119459, 5068.49315068505, 5072.797088116434, 5149.279100925798, 5175.068487672255, 5108.61685373591, 5124.205748109049, 5161.922571161361, 5272.841814745564, 5310.8141215057285], "value": 4596.53575390849, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.6179775280899, 311.4160948222084, 316.2599517129916, 317.1848349741166, 319.72024478581244, 4221.514525829534, 5530.617345663584, 5556.361018482876, 5558.638688485245, 5644.717764111793, 5027.622928280384, 5442.156465747806, 5475.315585551806, 5497.752601132948, 5573.470520300565, 3234.644189139193, 3656.5063716398213, 4084.14233011068, 4155.4182414462, 4187.183499217654, 4084.65280451162, 4098.221337945795, 4136.8965775855695, 4144.8134833932545, 4185.162967406531, 3348.665133486656, 3349.346877358916, 3603.297357015383, 3887.494625698655, 4144.917101657965, 3480.1995541025453, 3597.952303658384, 3794.260119276742, 3819.3125237457384, 4131.97934010331, 2918.8248705077763, 3036.498977668682, 3532.7880327180196, 3539.451385035298, 3547.929041419183, 4357.607815296638, 4511.977440112812, 5051.444341122474, 5121.692698438105, 5183.859550898796, 3916.7649941003633, 4376.540463251376, 4458.286674132156, 4530.864074077783, 4728.338032675429, 5706.115552089433, 5730.885382292331, 5751.367349591542, 5760.423957604248, 5863.413658634145, 3134.8275844828527, 3463.341965026637, 3916.608339166089, 3935.960640393617, 3956.5449973252867, 5828.7468194338, 6068.969655151696, 6094.8171554853425, 6114.449699527019, 6129.969350153266, 3973.6225058619384, 3986.8604598839484, 3989.5611482736876, 3992.86024989124, 4011.4756392570634, 3063.7702172337163, 4031.7580945143386, 4033.7176397651883, 4038.414429907647, 4075.4294398784195, 4373.606375426227, 4591.803823691434, 4708.858734237979, 4737.3841400618385, 4769.451513075971, 5192.896142077213, 5721.771129154788, 5773.0, 5829.764480713245, 5831.446012628793, 1110.711215084084, 1346.501794336102, 1373.7910638637934, 1376.5870238928283, 1389.6248013036422, 789.8902042630114, 819.2626636027622, 828.5732847583499, 837.5552172502506, 847.4745657692271, 4830.66185367028, 5087.567556757643, 5195.558377537876, 5299.364076310867, 5469.7573994662, 5515.834524964257, 6022.247219097613, 6027.517798576104, 6047.455728984412, 6060.090986352083, 4537.931931021038, 5120.359955005624, 5402.513773760302, 5421.457854214547, 5496.642718223303, 3065.5861458703234, 3074.5080787074407, 3103.8758449662214, 3108.393863196655, 3123.2816452216116, 5122.846314610493, 5195.818146364934, 6093.482054025369, 6127.693615319216, 6226.906596401058, 5763.827085187451, 5783.66975595607, 6111.633302001894, 6217.906731398942, 6284.24589049317, 5210.348706411774, 5451.400345962021, 6011.008183649737, 6200.968995155175, 6305.464035556937, 5541.058020136667, 5765.789184271182, 6061.635961974745, 6095.329513753572, 6193.411625895533, 5408.945910540844, 5941.553898738634, 5965.582409231312, 5992.910106348321, 6068.757249710139, 5621.875624875123, 5909.23179986599, 6254.0, 6290.716917738804, 6360.427561519486, 3473.2532505511435, 3495.877644282438, 3659.634036596319, 3952.584978577208, 3986.4219688145636, 4443.600075993176, 5947.691507868262, 5975.880482390241, 5992.310884248192, 6004.949133901637, 5388.0, 5765.279340082489, 5767.942320576741, 5770.307563092456, 5817.650940943557, 5614.438556144353, 5641.576881733969, 5689.715514224355, 5703.372629010722, 5752.338481074563, 4680.929786053144, 5228.529432351107, 5333.97333013348, 5541.944580554143, 5583.69289689071, 5054.2923990642075, 5262.394824595221, 5473.972630136824, 5598.832035038955, 5760.452756988079, 4716.410448693913, 5631.211630371833, 5660.245323949589, 5666.716664166857, 5672.319321681424], "value": 4151.3087993612835, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.4559843322209, 792.3027735592677, 805.1626308639015, 842.2882664148792, 849.3035710717211, 14972.775408368869, 15532.378704851806, 16258.593535161626, 16263.512094637164, 16284.674306513863, 14652.0, 14877.107373557594, 14891.478798242064, 14897.702045959075, 14920.62698432537, 711.3917600451617, 739.7632757517597, 864.4208380385126, 873.3013589128703, 903.259327351572, 874.6588830356167, 883.2360008592553, 885.2431171348469, 886.8049029213563, 893.008760276093, 943.1794338925145, 944.6457578408097, 945.1115951006033, 947.5072962059725, 947.6446332625266, 734.9669264883094, 905.1988989744058, 942.1426501883287, 944.475815922163, 953.0279115302422, 696.6899729620321, 697.5117417807504, 697.7732237022988, 698.4272896225098, 698.7938558125365, 15086.698266034618, 15153.469628563058, 15155.545333640008, 15162.545123646309, 15226.923865380713, 12756.617301480877, 13061.281629510373, 14097.506587269396, 14319.35562899672, 14332.785008224884, 12728.74542509154, 12842.678933026657, 13017.544385946447, 13164.736705265937, 13401.798973015413, 4466.932996005063, 4494.988627558806, 4583.633309335245, 4642.930356044628, 4667.22990706533, 106547.0, 107748.461257694, 108688.0, 109138.0, 112308.438457808, 11196.66410007701, 11247.94376028131, 11293.0, 11702.76594468106, 11718.88281117195, 18002.0, 21793.0, 21874.671879921814, 22058.88970555169, 22609.773902261095, 8085.272325490734, 8100.025293980496, 8107.959460202779, 8257.504549726915, 8306.127856574973, 4487.371767952489, 4520.2315606347, 4523.479799823062, 4675.438947326343, 4698.3187437821325, 3463.5843698755807, 3513.4905438711244, 3551.982240088783, 3573.338932297533, 3602.4776407420777, 629.527854109418, 640.8878446271884, 644.1014784375769, 658.344946777959, 659.7629444791016, 612.6477275566479, 623.8253289079065, 636.4971672378862, 640.4332166033001, 647.1425361396169, 8652.95673521641, 8823.426477278956, 9022.233110185576, 9046.773830654154, 9063.802037961661, 607.9452849243501, 610.5390430225241, 629.408356145222, 636.8312397214742, 638.2341190571333, 4127.4634297541725, 4324.6240210686865, 4402.163588918095, 4466.553344665507, 4490.39998812064, 84029.57985210157, 91660.54169729241, 93584.0, 146362.0, 153945.0, 80436.19563804404, 80442.59778701069, 103370.48314758528, 119664.80335196537, 123213.22327272086, 97107.0, 98758.51862222073, 103456.0, 108501.0, 110151.44924275488, 8566.400351975302, 8567.785805354915, 8686.565671716515, 8710.91289087101, 8830.514321712368, 60691.08963365641, 60885.0, 61826.69086654538, 62452.06321905266, 62708.686456567426, 82877.58561207396, 88915.0, 93649.0, 94141.0, 94754.52622736842, 11563.152180917668, 11784.292942423483, 11799.469023894117, 11830.940845295718, 12286.877131228573, 12101.455434505644, 12229.877701222875, 12253.938730306292, 12545.49818007306, 12820.23078615286, 9871.901280987098, 11906.345151016778, 11958.517986186262, 11979.640610781691, 12293.262404255773, 9728.221742260745, 9770.56032478526, 11654.650360489199, 12085.81871271914, 12287.262764234174, 11661.300321980709, 11736.237144585575, 11740.58907938218, 11774.587889423829, 11827.349495777815, 9344.766380840261, 10063.899361006297, 10203.38779673222, 10249.590016399261, 10278.486075696035, 9693.515324233902, 10092.091711745978, 10104.49477526106, 10112.494375281354, 10112.949435252776], "value": 7575.380725139073, "unit": "score"}], "name": "dromaeo_css", "value": 7408.59347525639, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 114.0, 113.0, 113.0, 112.0, 113.0, 113.0, 113.0, 113.0, 114.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 109.0, 107.0, 159.0, 104.0, 110.0, 128.0, 105.0, 139.0, 107.0], "value": 121.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 184.0, 185.0, 183.0, 183.0, 181.0, 181.0, 185.0, 185.0, 183.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 59.0, 110.0, 58.0, 57.0, 60.0, 69.0, 81.0, 56.0], "value": 70.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 96.0, 93.0, 70.0, 76.0, 68.0, 99.0, 72.0, 67.0, 102.0], "value": 82.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 84.0, 81.0, 130.0, 134.0, 84.0, 88.0, 85.0, 87.0, 84.0], "value": 94.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 98.0, 96.0, 93.0, 94.0, 95.0, 93.0, 93.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 84.0, 84.0, 84.0, 83.0, 83.0, 102.0, 135.0, 133.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 53.0, 55.0, 54.0, 54.0, 54.0, 54.0, 54.0, 53.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 71.0, 81.0, 77.0, 73.0, 69.0, 70.0, 70.0, 70.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 80.0, 83.0, 82.0, 83.0, 82.0, 83.0, 86.0, 84.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 139.0, 136.0, 133.0, 131.0, 137.0, 136.0, 134.0, 166.0, 121.0], "value": 136.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 159.0, 160.0, 161.0, 160.0, 217.0, 174.0, 161.0, 161.0], "value": 167.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 68.0, 65.0, 66.0, 66.0, 66.0, 66.0, 63.0, 72.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1432.9, "alertThreshold": 2.0}]} 12:42:43 INFO - Return code: 0 12:42:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:42:43 INFO - # TBPL SUCCESS # 12:42:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:42:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:42:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:42:43 INFO - 2016-07-02 12:42:43.669 screenresolution[1955:23093] starting screenresolution argv=screenresolution get 12:42:43 INFO - 2016-07-02 12:42:43.674 screenresolution[1955:23093] Display 0: 1600x1200x32@60 12:42:43 INFO - 2016-07-02 12:42:43.680 screenresolution[1956:23096] starting screenresolution argv=screenresolution list 12:42:43 INFO - Available Modes on Display 0 12:42:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:42:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:42:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:42:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:42:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:42:43 INFO - Intel Iris: 12:42:43 INFO - Chipset Model: Intel Iris 12:42:43 INFO - Type: GPU 12:42:43 INFO - Bus: Built-In 12:42:43 INFO - VRAM (Dynamic, Max): 1536 MB 12:42:43 INFO - Vendor: Intel (0x8086) 12:42:43 INFO - Device ID: 0x0a2e 12:42:43 INFO - Revision ID: 0x0009 12:42:43 INFO - Displays: 12:42:43 INFO - Display: 12:42:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:42:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:42:43 INFO - Display Serial Number: 1600x1200 60 12:42:43 INFO - Main Display: Yes 12:42:43 INFO - Mirror: Off 12:42:43 INFO - Online: Yes 12:42:43 INFO - Rotation: Supported 12:42:43 INFO - Return code: 0 12:42:43 INFO - Running post-action listener: _resource_record_post_action 12:42:43 INFO - ##### 12:42:43 INFO - ##### Finished run-tests step (success) 12:42:43 INFO - ##### 12:42:43 INFO - Running post-run listener: _resource_record_post_run 12:42:43 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 659456; Write bytes: 706123264; Read time: 8; Write time: 1995 12:42:43 INFO - TinderboxPrint: CPU usage
53.5% 12:42:43 INFO - TinderboxPrint: I/O read bytes / time
659,456 / 8 12:42:43 INFO - TinderboxPrint: I/O write bytes / time
706,123,264 / 1,995 12:42:43 INFO - TinderboxPrint: CPU idle
2,252.1 (46.5%) 12:42:43 INFO - TinderboxPrint: CPU system
968.1 (20.0%) 12:42:43 INFO - TinderboxPrint: CPU user
1,621.5 (33.5%) 12:42:43 INFO - TinderboxPrint: Swap in / out
406,958,080 / 0 12:42:43 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 400435712; Write bytes: 396492288; Read time: 29514; Write time: 866 12:42:43 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 626688; Write bytes: 293906432; Read time: 6; Write time: 1083 12:42:44 INFO - Running post-run listener: _upload_blobber_files 12:42:44 INFO - Blob upload gear active. 12:42:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:42:44 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:42:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:42:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:42:44 INFO - (blobuploader) - INFO - Open directory for files ... 12:42:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:42:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:42:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:45 INFO - (blobuploader) - INFO - Done attempting. 12:42:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:42:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:42:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:46 INFO - (blobuploader) - INFO - Done attempting. 12:42:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:42:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:42:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:49 INFO - (blobuploader) - INFO - Done attempting. 12:42:49 INFO - (blobuploader) - INFO - Iteration through files over. 12:42:49 INFO - Return code: 0 12:42:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:42:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:42:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18dd1521b8e53affe6eb8e1be6b155e9490984e0133c5f74ea6f64c91e9481e3b7d14eafb1b03471689ec2bef0e53d3685c64f877316687c685dd9b77323e2d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ade94be4bb98b00840fdbe120200aad7efa6c75049b6f0542e122fc419333240ca8f5d1b950f68299ea5acb6e69db8e2b2d703228970220df4e2fc2b7e030785", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ea374d997834b484d82f8fb2ef694aa8fed5c98435cff1aa56cba3414d012c71d990c1d9dfa7fa056718ecc2a29cf90ceaaad20bfbfeb91b8d050f61a556613"} 12:42:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:42:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:42:49 INFO - Contents: 12:42:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18dd1521b8e53affe6eb8e1be6b155e9490984e0133c5f74ea6f64c91e9481e3b7d14eafb1b03471689ec2bef0e53d3685c64f877316687c685dd9b77323e2d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ade94be4bb98b00840fdbe120200aad7efa6c75049b6f0542e122fc419333240ca8f5d1b950f68299ea5acb6e69db8e2b2d703228970220df4e2fc2b7e030785", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ea374d997834b484d82f8fb2ef694aa8fed5c98435cff1aa56cba3414d012c71d990c1d9dfa7fa056718ecc2a29cf90ceaaad20bfbfeb91b8d050f61a556613"} 12:42:49 INFO - Running post-run listener: copy_logs_to_upload_dir 12:42:49 INFO - Copying logs to upload dir... 12:42:49 INFO - mkdir: /builds/slave/test/build/upload/logs 12:42:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1347.354253 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 27 secs) (at 2016-07-02 12:42:49.309793) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-02 12:42:49.313055) ========= 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.dJTJf4n5NB/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.aZRgTIwHDJ/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18dd1521b8e53affe6eb8e1be6b155e9490984e0133c5f74ea6f64c91e9481e3b7d14eafb1b03471689ec2bef0e53d3685c64f877316687c685dd9b77323e2d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ade94be4bb98b00840fdbe120200aad7efa6c75049b6f0542e122fc419333240ca8f5d1b950f68299ea5acb6e69db8e2b2d703228970220df4e2fc2b7e030785", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ea374d997834b484d82f8fb2ef694aa8fed5c98435cff1aa56cba3414d012c71d990c1d9dfa7fa056718ecc2a29cf90ceaaad20bfbfeb91b8d050f61a556613"} build_url:https://queue.taskcluster.net/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011011 build_url: 'https://queue.taskcluster.net/v1/task/GYllZ4cRQtqW8ceq5G3k2g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18dd1521b8e53affe6eb8e1be6b155e9490984e0133c5f74ea6f64c91e9481e3b7d14eafb1b03471689ec2bef0e53d3685c64f877316687c685dd9b77323e2d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ade94be4bb98b00840fdbe120200aad7efa6c75049b6f0542e122fc419333240ca8f5d1b950f68299ea5acb6e69db8e2b2d703228970220df4e2fc2b7e030785", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ea374d997834b484d82f8fb2ef694aa8fed5c98435cff1aa56cba3414d012c71d990c1d9dfa7fa056718ecc2a29cf90ceaaad20bfbfeb91b8d050f61a556613"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-02 12:42:49.345381) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 12:42:49.345713) ========= 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.dJTJf4n5NB/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.aZRgTIwHDJ/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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.005298 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 12:42:49.394615) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-02 12:42:49.394935) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-02 12:42:49.395280) ========= ========= Total master_lag: 0.19 =========