builder: larch_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0351 starttime: 1478699453.1 results: success (0) buildid: 20161109044522 builduid: cd6c6be1202b418fa97516b9c30bde20 revision: 3466e6bc9874e5d7e53649d594ab70af9af2e358 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:53.103270) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:53.103865) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:53.104172) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:53.129155) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:53.129537) ========= 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.LZUrGeOmNy/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.oaaENf5HKI/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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-11-09 05:50:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 166M=0s 2016-11-09 05:50:53 (166 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.512754 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:53.664690) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:53.665071) ========= 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.LZUrGeOmNy/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.oaaENf5HKI/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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.025028 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:53.714121) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:53.714488) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 3466e6bc9874e5d7e53649d594ab70af9af2e358 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 3466e6bc9874e5d7e53649d594ab70af9af2e358 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LZUrGeOmNy/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.oaaENf5HKI/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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-11-09 05:50:53,798 truncating revision to first 12 chars 2016-11-09 05:50:53,799 Setting DEBUG logging. 2016-11-09 05:50:53,799 attempt 1/10 2016-11-09 05:50:53,799 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/3466e6bc9874?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-09 05:50:54,476 unpacking tar archive at: larch-3466e6bc9874/testing/mozharness/ program finished with exit code 0 elapsedTime=0.951929 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:54.689207) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:54.689549) ========= script_repo_revision: 3466e6bc9874e5d7e53649d594ab70af9af2e358 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:54.690003) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:54.690294) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:54.708435) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-11-09 05:50:54.708768) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LZUrGeOmNy/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.oaaENf5HKI/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 05:50:54 INFO - MultiFileLogger online at 20161109 05:50:54 in /builds/slave/test 05:50:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 05:50:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:50:54 INFO - {'append_to_log': False, 05:50:54 INFO - 'base_work_dir': '/builds/slave/test', 05:50:54 INFO - 'blob_upload_branch': 'Larch', 05:50:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:50:54 INFO - 'branch': 'Larch', 05:50:54 INFO - 'buildbot_json_path': 'buildprops.json', 05:50:54 INFO - 'config_files': ('talos/mac_config.py',), 05:50:54 INFO - 'default_actions': ('clobber', 05:50:54 INFO - 'read-buildbot-config', 05:50:54 INFO - 'download-and-extract', 05:50:54 INFO - 'populate-webroot', 05:50:54 INFO - 'create-virtualenv', 05:50:54 INFO - 'install', 05:50:54 INFO - 'run-tests'), 05:50:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:50:54 INFO - 'download_minidump_stackwalk': True, 05:50:54 INFO - 'download_symbols': 'ondemand', 05:50:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:50:54 INFO - 'tooltool.py': '/tools/tooltool.py', 05:50:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:50:54 INFO - '/tools/misc-python/virtualenv.py')}, 05:50:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:50:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:50:54 INFO - 'installer_path': 'installer.exe', 05:50:54 INFO - 'log_level': 'info', 05:50:54 INFO - 'log_name': 'talos', 05:50:54 INFO - 'log_to_console': True, 05:50:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:50:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:50:54 INFO - 'opt_config_files': (), 05:50:54 INFO - 'pip_index': False, 05:50:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:50:54 INFO - 'cmd': ('bash', 05:50:54 INFO - '-c', 05:50:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:50:54 INFO - 'enabled': True, 05:50:54 INFO - 'halt_on_failure': False, 05:50:54 INFO - 'name': 'check_screen_resolution'},), 05:50:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:50:54 INFO - 'cmd': ('bash', 05:50:54 INFO - '-c', 05:50:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:50:54 INFO - 'enabled': True, 05:50:54 INFO - 'halt_on_failure': False, 05:50:54 INFO - 'name': 'check_screen_resolution'},), 05:50:54 INFO - 'run_cmd_checks_enabled': True, 05:50:54 INFO - 'sps_profile': False, 05:50:54 INFO - 'sps_profile_interval': 0, 05:50:54 INFO - 'suite': 'dromaeojs-e10s', 05:50:54 INFO - 'system_bits': '32', 05:50:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:50:54 INFO - 'title': 't-yosemite-r7-0351', 05:50:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:50:54 INFO - 'use_talos_json': True, 05:50:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:50:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:50:54 INFO - 'work_dir': 'build'} 05:50:54 INFO - [mozharness: 2016-11-09 13:50:54.910931Z] Running clobber step. 05:50:54 INFO - Running pre-action listener: _resource_record_pre_action 05:50:54 INFO - Running main action method: clobber 05:50:54 INFO - rmtree: /builds/slave/test/build 05:50:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:50:57 INFO - Running post-action listener: _resource_record_post_action 05:50:57 INFO - [mozharness: 2016-11-09 13:50:57.272175Z] Finished clobber step (success) 05:50:57 INFO - [mozharness: 2016-11-09 13:50:57.272347Z] Running read-buildbot-config step. 05:50:57 INFO - Running pre-action listener: _resource_record_pre_action 05:50:57 INFO - Running main action method: read_buildbot_config 05:50:57 INFO - Using buildbot properties: 05:50:57 INFO - { 05:50:57 INFO - "project": "", 05:50:57 INFO - "product": "firefox", 05:50:57 INFO - "script_repo_revision": "production", 05:50:57 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs-e10s", 05:50:57 INFO - "repository": "", 05:50:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs-e10s", 05:50:57 INFO - "buildid": "20161109044522", 05:50:57 INFO - "pgo_build": "False", 05:50:57 INFO - "basedir": "/builds/slave/test", 05:50:57 INFO - "buildnumber": 0, 05:50:57 INFO - "slavename": "t-yosemite-r7-0351", 05:50:57 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 05:50:57 INFO - "platform": "yosemite_r7", 05:50:57 INFO - "scheduler": "tests-larch-macosx64-talos", 05:50:57 INFO - "branch": "larch", 05:50:57 INFO - "repo_path": "projects/larch", 05:50:57 INFO - "revision": "3466e6bc9874e5d7e53649d594ab70af9af2e358", 05:50:57 INFO - "stage_platform": "macosx64", 05:50:57 INFO - "builduid": "cd6c6be1202b418fa97516b9c30bde20", 05:50:57 INFO - "slavebuilddir": "test" 05:50:57 INFO - } 05:50:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:50:57 INFO - Running post-action listener: _resource_record_post_action 05:50:57 INFO - [mozharness: 2016-11-09 13:50:57.276430Z] Finished read-buildbot-config step (success) 05:50:57 INFO - [mozharness: 2016-11-09 13:50:57.276566Z] Running download-and-extract step. 05:50:57 INFO - Running pre-action listener: _resource_record_pre_action 05:50:57 INFO - Running main action method: download_and_extract 05:50:57 INFO - mkdir: /builds/slave/test/build/tests 05:50: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:50:57 INFO - trying https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:50:57 INFO - Downloading https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:50:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 05:50:58 INFO - Downloaded 1199 bytes. 05:50:58 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:50:58 INFO - Using the following test package requirements: 05:50:58 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:50:58 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:50:58 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:50:58 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:50:58 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:50:58 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:50:58 INFO - u'jsshell-mac.zip'], 05:50:58 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:50:58 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:50:58 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:50:58 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:50:58 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:50:58 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:50:58 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:50:58 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:50:58 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:50:58 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:50:58 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:50:58 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:50:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:50:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:50:58 INFO - Fetch https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:50:59 INFO - Http code: 200 05:50:59 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 05:50:59 INFO - x-amz-cf-id: q1BJqKo2I--S53RXPv2gAYnFQKuSoGMgebT3MqpYSJRELl0UpAjc8w== 05:50:59 INFO - x-amz-version-id: hoO5Uzybr.3iMQqmdTD1G3qyxhDyUMVB 05:50:59 INFO - Expected file size: 16308014 05:50:59 INFO - Obtained file size: 16308014 05:51:06 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:51:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:51:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:51:06 INFO - Fetch https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:51:06 INFO - Http code: 200 05:51:06 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 05:51:06 INFO - x-amz-cf-id: wKx33phPTMqJGlnECk3uSoGQb4l-6Nf82iAyylmVHHErsR41_DSbjA== 05:51:06 INFO - x-amz-version-id: hoO5Uzybr.3iMQqmdTD1G3qyxhDyUMVB 05:51:07 INFO - Expected file size: 16308014 05:51:07 INFO - Obtained file size: 16308014 05:51:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:51:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:51:13 INFO - Fetch https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:51:14 INFO - Http code: 200 05:51:14 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 05:51:14 INFO - x-amz-cf-id: SjbOh9igtTQGdG72_24qyEqzKgZJjiNTZ5P5U8pjbTjQD_XBhBsiXQ== 05:51:14 INFO - x-amz-version-id: AMfURl66fRsLFOojMNgc69GtLS5ml7Xn 05:51:15 INFO - Expected file size: 14409038 05:51:15 INFO - Obtained file size: 14409038 05:51:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:15 INFO - trying https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:51:15 INFO - Downloading https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:51:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 05:51:18 INFO - Downloaded 111576760 bytes. 05:51:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:51:18 INFO - mkdir: /builds/slave/test/properties 05:51:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:51:18 INFO - Writing to file /builds/slave/test/properties/build_url 05:51:18 INFO - Contents: 05:51:18 INFO - build_url:https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:51:19 INFO - Running post-action listener: _resource_record_post_action 05:51:19 INFO - Running post-action listener: set_extra_try_arguments 05:51:19 INFO - [mozharness: 2016-11-09 13:51:19.709816Z] Finished download-and-extract step (success) 05:51:19 INFO - [mozharness: 2016-11-09 13:51:19.709933Z] Running populate-webroot step. 05:51:19 INFO - Running pre-action listener: _resource_record_pre_action 05:51:19 INFO - Running main action method: populate_webroot 05:51:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:51:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:51:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:51:19 INFO - '--tppagecycles', 05:51:19 INFO - '7'], 05:51:19 INFO - 'tests': ['tsvgm']}}, 05:51:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:51:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:51:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:51:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:51:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:51:19 INFO - 'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:51:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:51:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:51:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:51:19 INFO - 'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['damp', 'tps']}, 05:51:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:51:19 INFO - 'tests': ['damp', 'tps']}, 05:51:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['dromaeo_dom']}, 05:51:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:51:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['basic_compositor_video']}, 05:51:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:51:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['a11yr', 05:51:19 INFO - 'ts_paint', 05:51:19 INFO - 'tpaint', 05:51:19 INFO - 'sessionrestore', 05:51:19 INFO - 'sessionrestore_no_auto_restore', 05:51:19 INFO - 'tabpaint']}, 05:51:19 INFO - 'other-e10s': {'tests': ['a11yr', 05:51:19 INFO - 'ts_paint', 05:51:19 INFO - 'tpaint', 05:51:19 INFO - 'sessionrestore', 05:51:19 INFO - 'sessionrestore_no_auto_restore', 05:51:19 INFO - 'tabpaint']}, 05:51:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:51:19 INFO - 'ts_paint', 05:51:19 INFO - 'tpaint', 05:51:19 INFO - 'sessionrestore', 05:51:19 INFO - 'sessionrestore_no_auto_restore', 05:51:19 INFO - 'tabpaint']}, 05:51:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:51:19 INFO - 'ts_paint', 05:51:19 INFO - 'tpaint', 05:51:19 INFO - 'sessionrestore', 05:51:19 INFO - 'sessionrestore_no_auto_restore', 05:51:19 INFO - 'tabpaint']}, 05:51:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['a11yr', 05:51:19 INFO - 'ts_paint', 05:51:19 INFO - 'tpaint', 05:51:19 INFO - 'sessionrestore', 05:51:19 INFO - 'sessionrestore_no_auto_restore', 05:51:19 INFO - 'tabpaint']}, 05:51:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['a11yr', 05:51:19 INFO - 'ts_paint', 05:51:19 INFO - 'tpaint', 05:51:19 INFO - 'sessionrestore', 05:51:19 INFO - 'sessionrestore_no_auto_restore', 05:51:19 INFO - 'tabpaint']}, 05:51:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['tsvgx', 05:51:19 INFO - 'tsvgr_opacity', 05:51:19 INFO - 'tart', 05:51:19 INFO - 'tscrollx', 05:51:19 INFO - 'cart']}, 05:51:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:51:19 INFO - 'tsvgr_opacity', 05:51:19 INFO - 'tart', 05:51:19 INFO - 'tscrollx', 05:51:19 INFO - 'cart']}, 05:51:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:51:19 INFO - 'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['tp5o']}, 05:51:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:51:19 INFO - 'tests': ['tp5o']}, 05:51:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:51:19 INFO - 'talos_options': ['--disable-e10s', 05:51:19 INFO - '--xperf_path', 05:51:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:51:19 INFO - 'tests': ['tp5n']}, 05:51:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:51:19 INFO - 'talos_options': ['--xperf_path', 05:51:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:51:19 INFO - 'tests': ['tp5n']}}, 05:51:19 INFO - 'talos.zip': {'path': '', 05:51:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:51:19 INFO - Running post-action listener: _resource_record_post_action 05:51:19 INFO - [mozharness: 2016-11-09 13:51:19.728318Z] Finished populate-webroot step (success) 05:51:19 INFO - [mozharness: 2016-11-09 13:51:19.728462Z] Running create-virtualenv step. 05:51:19 INFO - Running pre-action listener: _resource_record_pre_action 05:51:19 INFO - Running main action method: create_virtualenv 05:51:19 INFO - Creating virtualenv /builds/slave/test/build/venv 05:51:19 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 05:51:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:51:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:51:19 INFO - Using real prefix '/tools/python27' 05:51:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:51:20 INFO - Installing distribute.............................................................................................................................................................................................done. 05:51:23 INFO - Installing pip.................done. 05:51:23 INFO - Return code: 0 05:51:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:51:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:51:23 INFO - Reading from file tmpfile_stdout 05:51:23 INFO - Output received: 05:51:23 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:51:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:51: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:23 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 0x10c98e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe20b46fcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2d30>, '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 0x10c5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ab20>, '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.LZUrGeOmNy/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.oaaENf5HKI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 05:51:23 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 05:51:23 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 05:51:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LZUrGeOmNy/Render', 05:51:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:23 INFO - 'HOME': '/Users/cltbld', 05:51:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:23 INFO - 'LOGNAME': 'cltbld', 05:51:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:23 INFO - 'MOZ_NO_REMOTE': '1', 05:51:23 INFO - 'NO_EM_RESTART': '1', 05:51:23 INFO - 'PAGER': '/bin/cat', 05:51:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:23 INFO - 'PWD': '/builds/slave/test', 05:51:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:23 INFO - 'SHELL': '/bin/bash', 05:51:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaENf5HKI/Listeners', 05:51:23 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 05:51:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:23 INFO - 'USER': 'cltbld', 05:51:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:23 INFO - 'XPC_FLAGS': '0x0', 05:51:23 INFO - 'XPC_SERVICE_NAME': '0', 05:51:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:24 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 05:51:24 INFO - Cleaning up... 05:51:24 INFO - Return code: 0 05:51:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:51:24 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:24 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:24 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 0x10c98e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe20b46fcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2d30>, '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 0x10c5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ab20>, '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.LZUrGeOmNy/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.oaaENf5HKI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 05:51:24 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 05:51:24 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 05:51:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LZUrGeOmNy/Render', 05:51:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:24 INFO - 'HOME': '/Users/cltbld', 05:51:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:24 INFO - 'LOGNAME': 'cltbld', 05:51:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:24 INFO - 'MOZ_NO_REMOTE': '1', 05:51:24 INFO - 'NO_EM_RESTART': '1', 05:51:24 INFO - 'PAGER': '/bin/cat', 05:51:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:24 INFO - 'PWD': '/builds/slave/test', 05:51:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:24 INFO - 'SHELL': '/bin/bash', 05:51:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaENf5HKI/Listeners', 05:51:24 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 05:51:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:24 INFO - 'USER': 'cltbld', 05:51:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:24 INFO - 'XPC_FLAGS': '0x0', 05:51:24 INFO - 'XPC_SERVICE_NAME': '0', 05:51:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:24 INFO - Downloading/unpacking psutil>=3.1.1 05:51:24 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 05:51:24 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 05:51:24 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 05:51:24 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 05:51:24 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 05:51:24 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 05:51:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:51:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:51:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:51:28 INFO - Installing collected packages: psutil 05:51:28 INFO - Running setup.py install for psutil 05:51:28 INFO - building 'psutil._psutil_osx' extension 05:51:28 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 05:51:28 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 05:51:28 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 05:51:28 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 05:51:28 INFO - building 'psutil._psutil_posix' extension 05:51:28 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 05:51:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:51:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:51:28 INFO - ^ 05:51:29 INFO - 1 warning generated. 05:51:29 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 05:51:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:51:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:51:29 INFO - Successfully installed psutil 05:51:29 INFO - Cleaning up... 05:51:29 INFO - Return code: 0 05:51:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c98e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe20b46fcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2d30>, '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 0x10c5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ab20>, '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.LZUrGeOmNy/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.oaaENf5HKI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 05:51:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:51:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:51:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LZUrGeOmNy/Render', 05:51:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:29 INFO - 'HOME': '/Users/cltbld', 05:51:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:29 INFO - 'LOGNAME': 'cltbld', 05:51:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:29 INFO - 'MOZ_NO_REMOTE': '1', 05:51:29 INFO - 'NO_EM_RESTART': '1', 05:51:29 INFO - 'PAGER': '/bin/cat', 05:51:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:29 INFO - 'PWD': '/builds/slave/test', 05:51:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:29 INFO - 'SHELL': '/bin/bash', 05:51:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaENf5HKI/Listeners', 05:51:29 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 05:51:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:29 INFO - 'USER': 'cltbld', 05:51:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:29 INFO - 'XPC_FLAGS': '0x0', 05:51:29 INFO - 'XPC_SERVICE_NAME': '0', 05:51:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:51: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 05:51: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 05:51: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 05:51: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 05:51: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 05:51: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 05:51:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:51:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:51:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:51:32 INFO - Installing collected packages: mozsystemmonitor 05:51:32 INFO - Running setup.py install for mozsystemmonitor 05:51:32 INFO - Successfully installed mozsystemmonitor 05:51:32 INFO - Cleaning up... 05:51:32 INFO - Return code: 0 05:51:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:51: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c98e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe20b46fcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2d30>, '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 0x10c5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ab20>, '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.LZUrGeOmNy/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.oaaENf5HKI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 05:51: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==2.5.1'] in /builds/slave/test/build 05:51: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==2.5.1 05:51:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LZUrGeOmNy/Render', 05:51:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:32 INFO - 'HOME': '/Users/cltbld', 05:51:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:32 INFO - 'LOGNAME': 'cltbld', 05:51:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:32 INFO - 'MOZ_NO_REMOTE': '1', 05:51:32 INFO - 'NO_EM_RESTART': '1', 05:51:32 INFO - 'PAGER': '/bin/cat', 05:51:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:32 INFO - 'PWD': '/builds/slave/test', 05:51:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:32 INFO - 'SHELL': '/bin/bash', 05:51:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaENf5HKI/Listeners', 05:51:32 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 05:51:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:32 INFO - 'USER': 'cltbld', 05:51:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:32 INFO - 'XPC_FLAGS': '0x0', 05:51:32 INFO - 'XPC_SERVICE_NAME': '0', 05:51:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:32 INFO - Downloading/unpacking jsonschema==2.5.1 05:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:51:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:51:35 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 05:51:35 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 05:51:35 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 05:51:35 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 05:51:35 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 05:51:35 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 05:51:35 INFO - Downloading functools32-3.2.3-2.tar.gz 05:51:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:51:35 INFO - warning: no files found matching '*.txt' 05:51:35 INFO - no previously-included directories found matching 'build' 05:51:35 INFO - no previously-included directories found matching 'dist' 05:51:35 INFO - no previously-included directories found matching '.git*' 05:51:35 INFO - Installing collected packages: jsonschema, functools32 05:51:35 INFO - Running setup.py install for functools32 05:51:35 INFO - warning: no files found matching '*.txt' 05:51:35 INFO - no previously-included directories found matching 'build' 05:51:35 INFO - no previously-included directories found matching 'dist' 05:51:35 INFO - no previously-included directories found matching '.git*' 05:51:35 INFO - Successfully installed jsonschema functools32 05:51:35 INFO - Cleaning up... 05:51:35 INFO - Return code: 0 05:51:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:51:35 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:35 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:35 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c98e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe20b46fcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2d30>, '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 0x10c5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ab20>, '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.LZUrGeOmNy/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.oaaENf5HKI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 05:51:35 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:51:35 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 functools32==3.2.3-2 05:51:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LZUrGeOmNy/Render', 05:51:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:35 INFO - 'HOME': '/Users/cltbld', 05:51:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:35 INFO - 'LOGNAME': 'cltbld', 05:51:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:35 INFO - 'MOZ_NO_REMOTE': '1', 05:51:35 INFO - 'NO_EM_RESTART': '1', 05:51:35 INFO - 'PAGER': '/bin/cat', 05:51:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:35 INFO - 'PWD': '/builds/slave/test', 05:51:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:35 INFO - 'SHELL': '/bin/bash', 05:51:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaENf5HKI/Listeners', 05:51:35 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 05:51:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:35 INFO - 'USER': 'cltbld', 05:51:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:35 INFO - 'XPC_FLAGS': '0x0', 05:51:35 INFO - 'XPC_SERVICE_NAME': '0', 05:51:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:51:36 INFO - Cleaning up... 05:51:36 INFO - Return code: 0 05:51:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:51:36 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:36 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:36 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 0x10c98e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe20b46fcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2d30>, '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 0x10c5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ab20>, '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.LZUrGeOmNy/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.oaaENf5HKI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 05:51:36 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 05:51:36 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 05:51:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LZUrGeOmNy/Render', 05:51:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:36 INFO - 'HOME': '/Users/cltbld', 05:51:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:36 INFO - 'LOGNAME': 'cltbld', 05:51:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:36 INFO - 'MOZ_NO_REMOTE': '1', 05:51:36 INFO - 'NO_EM_RESTART': '1', 05:51:36 INFO - 'PAGER': '/bin/cat', 05:51:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:36 INFO - 'PWD': '/builds/slave/test', 05:51:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:36 INFO - 'SHELL': '/bin/bash', 05:51:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaENf5HKI/Listeners', 05:51:36 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 05:51:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:36 INFO - 'USER': 'cltbld', 05:51:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:36 INFO - 'XPC_FLAGS': '0x0', 05:51:36 INFO - 'XPC_SERVICE_NAME': '0', 05:51:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:36 INFO - Downloading/unpacking blobuploader==1.2.4 05:51:36 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 05:51:36 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 05:51:36 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 05:51:36 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 05:51:36 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 05:51:36 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 05:51:38 INFO - Downloading blobuploader-1.2.4.tar.gz 05:51:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:51:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:51:38 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 05:51:38 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 05:51:38 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 05:51:38 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 05:51:38 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 05:51:38 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 05:51:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:51:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:39 INFO - Downloading docopt-0.6.1.tar.gz 05:51:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:51:40 INFO - Installing collected packages: blobuploader, requests, docopt 05:51:40 INFO - Running setup.py install for blobuploader 05:51:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:51:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:51:40 INFO - Running setup.py install for requests 05:51:40 INFO - Running setup.py install for docopt 05:51:40 INFO - Successfully installed blobuploader requests docopt 05:51:40 INFO - Cleaning up... 05:51:41 INFO - Return code: 0 05:51:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:51:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:41 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 0x10c98e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe20b46fcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2d30>, '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 0x10c5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ab20>, '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.LZUrGeOmNy/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.oaaENf5HKI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 05:51:41 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 05:51:41 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 05:51:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LZUrGeOmNy/Render', 05:51:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:41 INFO - 'HOME': '/Users/cltbld', 05:51:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:41 INFO - 'LOGNAME': 'cltbld', 05:51:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:41 INFO - 'MOZ_NO_REMOTE': '1', 05:51:41 INFO - 'NO_EM_RESTART': '1', 05:51:41 INFO - 'PAGER': '/bin/cat', 05:51:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:41 INFO - 'PWD': '/builds/slave/test', 05:51:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:41 INFO - 'SHELL': '/bin/bash', 05:51:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaENf5HKI/Listeners', 05:51:41 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 05:51:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:41 INFO - 'USER': 'cltbld', 05:51:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:41 INFO - 'XPC_FLAGS': '0x0', 05:51:41 INFO - 'XPC_SERVICE_NAME': '0', 05:51:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:51:41 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-iK9S7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:51:41 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-8Iwy8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:51:41 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-siweIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:51:41 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-ztpBYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:51:41 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-v7_Ac0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:51:41 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-1Swohs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:51:41 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-qFKZlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:51:42 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Y61rDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:51:42 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-8WT4Dm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:51:42 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-kXDRtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:51:42 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-TR9Lmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:51:42 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-pa34Lq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:51:42 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-rBtsFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:51:42 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-LLMQLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:51:42 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-JeGGlj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:51:42 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-WYTf9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:51:42 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-eLO5BX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:51:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:51:43 INFO - Running setup.py install for manifestparser 05:51:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:51:43 INFO - Running setup.py install for mozcrash 05:51:43 INFO - Running setup.py install for mozdebug 05:51:43 INFO - Running setup.py install for mozdevice 05:51:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:51:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:51:43 INFO - Running setup.py install for mozfile 05:51:43 INFO - Running setup.py install for mozhttpd 05:51:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:51:43 INFO - Running setup.py install for mozinfo 05:51:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:51:44 INFO - Running setup.py install for mozInstall 05:51:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:51:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:51:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:51:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:51:44 INFO - Running setup.py install for mozleak 05:51:44 INFO - Running setup.py install for mozlog 05:51:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:51:44 INFO - Running setup.py install for moznetwork 05:51:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:51:44 INFO - Running setup.py install for mozprocess 05:51:44 INFO - Running setup.py install for mozprofile 05:51:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:51:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:51:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:51:45 INFO - Running setup.py install for mozrunner 05:51:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:51:45 INFO - Running setup.py install for mozscreenshot 05:51:45 INFO - Running setup.py install for moztest 05:51:45 INFO - Running setup.py install for mozversion 05:51:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:51:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:51:45 INFO - Cleaning up... 05:51:45 INFO - Return code: 0 05:51:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:51:45 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:45 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:45 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 0x10c98e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe20b46fcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2d30>, '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 0x10c5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ab20>, '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.LZUrGeOmNy/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.oaaENf5HKI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 05:51:45 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 05:51:45 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 05:51:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LZUrGeOmNy/Render', 05:51:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:45 INFO - 'HOME': '/Users/cltbld', 05:51:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:45 INFO - 'LOGNAME': 'cltbld', 05:51:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:45 INFO - 'MOZ_NO_REMOTE': '1', 05:51:45 INFO - 'NO_EM_RESTART': '1', 05:51:45 INFO - 'PAGER': '/bin/cat', 05:51:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:45 INFO - 'PWD': '/builds/slave/test', 05:51:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:45 INFO - 'SHELL': '/bin/bash', 05:51:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaENf5HKI/Listeners', 05:51:45 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 05:51:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:45 INFO - 'USER': 'cltbld', 05:51:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:45 INFO - 'XPC_FLAGS': '0x0', 05:51:45 INFO - 'XPC_SERVICE_NAME': '0', 05:51:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:51:45 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-6bevgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:51:46 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)) 05:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:51:46 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Ssuq31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:51:46 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)) 05:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:51:46 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-XJaKEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:51:46 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)) 05:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:51:46 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-xlOV_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:51:46 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)) 05:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:51:46 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-QmIl44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:51:46 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)) 05:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:51:46 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Qh07GB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:51:46 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)) 05:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:51:46 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-QhXidq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:51:46 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)) 05:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:51:46 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-uchtds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:51:46 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)) 05:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:51:46 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-LPg35G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:51:46 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)) 05:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:51:46 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-2oFIFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:51:47 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-ijhBdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:51:47 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)) 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:51:47 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-sGy81u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:51:47 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Svhovj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:51:47 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)) 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:51:47 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-pR6zbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:51:47 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-pzh00e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:51:47 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)) 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:51:47 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-6fuQH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:51:47 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)) 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:51:47 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-25tGBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:51:47 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)) 05:51:47 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)) 05:51:47 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)) 05:51:47 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)) 05:51:47 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)) 05:51:47 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)) 05:51:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:50 INFO - Downloading blessings-1.6.tar.gz 05:51:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:51:50 INFO - Installing collected packages: blessings 05:51:50 INFO - Running setup.py install for blessings 05:51:50 INFO - Successfully installed blessings 05:51:50 INFO - Cleaning up... 05:51:50 INFO - Return code: 0 05:51:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:51:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:51:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:51:50 INFO - Reading from file tmpfile_stdout 05:51:50 INFO - Current package versions: 05:51:50 INFO - blessings == 1.6 05:51:50 INFO - blobuploader == 1.2.4 05:51:50 INFO - docopt == 0.6.1 05:51:50 INFO - functools32 == 3.2.3-2 05:51:50 INFO - jsonschema == 2.5.1 05:51:50 INFO - manifestparser == 1.1 05:51:50 INFO - mozInstall == 1.12 05:51:50 INFO - mozcrash == 0.17 05:51:50 INFO - mozdebug == 0.1 05:51:50 INFO - mozdevice == 0.48 05:51:50 INFO - mozfile == 1.2 05:51:50 INFO - mozhttpd == 0.7 05:51:50 INFO - mozinfo == 0.9 05:51:50 INFO - mozleak == 0.1 05:51:50 INFO - mozlog == 3.4 05:51:50 INFO - moznetwork == 0.27 05:51:50 INFO - mozprocess == 0.23 05:51:50 INFO - mozprofile == 0.28 05:51:50 INFO - mozrunner == 6.12 05:51:50 INFO - mozscreenshot == 0.1 05:51:50 INFO - mozsystemmonitor == 0.3 05:51:50 INFO - moztest == 0.7 05:51:50 INFO - mozversion == 1.4 05:51:50 INFO - psutil == 3.1.1 05:51:50 INFO - requests == 1.2.3 05:51:50 INFO - wsgiref == 0.1.2 05:51:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:51: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:50 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 0x10c98e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe20b46fcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2d30>, '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 0x10c5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ab20>, '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.LZUrGeOmNy/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.oaaENf5HKI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 05:51:50 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 05:51:50 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 05:51:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LZUrGeOmNy/Render', 05:51:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:50 INFO - 'HOME': '/Users/cltbld', 05:51:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:50 INFO - 'LOGNAME': 'cltbld', 05:51:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:50 INFO - 'MOZ_NO_REMOTE': '1', 05:51:50 INFO - 'NO_EM_RESTART': '1', 05:51:50 INFO - 'PAGER': '/bin/cat', 05:51:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:50 INFO - 'PWD': '/builds/slave/test', 05:51:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:50 INFO - 'SHELL': '/bin/bash', 05:51:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaENf5HKI/Listeners', 05:51:50 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 05:51:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:50 INFO - 'USER': 'cltbld', 05:51:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:50 INFO - 'XPC_FLAGS': '0x0', 05:51:50 INFO - 'XPC_SERVICE_NAME': '0', 05:51:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:51 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)) 05:51:51 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)) 05:51:51 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)) 05:51:51 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)) 05:51:51 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)) 05:51:51 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)) 05:51:51 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)) 05:51:51 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)) 05:51:51 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)) 05:51:51 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)) 05:51:51 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)) 05:51:51 INFO - Cleaning up... 05:51:51 INFO - Return code: 0 05:51:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:51:51 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:51 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:51 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 0x10c98e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe20b46fcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2d30>, '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 0x10c5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ab20>, '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.LZUrGeOmNy/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.oaaENf5HKI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 05:51:51 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 05:51:51 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 05:51:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LZUrGeOmNy/Render', 05:51:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:51 INFO - 'HOME': '/Users/cltbld', 05:51:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:51 INFO - 'LOGNAME': 'cltbld', 05:51:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:51 INFO - 'MOZ_NO_REMOTE': '1', 05:51:51 INFO - 'NO_EM_RESTART': '1', 05:51:51 INFO - 'PAGER': '/bin/cat', 05:51:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:51 INFO - 'PWD': '/builds/slave/test', 05:51:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:51 INFO - 'SHELL': '/bin/bash', 05:51:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaENf5HKI/Listeners', 05:51:51 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 05:51:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:51 INFO - 'USER': 'cltbld', 05:51:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:51 INFO - 'XPC_FLAGS': '0x0', 05:51:51 INFO - 'XPC_SERVICE_NAME': '0', 05:51:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:51:51 INFO - Cleaning up... 05:51:51 INFO - Return code: 0 05:51:51 INFO - Running post-action listener: _resource_record_post_action 05:51:51 INFO - Running post-action listener: _start_resource_monitoring 05:51:51 INFO - Starting resource monitoring. 05:51:51 INFO - [mozharness: 2016-11-09 13:51:51.519519Z] Finished create-virtualenv step (success) 05:51:51 INFO - [mozharness: 2016-11-09 13:51:51.520510Z] Running install step. 05:51:51 INFO - Running pre-action listener: _resource_record_pre_action 05:51:51 INFO - Running main action method: install 05:51:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:51:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:51:51 INFO - Reading from file tmpfile_stdout 05:51:51 INFO - Detecting whether we're running mozinstall >=1.0... 05:51:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:51:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:51:51 INFO - Reading from file tmpfile_stdout 05:51:51 INFO - Output received: 05:51:51 INFO - Usage: mozinstall [options] installer 05:51:51 INFO - Options: 05:51:51 INFO - -h, --help show this help message and exit 05:51:51 INFO - -d DEST, --destination=DEST 05:51:51 INFO - Directory to install application into. [default: 05:51:51 INFO - "/builds/slave/test"] 05:51:51 INFO - --app=APP Application being installed. [default: firefox] 05:51:51 INFO - mkdir: /builds/slave/test/build/application 05:51:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:51:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:52:26 INFO - Reading from file tmpfile_stdout 05:52:26 INFO - Output received: 05:52:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:52:26 INFO - Running post-action listener: _resource_record_post_action 05:52:26 INFO - [mozharness: 2016-11-09 13:52:26.108468Z] Finished install step (success) 05:52:26 INFO - [mozharness: 2016-11-09 13:52:26.108627Z] Running run-tests step. 05:52:26 INFO - Running pre-action listener: _resource_record_pre_action 05:52:26 INFO - Running main action method: run_tests 05:52:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:52:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:52:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:52:26 INFO - 2016-11-09 05:52:26.123 screenresolution[1641:7950] starting screenresolution argv=screenresolution get 05:52:26 INFO - 2016-11-09 05:52:26.125 screenresolution[1641:7950] Display 0: 1600x1200x32@60 05:52:26 INFO - 2016-11-09 05:52:26.131 screenresolution[1642:7952] starting screenresolution argv=screenresolution list 05:52:26 INFO - Available Modes on Display 0 05:52:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:52:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:52:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:52:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:52:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:52:26 INFO - Intel Iris: 05:52:26 INFO - Chipset Model: Intel Iris 05:52:26 INFO - Type: GPU 05:52:26 INFO - Bus: Built-In 05:52:26 INFO - VRAM (Dynamic, Max): 1536 MB 05:52:26 INFO - Vendor: Intel (0x8086) 05:52:26 INFO - Device ID: 0x0a2e 05:52:26 INFO - Revision ID: 0x0009 05:52:26 INFO - Displays: 05:52:26 INFO - Display: 05:52:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:52:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:52:26 INFO - Display Serial Number: 1600x1200 60 05:52:26 INFO - Main Display: Yes 05:52:26 INFO - Mirror: Off 05:52:26 INFO - Online: Yes 05:52:26 INFO - Rotation: Supported 05:52:26 INFO - Return code: 0 05:52:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:52:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:52:26 INFO - Python 2.7.3 05:52:26 INFO - Return code: 0 05:52:26 INFO - grabbing minidump binary from tooltool 05:52:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:26 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 0x10c7a2d30>, '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 0x10c5c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:52:26 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 05:52:26 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 05:52:26 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 05:52:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:52:26 INFO - Return code: 0 05:52:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:52:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:52:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:52:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:52:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:52:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0351', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:52:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0351 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:52:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LZUrGeOmNy/Render', 05:52:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:52:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:52:26 INFO - 'HOME': '/Users/cltbld', 05:52:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:52:26 INFO - 'LOGNAME': 'cltbld', 05:52:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:52:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:52:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:26 INFO - 'MOZ_NO_REMOTE': '1', 05:52:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:52:26 INFO - 'NO_EM_RESTART': '1', 05:52:26 INFO - 'PAGER': '/bin/cat', 05:52:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:52:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:26 INFO - 'PWD': '/builds/slave/test', 05:52:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:52:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:52:26 INFO - 'SHELL': '/bin/bash', 05:52:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaENf5HKI/Listeners', 05:52:26 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 05:52:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:52:26 INFO - 'USER': 'cltbld', 05:52:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:52:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:52:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:26 INFO - 'XPC_FLAGS': '0x0', 05:52:26 INFO - 'XPC_SERVICE_NAME': '0', 05:52:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:52:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0351', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:52:26 INFO - mozversion application_buildid: 20161109044522 05:52:26 INFO - mozversion application_changeset: 3466e6bc9874e5d7e53649d594ab70af9af2e358 05:52:26 INFO - mozversion application_display_name: Nightly 05:52:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:52:26 INFO - mozversion application_name: Firefox 05:52:26 INFO - mozversion application_remotingname: firefox 05:52:26 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 05:52:26 INFO - mozversion application_vendor: Mozilla 05:52:26 INFO - mozversion application_version: 52.0a1 05:52:26 INFO - mozversion platform_buildid: 20161109044522 05:52:26 INFO - mozversion platform_changeset: 3466e6bc9874e5d7e53649d594ab70af9af2e358 05:52:26 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 05:52:26 INFO - mozversion platform_version: 52.0a1 05:52:26 INFO - using testdate: 1478699546 05:52:26 INFO - actual date: 1478699546 05:52:26 INFO - starting webserver on 'localhost:49274' 05:52:26 INFO - SUITE-START | Running 2 tests 05:52:26 INFO - TEST-START | dromaeo_css 05:52:26 INFO - operating with platform_type : mac_ 05:52:26 INFO - Initialising browser for dromaeo_css test... 05:52:26 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpXIw4jd/profile http://localhost:49274/getInfo.html) 05:52:29 INFO - PROCESS | 1652 | 2016-11-09 05:52:29.741 plugin-container[1654:8130] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f4b, name = 'com.apple.tsm.portname' 05:52:29 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:52:29 INFO - PROCESS | 1652 | 2016-11-09 05:52:29.745 plugin-container[1654:8130] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940f, name = 'com.apple.CFPasteboardClient' 05:52:29 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:52:29 INFO - PROCESS | 1652 | 2016-11-09 05:52:29.745 plugin-container[1654:8130] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:52:29 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 05:52:29 INFO - PROCESS | 1652 | colorDepth:24 05:52:29 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 05:52:29 INFO - PROCESS | 1652 | __metrics 05:52:29 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 05:52:30 INFO - TEST-INFO | 1652: exit 0 05:52:30 INFO - Browser initialized. 05:52:30 INFO - Running cycle 1/1 for dromaeo_css test... 05:52:30 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpXIw4jd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:52:32 INFO - PROCESS | 1655 | 2016-11-09 05:52:32.922 plugin-container[1656:8253] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863b, name = 'com.apple.tsm.portname' 05:52:32 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:52:32 INFO - PROCESS | 1655 | 2016-11-09 05:52:32.923 plugin-container[1656:8253] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 05:52:32 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:52:32 INFO - PROCESS | 1655 | 2016-11-09 05:52:32.923 plugin-container[1656:8253] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:52:42 INFO - PROCESS | 1655 | RSS: Main: 242991104 05:52:42 INFO - PROCESS | 1655 | 05:53:32 INFO - PROCESS | 1655 | 1478699612292 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] 05:55:46 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 05:55:47 INFO - PROCESS | 1655 | RSS: Main: 255614976 05:55:47 INFO - PROCESS | 1655 | 05:58:50 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 05:58:50 INFO - PROCESS | 1655 | RSS: Main: 266305536 05:58:50 INFO - PROCESS | 1655 | 06:01:52 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 06:01:53 INFO - PROCESS | 1655 | RSS: Main: 270635008 06:01:53 INFO - PROCESS | 1655 | 06:04:55 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 06:04:56 INFO - PROCESS | 1655 | RSS: Main: 269676544 06:04:56 INFO - PROCESS | 1655 | 06:07:59 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 06:07:59 INFO - PROCESS | 1655 | RSS: Main: 273723392 06:07:59 INFO - PROCESS | 1655 | 06:11:02 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 06:11:02 INFO - PROCESS | 1655 | RSS: Main: 276131840 06:11:02 INFO - PROCESS | 1655 | 06:11:02 INFO - PROCESS | 1655 | __start_tp_report 06:11:02 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 06:11:02 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 06:11:02 INFO - PROCESS | 1655 | |i|pagename|runs| 06:11:02 INFO - PROCESS | 1655 | |0;dojo.html;3437.8624855005796;3453.5855697316324;3478.1652403423186;3478.7738796978188;3484.425069863473;5659.179418984245;5749.223854779603;5842.912356314653;5908.113782932563;5913.85215369616;5731.512821410175;5799.18811366409;5828.766849326033;5885.440883116097;5966.970165149168;5228.92156617651;5241.711705856176;5242.004019236352;5340.946590534103;5379.919301210485;4841.523335382707;5345.1447768357075;5371.838844834641;5382.085045542267;5461.918071228915;5175.171972484422;5231.24146998683;5321.813736519222;5323.893522129574;5331.067063263913;3882.6893848492055;4049.736767110158;4338.349247612852;4661.533846615345;5332.68003919766;5213.504717051874;5223.738813059332;5236.476352364771;5241.86895327616;5280.234366016906;5581.079121944874;5663.9716801415725;5692.487676109128;5731.942680573183;5827.242458480413;5391.757370918318;6119.99004232014;6230.750769969196;6268.65522396268;6277.372262773732;6308.905366419507;6311.905321420182;6314.62112273265;6317.74729010839;6317.842053948642;5875.353711091777;5915.08316210988;5954.2259506264345;5982.072778719218;6003.519718422515;6345.460635845912;6379.936200638027;6389.680515974275;6399.264084630234;6431.5497915146425;6245.937540624535;6257.0614407838275;6274.247090349096;6286.9056964145575;6290.119383286344;4954.801807927714;6166;6196.318404975449;6224.595401298901;6310.274318453345;6270.31026587075;6298.149749783812;6305.243370795534;6312.274088479883;6329.082283068929;6201.596896201732;6298;6298.527610429236;6298.874022519524;6327.651979141099;4899.338589290472;4905.87735306613;4973.104841128559;5060.291559181644;5088;3914.432407300925;4435.733855968652;4466.7766611669285;4532.637389008872;4644.117617652635;5604.97197514018;6061.666608336495;6067;6100.69496525172;6105.175801266772;6200.193974783339;6300.873982520324;6312.810615681537;6321.873562528724;6325.746970121144;5983.222181116427;6007.459328660441;6047.0929360596265;6077.4226448487325;6120.0207966726;5514.944850551524;5522.364928033247;5522.364928033247;5526.06056970316;5528.695921724263;5132.178851383761;6174.073888916699;6348.238211414659;6483.481321494363;6483.610983341015;6180.196574445294;6229.781957631461;6262.091996660368;6262.499000079892;6275.6862156892885;6435.163428754232;6447.0651755494255;6452.451541619015;6453.51598630114;6478.222613286247;6086.908696369469;6109.08363745424;6142.969285153546;6213.285472170758;6220.129181914445;6203.038529028008;6255.124282600531;6266.404691554295;6286.465650419583;6308.495320374451;5976.880462390641;6105.945000794801;6130.887362651087;6150.538709596887;6197.96901015492;5891.381404952418;6022.427869352405;6138.693065346625;6165.876682466236;6173.69131543412;5225.6603320784025;5844.2986841577585;6052.213212282375;6052.909206361996;6109.17526133984;6158.050648819579;6173.135760993554;6180.505559555315;6183.84540386476;6193.163922870535;5735.942640573541;6052.182955300978;6054.515638748978;6077.544184186116;6094.329623741296 06:11:02 INFO - PROCESS | 1655 | |1;ext.html;496.4415033087776;501.6830819099863;516.2746341390348;522.0133946840471;525.4036668381387;39410.60589394109;40688.593114068884;40793.98015049625;40898;40967.59032409679;33950.32099358018;41455;41543;42012;42073;9454.24366050716;9577.281703872202;9628.614855405775;9663.275254355916;9689.515524223796;9526.23790096794;9601.087896649842;9604.231661467065;9607.2314214863;9624.47065411402;9598;9622.807543849154;9639.421634701905;9652;9658.565364558612;9546.761330966712;9562.61749530018;9607.951960240223;9630.988746181654;9638.228941684649;9442.57508412123;9455.763605909839;9504.714858554185;9544.665936692183;9560.856587151198;39714.80142599298;39875.800620997;39955;40083.198336033114;40225;3370.6629337066342;3499.947500787465;3657.9085522861874;3717.442383642448;3912.647861692433;23058;29908.55137172944;30358.241043973856;31108.533371999438;31118.688813112032;2849.9287517812013;3180.9006404665324;3238.5951756030495;3249.983750081258;3366.545516355285;365719;369370;369738.15130924445;370008;370079;57735.42264577385;58042.12936805951;58243.8351232973;58304;58392.708036459546;60629.39370606237;60689;60740.08889866655;60783;60973.78052439015;15560.299786509408;15769.526914192593;15776.368945242291;15786.68426631484;15820.367185312689;11126.443677816238;11808.763824723457;12188.390580470941;12214.206076605169;12231.449584768528;13550.728985420237;15778.289976951064;15942.920285398499;15961.760573591406;15999.920802272902;3191.856366463515;6500.447461965691;6590.6932573599815;6624.403803657598;6663.766768163091;892.9553522323857;957.5834511987308;1030.21703505335;1030.721705139608;1032.6385764982197;27014.32464188411;27638;29911.25221869427;30028.399432011236;31262.531062034086;925.9490728010024;1034.4414016431194;1041.1202533858877;1100.3782862682558;1170.169179882293;15212.391504339923;15420.845791542166;15481.5355539334;15513.612159696098;15898.364065437252;662181;668624;669115;671495;673828;47273;55403.72298138564;57081;57378.7131064342;57436.712816436484;1331.8135461035463;1339.41065930992;1366.6241783509615;1366.8291463567055;1371.9039667223199;28808.855955720086;29398.853005734836;29493.26266843346;30075.24811879633;30644.387112258075;49108.75445622749;49501;49648.50351496439;49694.25458618051;49701.50298496969;585467;602637;603027.9848600728;604854;607610;24965.750342496343;26056.73943260543;26432.471350572498;26472.602910955964;26476.338091547866;42757.57242427536;44114;45149.77425112853;45276.32085518654;45357;36981;37271.6272837279;37356.25287494289;37369.4394584076;38606.8417947462;36977.26045479129;37064.44403334006;37290.62709372872;37861.62138378581;38550.6144938547;36686;37133.62866371302;37142.62857371392;37666.623333767406;38054;32593.674063259063;33628.49557256692;34239.315213696085;34549.309013820086;34569.135771605914;21065.894670526548;25004.749952500242;25252.62121068222;25585.87207063953;25594.360140996625 06:11:02 INFO - PROCESS | 1655 | |2;jquery.html;365.2093218182636;371.3000993127954;378.3907908267689;382.7990305089826;397.7553804410289;18569.071546422674;19714;19737.60524789505;20060.99999999998;20226.29207977721;19307.613847723038;19633.705494417594;19959.501012474695;20132;20226.89886550569;4533.977330113361;5515.117581187001;5781.913271300934;5786.334571524287;5800.506956908668;5571.387147413781;5769.24999750034;5771.50942169916;5781.161731548923;5798.768049278045;5446.455354464561;5670.886582268352;5686.772529098831;5723.227364305807;5729.512991395736;4985.853253751658;5571.13647384654;5584;5609.793894312742;5656.151577263402;4716.38686970695;4743.0988112258565;4763.285507173951;4765.618750499952;4838.467768545457;24160.395990100213;24326.905289262024;24382.780860956882;24395;24596;1997.4207479830977;2111.3032699209225;2129.8615589986603;2307.792298693109;2752.2981639681952;9647.276454265959;9952.90047099527;10335.638252661121;10396.11633011195;12446.439910203972;1822.2164469278205;1825.7170138628535;1844.7095404670144;1880.576870204207;1916.9904150479297;127875;130664.346678266;131775;132587;132960;11443.713907152222;11488.310701357777;11700.70748231284;11714.472848721827;11825.940870295766;13034.696530347079;13342.266175360253;14578.125312481285;14609.92695036518;14658.926705366619;6758.932410675929;8223.876641850258;8355.832883342298;8369.665213391396;8531.360147988926;8174.918250817415;8175.918240817516;8312.54281014538;8326.417150799385;8365.58172091393;3901.785401802871;4335.241332766816;4406.096750166208;4559.635229181657;4667.719936803803;1688.9493315200564;1918.6066856294426;1963.4207908666692;1967.2131147540813;2032.2463062550328;805.9516429014278;892.1658249536704;931.8892708016349;938.5166639180827;979.5494072726483;14802.703945921021;19914.805111693346;20433.67181133251;20718.89640551818;20771;885.968991085309;900.1898291537668;922.372786505183;927.1933417926313;938.380668758616;9911.801763964824;10829.566817327377;10938;10984.780304393867;11138.665840024812;573603.1319843457;580316;584259;590817;596355;130682;136592.9511057335;138131.30934345265;139899;143916.56083439031;956.7560272130562;976.2141476111798;993.2550587059706;1008.047395211518;1010.0455069958819;18952.52618684544;19214.519637009187;19228.807711923262;19239.711404328667;19357.806421935602;38615.8415247543;43138.13723725571;43737.56262437335;56795.71602142128;57373;75135;77590;77614.83577746226;79244.60377698074;83932.58033710037;6960.860782784214;7920.90096124539;7926.207379262028;7954.64204110828;8193.09875913661;10001.54993025301;11911.285322880654;12218.38908054576;12697;12795.488180472677;9460.195883349992;10629.8937010632;10632.202584806017;10865.674029779118;10936.507857146607;10395.272330936761;10634.89365106339;10818.780219016398;11020.50407731638;11077.667669969915;10897.836532451862;10959.99960014573;11055.170862185207;11132.777344452903;11412.828807567726;7164.820879478055;7330;7397.334239918433;7550.433717471102;7651.655675494728;8838.425502342327;10806.621768238074;10844;11138.443077846237;11148.777024459627 06:11:02 INFO - PROCESS | 1655 | |3;mootools.html;1432.9211893345866;1495.2972103111538;1685.1995302231442;1818.654455653425;1820.4720631017005;7555.69777208911;7731.613419329039;8011.839763204733;8472.661093556258;8488.363372747037;7925.207479252086;8217.465864718804;8242.093369729326;8353.122922093184;8358.205970432815;2271.250487339174;2281.623532117203;2308.676785250066;2309.4688221709016;2310.849794763343;2284.3832165315357;2288.8397812153157;2299.8735069571167;2344.3787396339985;2348.58899692554;2287.9084836606517;2288.2220045184717;2311.8034967027825;2316.652502124686;2323;2269.1830940861278;2281.863088214712;2306.042992158269;2307.3885420363617;2310.9768902311016;1931.4205738278602;2043.2950632031925;2075.9065842036994;2297.448612333045;2312.595295823241;8295.751127466187;8362.331013518964;8371.16288371164;8426.747197584082;8449.788755281106;5041.369828771403;5160.716160611165;5202.141646628302;5210.713410762407;5222.190560463135;3197.520371944227;3205.4390481665614;3544.9113772155642;3545.220051588672;3557.3952428087027;1045.9947700261525;1048.2295512798087;1082.6481393547128;1102.1953973599236;1102.5699977008974;10821.080208182313;10871.891281087166;10909.672709818718;10965.61620343292;11002.394868282241;3654.780713157219;4524.253498172765;4621.445426548836;4631.58315751584;4667.066586682677;9423.057694230522;9439.811203776024;9443.291753118543;9471.289653276031;9495.810083798286;5119.206522988943;5138.97430512845;5158.226266060121;5170.663906846118;5185.040767458032;4224.55642157569;4450;4661.930071048937;4683.812647494062;4747.216709243005;3762.097096696773;3792.1088544192094;3843.461915331856;3875.3218186817194;3878.108035151931;1160.1762748448527;1217.903886502155;1263.8925691316158;1274.796032634774;1281.9871801282054;863.2619110660421;875.6803766625263;884.363258453925;914.5244472874201;924.6856068936594;5077.1876499759865;5362.812301569426;5543.750531226105;5645.633033852787;5742.971285143548;429.08176502285136;434.2249085382577;450.5967159392325;450.7858767085608;450.8121100899089;3061.5101583746496;3217.3243618840306;3242.9351412972082;3450.706689931384;3638.927221455609;9674.419534827934;9736.22110231194;10154.035366640157;10161.339512931636;10309.896901031043;10209.285350025428;10295.742606434898;10306;10306.278560500843;10314.948425257975;7779.45543811939;7976.401769867283;7976.601169941479;8019.997500312461;8037.23646253605;4748.857534273978;4957.603391728725;5851.327097383855;5926.436988486087;5998.730057147526;8949.776255593662;9037.457752534869;9278.072192780668;9328.813423731623;9452.810943780949;7523.510971786816;9270.490123043028;9377.202937750368;9404.52977351116;9447.385919915183;7020.5787652741;7253.093363329584;7569.810754731176;7707.807304817425;7862.095858976291;7819.530828150329;7841.294283514511;7939.444238903221;7985.5208687478935;7990.04119505663;6963.825904352431;7010.088688470466;7043.823904402431;7072.115985501812;7078.079849619517;6864.5881247125335;6869.553479023847;6909.585424874524;6965.33829286217;7019.964900175466;6495.382938620824;6630.30381809903;6634.170728658918;6696.19645642526;6703.128593283037;4165.312723400605;4303.419038429897;4701.670883038291;4801.4958429365815;4972.7762250699525;4788.521147885183;4830.106430310404;4959.7272150032095;4985.8005679772405;5062.0888240115655 06:11:02 INFO - PROCESS | 1655 | |4;prototype.html;264.33124195784666;308.15565350938436;309.44609149622175;312.84201478253476;314.88349310755024;4566.794494247759;5491.505764481196;5506.311711036121;5543.916841247385;5551;4799.37608110946;5116.437191908887;5391.877716169635;5455.727213639312;5492.313460817398;3364.8317584120696;3938.3698608222617;4078.775667338295;4089.6114869087387;4108.958910410903;3944.9091981249985;4102.938455923164;4139.3997870308785;4148.174803086647;4209.936850947223;3124.875004999797;3722.813859307024;3994.9289757129864;4157.355609880473;4157.833686652545;3828.4158193219464;3994.101327201371;4071.694622903294;4112.231740351647;4186.225548273564;2936.016434494447;3405.9489107663403;3509.2455122148604;3519.8592056317716;3533;4418.6023257906945;4682.531746825324;4874.2444921037295;5160.019596276696;5295.655782374133;3765.1151979284846;4355.912881742347;4638.884027899296;4680.251159814414;4710.11595361858;5696.344920334173;5731.541476681856;5751.424857514257;5772.7113644317615;5810.709464526757;3901.200253947934;3901.882943511699;3903.5901230370687;3907.886252418076;3931.1523583915514;5812.738426770763;5955.612885162451;6057.3639767824625;6058.878822423527;6090.604110732833;3942.48747662802;3964.6035396460125;3970.4838371011583;3979.7612143271494;3988.501437320335;3487.4245749451056;3634.636536346397;3917.745346552465;4036.0918793271867;4062.756234625873;3794.354959656865;4518.796654150571;4668.673192876534;4675.181843177498;4698.647601429906;4479.843205487858;5624.803131890364;5788.652680839163;5790.565707571949;5821.88356232873;1293.683047653312;1298.571471414431;1299.226959958823;1299.7335546213003;1303.2571434282368;716.8458781362003;814.6130587970666;823.6828820903995;871.4335681806749;883.1874675298612;5094.974525127351;5364.946350536445;5492.395836457987;5516.227728118067;5544.417836127229;5305.973470132624;5463.262459567987;5702.889930725658;5961.880762384728;6019.337872833985;4444.822207111744;4714.599259062949;4839.201531747292;5520.447955204448;5587.972060139673;2483.913063042821;2870.512012957802;2962.511185654386;2983.925401864971;3037.16477968556;5460.225426736247;5548.4174161713245;5574.888502229933;5945.473173901075;5965.3139888912465;5143.405544281285;5306.787728490817;5612.102063669793;5639.945483831;5781;5802.303723553201;5873.442023007893;5920.348761636216;5968.7015649217365;6043.2748070231855;4592.7014744041535;4686.507916668837;5057.898842023065;5152.3044389007;5580.935054030035;5140.46025167367;5289.761960711854;5955.464008239279;5988.850278743066;5998.730057147526;5590.413006634408;6202.751889924352;6260.248770147429;6262.655553944577;6284.842878928063;3291.8313998530175;3298.356820420091;3767.3783825668456;3968.7420317679257;3981.3430783921485;4971.428285747185;5192.299039629602;5203.609729270395;5373.677579345252;5605.850800585951;5770.711464426846;5794.594378393472;5849.561282903885;5852.244326702057;5855;4989.900201996013;5455.781768729206;5760.395158508464;5799.478046975623;5847.433065829925;4746.57602719176;5178.359036891137;5639.8590035249445;5691.516221121085;5711.400302968129;4569.977150114361;5008.418980428865;5589.385167631639;5750.163583962365;5847.152162936265;5422.62041657096;5641.548676105819;5722.656640601577;5729.742161602655;5752.9712351437975 06:11:02 INFO - PROCESS | 1655 | |5;yui.html;694.517310469224;695.235241234642;739.8705226585346;752.38304590236;776.5418403142147;14190.71618567628;14846.480373186942;14963.625909352271;15001.024933379336;15061.246937653128;13591.796123058162;13683.931580342109;13684.863151368483;13735.793963090537;13762.174269543835;632.3644737039292;651.9445847102978;791.1218547412368;804.9758507244777;808.2160304504621;813.6419975210888;818.8116733151379;821.4003777242616;823.0534884882403;824.4064273722939;873.5413907698477;886.1050339157433;887.6316328723572;889.0664801957951;889.6975028490313;846.1580727176469;854.2653318146389;878.6617152396374;879.1691851200618;880.3133555826465;638.2277444292412;639.4788247578209;647.5175993884586;652.2303681655644;654.5581732330658;14096.647583810383;14275.000749947509;14357.856421435756;14423.206723630195;14846.628834279121;13861.4455421783;13861.653458663513;14082.788758168635;14259.857401425958;14288.285585720672;10871.945640271828;11191.2725672831;11370;11610.70973225676;11693.415329233505;3671.4859919611276;3672.944905826415;3685.907852303687;3701.8519259229597;3702.6667599916136;80865;86481.71037413199;87691.56154219252;91097;94464.99999999932;10741.624043158452;10873.83689244662;10901.890981090246;10903.236773425942;11217.04655104309;21665.45836354072;21803.69177849334;21825.126994920025;21888.343349699215;21937.561248775255;7762.92237077622;7822.569758663214;7854.136045035041;7863.567503787347;7928.365730741527;4222.556631553704;4366.825326986948;4367.366731823868;4368.191884501377;4368.934465983013;3474.7915125092068;3532.6290739472497;3585.964140358615;3599.9280014399565;3607.7474576779373;537.5054949446519;545.1931141909995;613.9877202456015;645.0775391190642;649.3636236488268;588.9252114890343;603.4900509069829;617.8362733875526;643.7231990244146;673.9157605299338;7529.58587277694;8423.4945903246;8731.257843083282;9165.220956218664;9235.722928312027;509.4548832748923;565.3442007271545;633.9492840572835;663.4195079305607;672.3310306245318;3520.4015317396106;3770.4344348347418;4144.585541445892;4164.333706606928;4570.794314255866;75455.62272188604;104452.47773761082;117676;118952;121270.9682257933;102450;104000;104475;106163;109905;71741.20646983867;88823.22353552893;89873.10126898778;89905.10094899098;91843.54078229566;8422.241998219944;8696.260817830302;8697.95651021741;8827.514486703292;8881.866771998297;49278;58280.708596456745;59092.704536477046;60173.39826601853;61242;49660.503394965584;58256.708716456145;68974;72036;72962.90555641775;12657.240565566095;12683.365831708563;12793.488260469478;13171.47314107425;13402.06185567001;13910.441253703762;14197;14198.929005354907;14415.207163605683;14980.400783968955;12907.225566466042;12953.41709623088;13003.284819335027;13077.738445230852;13210.141340812816;12874.485020599446;13036.217826930415;13328.200307981551;13350.799738003743;13761.105528140639;12549.247045177319;12850.36995893752;12917.677058073623;13019.544315948897;13242.470301187845;9901.762279715036;10039.849402259119;10765.946170269099;11227.326360418401;11295.604653837076;7957.075118542551;9402.52987350616;9603.739826863515;9626.566804493674;11221.046211072151 06:11:02 INFO - PROCESS | 1655 | __end_tp_report 06:11:02 INFO - PROCESS | 1655 | __start_cc_report 06:11:02 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,385 06:11:02 INFO - PROCESS | 1655 | __end_cc_report 06:11:02 INFO - PROCESS | 1655 | __startTimestamp1478700662456__endTimestamp 06:11:02 INFO - PROCESS | 1655 | 06:11:02 INFO - PROCESS | 1655 | ------- Summary: start ------- 06:11:02 INFO - PROCESS | 1655 | Number of tests: 6 06:11:02 INFO - PROCESS | 1655 | 06:11:02 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5775.63 Median:6061.67 stddev:688.05 (11.4%) stddev-sans-first:665.27 06:11:02 INFO - PROCESS | 1655 | Values: 3437.9 3453.6 3478.2 3478.8 3484.4 5659.2 5749.2 5842.9 5908.1 5913.9 5731.5 5799.2 5828.8 5885.4 5967.0 5228.9 5241.7 5242.0 5340.9 5379.9 4841.5 5345.1 5371.8 5382.1 5461.9 5175.2 5231.2 5321.8 5323.9 5331.1 3882.7 4049.7 4338.3 4661.5 5332.7 5213.5 5223.7 5236.5 5241.9 5280.2 5581.1 5664.0 5692.5 5731.9 5827.2 5391.8 6120.0 6230.8 6268.7 6277.4 6308.9 6311.9 6314.6 6317.7 6317.8 5875.4 5915.1 5954.2 5982.1 6003.5 6345.5 6379.9 6389.7 6399.3 6431.5 6245.9 6257.1 6274.2 6286.9 6290.1 4954.8 6166.0 6196.3 6224.6 6310.3 6270.3 6298.1 6305.2 6312.3 6329.1 6201.6 6298.0 6298.5 6298.9 6327.7 4899.3 4905.9 4973.1 5060.3 5088.0 3914.4 4435.7 4466.8 4532.6 4644.1 5605.0 6061.7 6067.0 6100.7 6105.2 6200.2 6300.9 6312.8 6321.9 6325.7 5983.2 6007.5 6047.1 6077.4 6120.0 5514.9 5522.4 5522.4 5526.1 5528.7 5132.2 6174.1 6348.2 6483.5 6483.6 6180.2 6229.8 6262.1 6262.5 6275.7 6435.2 6447.1 6452.5 6453.5 6478.2 6086.9 6109.1 6143.0 6213.3 6220.1 6203.0 6255.1 6266.4 6286.5 6308.5 5976.9 6105.9 6130.9 6150.5 6198.0 5891.4 6022.4 6138.7 6165.9 6173.7 5225.7 5844.3 6052.2 6052.9 6109.2 6158.1 6173.1 6180.5 6183.8 6193.2 5735.9 6052.2 6054.5 6077.5 6094.3 06:11:02 INFO - PROCESS | 1655 | 06:11:02 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:67649.98 Median:38302.31 stddev:150418.86 (392.7%) stddev-sans-first:150756.33 06:11:02 INFO - PROCESS | 1655 | Values: 496.4 501.7 516.3 522.0 525.4 39410.6 40688.6 40794.0 40898.0 40967.6 33950.3 41455.0 41543.0 42012.0 42073.0 9454.2 9577.3 9628.6 9663.3 9689.5 9526.2 9601.1 9604.2 9607.2 9624.5 9598.0 9622.8 9639.4 9652.0 9658.6 9546.8 9562.6 9608.0 9631.0 9638.2 9442.6 9455.8 9504.7 9544.7 9560.9 39714.8 39875.8 39955.0 40083.2 40225.0 3370.7 3499.9 3657.9 3717.4 3912.6 23058.0 29908.6 30358.2 31108.5 31118.7 2849.9 3180.9 3238.6 3250.0 3366.5 365719.0 369370.0 369738.2 370008.0 370079.0 57735.4 58042.1 58243.8 58304.0 58392.7 60629.4 60689.0 60740.1 60783.0 60973.8 15560.3 15769.5 15776.4 15786.7 15820.4 11126.4 11808.8 12188.4 12214.2 12231.4 13550.7 15778.3 15942.9 15961.8 15999.9 3191.9 6500.4 6590.7 6624.4 6663.8 893.0 957.6 1030.2 1030.7 1032.6 27014.3 27638.0 29911.3 30028.4 31262.5 925.9 1034.4 1041.1 1100.4 1170.2 15212.4 15420.8 15481.5 15513.6 15898.4 662181.0 668624.0 669115.0 671495.0 673828.0 47273.0 55403.7 57081.0 57378.7 57436.7 1331.8 1339.4 1366.6 1366.8 1371.9 28808.9 29398.9 29493.3 30075.2 30644.4 49108.8 49501.0 49648.5 49694.3 49701.5 585467.0 602637.0 603028.0 604854.0 607610.0 24965.8 26056.7 26432.5 26472.6 26476.3 42757.6 44114.0 45149.8 45276.3 45357.0 36981.0 37271.6 37356.3 37369.4 38606.8 36977.3 37064.4 37290.6 37861.6 38550.6 36686.0 37133.6 37142.6 37666.6 38054.0 32593.7 33628.5 34239.3 34549.3 34569.1 21065.9 25004.7 25252.6 25585.9 25594.4 06:11:02 INFO - PROCESS | 1655 | 06:11:02 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:34923.44 Median:13674.15 stddev:98447.85 (720.0%) stddev-sans-first:98689.82 06:11:02 INFO - PROCESS | 1655 | Values: 365.2 371.3 378.4 382.8 397.8 18569.1 19714.0 19737.6 20061.0 20226.3 19307.6 19633.7 19959.5 20132.0 20226.9 4534.0 5515.1 5781.9 5786.3 5800.5 5571.4 5769.2 5771.5 5781.2 5798.8 5446.5 5670.9 5686.8 5723.2 5729.5 4985.9 5571.1 5584.0 5609.8 5656.2 4716.4 4743.1 4763.3 4765.6 4838.5 24160.4 24326.9 24382.8 24395.0 24596.0 1997.4 2111.3 2129.9 2307.8 2752.3 9647.3 9952.9 10335.6 10396.1 12446.4 1822.2 1825.7 1844.7 1880.6 1917.0 127875.0 130664.3 131775.0 132587.0 132960.0 11443.7 11488.3 11700.7 11714.5 11825.9 13034.7 13342.3 14578.1 14609.9 14658.9 6758.9 8223.9 8355.8 8369.7 8531.4 8174.9 8175.9 8312.5 8326.4 8365.6 3901.8 4335.2 4406.1 4559.6 4667.7 1688.9 1918.6 1963.4 1967.2 2032.2 806.0 892.2 931.9 938.5 979.5 14802.7 19914.8 20433.7 20718.9 20771.0 886.0 900.2 922.4 927.2 938.4 9911.8 10829.6 10938.0 10984.8 11138.7 573603.1 580316.0 584259.0 590817.0 596355.0 130682.0 136593.0 138131.3 139899.0 143916.6 956.8 976.2 993.3 1008.0 1010.0 18952.5 19214.5 19228.8 19239.7 19357.8 38615.8 43138.1 43737.6 56795.7 57373.0 75135.0 77590.0 77614.8 79244.6 83932.6 6960.9 7920.9 7926.2 7954.6 8193.1 10001.5 11911.3 12218.4 12697.0 12795.5 9460.2 10629.9 10632.2 10865.7 10936.5 10395.3 10634.9 10818.8 11020.5 11077.7 10897.8 10960.0 11055.2 11132.8 11412.8 7164.8 7330.0 7397.3 7550.4 7651.7 8838.4 10806.6 10844.0 11138.4 11148.8 06:11:02 INFO - PROCESS | 1655 | 06:11:02 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5401.76 Median:4958.67 stddev:3018.48 (60.9%) stddev-sans-first:3012.22 06:11:02 INFO - PROCESS | 1655 | Values: 1432.9 1495.3 1685.2 1818.7 1820.5 7555.7 7731.6 8011.8 8472.7 8488.4 7925.2 8217.5 8242.1 8353.1 8358.2 2271.3 2281.6 2308.7 2309.5 2310.8 2284.4 2288.8 2299.9 2344.4 2348.6 2287.9 2288.2 2311.8 2316.7 2323.0 2269.2 2281.9 2306.0 2307.4 2311.0 1931.4 2043.3 2075.9 2297.4 2312.6 8295.8 8362.3 8371.2 8426.7 8449.8 5041.4 5160.7 5202.1 5210.7 5222.2 3197.5 3205.4 3544.9 3545.2 3557.4 1046.0 1048.2 1082.6 1102.2 1102.6 10821.1 10871.9 10909.7 10965.6 11002.4 3654.8 4524.3 4621.4 4631.6 4667.1 9423.1 9439.8 9443.3 9471.3 9495.8 5119.2 5139.0 5158.2 5170.7 5185.0 4224.6 4450.0 4661.9 4683.8 4747.2 3762.1 3792.1 3843.5 3875.3 3878.1 1160.2 1217.9 1263.9 1274.8 1282.0 863.3 875.7 884.4 914.5 924.7 5077.2 5362.8 5543.8 5645.6 5743.0 429.1 434.2 450.6 450.8 450.8 3061.5 3217.3 3242.9 3450.7 3638.9 9674.4 9736.2 10154.0 10161.3 10309.9 10209.3 10295.7 10306.0 10306.3 10314.9 7779.5 7976.4 7976.6 8020.0 8037.2 4748.9 4957.6 5851.3 5926.4 5998.7 8949.8 9037.5 9278.1 9328.8 9452.8 7523.5 9270.5 9377.2 9404.5 9447.4 7020.6 7253.1 7569.8 7707.8 7862.1 7819.5 7841.3 7939.4 7985.5 7990.0 6963.8 7010.1 7043.8 7072.1 7078.1 6864.6 6869.6 6909.6 6965.3 7020.0 6495.4 6630.3 6634.2 6696.2 6703.1 4165.3 4303.4 4701.7 4801.5 4972.8 4788.5 4830.1 4959.7 4985.8 5062.1 06:11:02 INFO - PROCESS | 1655 | 06:11:02 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4573.86 Median:5147.85 stddev:1442.43 (28.0%) stddev-sans-first:1409.74 06:11:02 INFO - PROCESS | 1655 | Values: 264.3 308.2 309.4 312.8 314.9 4566.8 5491.5 5506.3 5543.9 5551.0 4799.4 5116.4 5391.9 5455.7 5492.3 3364.8 3938.4 4078.8 4089.6 4109.0 3944.9 4102.9 4139.4 4148.2 4209.9 3124.9 3722.8 3994.9 4157.4 4157.8 3828.4 3994.1 4071.7 4112.2 4186.2 2936.0 3405.9 3509.2 3519.9 3533.0 4418.6 4682.5 4874.2 5160.0 5295.7 3765.1 4355.9 4638.9 4680.3 4710.1 5696.3 5731.5 5751.4 5772.7 5810.7 3901.2 3901.9 3903.6 3907.9 3931.2 5812.7 5955.6 6057.4 6058.9 6090.6 3942.5 3964.6 3970.5 3979.8 3988.5 3487.4 3634.6 3917.7 4036.1 4062.8 3794.4 4518.8 4668.7 4675.2 4698.6 4479.8 5624.8 5788.7 5790.6 5821.9 1293.7 1298.6 1299.2 1299.7 1303.3 716.8 814.6 823.7 871.4 883.2 5095.0 5364.9 5492.4 5516.2 5544.4 5306.0 5463.3 5702.9 5961.9 6019.3 4444.8 4714.6 4839.2 5520.4 5588.0 2483.9 2870.5 2962.5 2983.9 3037.2 5460.2 5548.4 5574.9 5945.5 5965.3 5143.4 5306.8 5612.1 5639.9 5781.0 5802.3 5873.4 5920.3 5968.7 6043.3 4592.7 4686.5 5057.9 5152.3 5580.9 5140.5 5289.8 5955.5 5988.9 5998.7 5590.4 6202.8 6260.2 6262.7 6284.8 3291.8 3298.4 3767.4 3968.7 3981.3 4971.4 5192.3 5203.6 5373.7 5605.9 5770.7 5794.6 5849.6 5852.2 5855.0 4989.9 5455.8 5760.4 5799.5 5847.4 4746.6 5178.4 5639.9 5691.5 5711.4 4570.0 5008.4 5589.4 5750.2 5847.2 5422.6 5641.5 5722.7 5729.7 5753.0 06:11:02 INFO - PROCESS | 1655 | 06:11:02 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:20683.32 Median:4367.78 stddev:30526.10 (698.9%) stddev-sans-first:30574.83 06:11:02 INFO - PROCESS | 1655 | Values: 694.5 695.2 739.9 752.4 776.5 14190.7 14846.5 14963.6 15001.0 15061.2 13591.8 13683.9 13684.9 13735.8 13762.2 632.4 651.9 791.1 805.0 808.2 813.6 818.8 821.4 823.1 824.4 873.5 886.1 887.6 889.1 889.7 846.2 854.3 878.7 879.2 880.3 638.2 639.5 647.5 652.2 654.6 14096.6 14275.0 14357.9 14423.2 14846.6 13861.4 13861.7 14082.8 14259.9 14288.3 10871.9 11191.3 11370.0 11610.7 11693.4 3671.5 3672.9 3685.9 3701.9 3702.7 80865.0 86481.7 87691.6 91097.0 94465.0 10741.6 10873.8 10901.9 10903.2 11217.0 21665.5 21803.7 21825.1 21888.3 21937.6 7762.9 7822.6 7854.1 7863.6 7928.4 4222.6 4366.8 4367.4 4368.2 4368.9 3474.8 3532.6 3586.0 3599.9 3607.7 537.5 545.2 614.0 645.1 649.4 588.9 603.5 617.8 643.7 673.9 7529.6 8423.5 8731.3 9165.2 9235.7 509.5 565.3 633.9 663.4 672.3 3520.4 3770.4 4144.6 4164.3 4570.8 75455.6 104452.5 117676.0 118952.0 121271.0 102450.0 104000.0 104475.0 106163.0 109905.0 71741.2 88823.2 89873.1 89905.1 91843.5 8422.2 8696.3 8698.0 8827.5 8881.9 49278.0 58280.7 59092.7 60173.4 61242.0 49660.5 58256.7 68974.0 72036.0 72962.9 12657.2 12683.4 12793.5 13171.5 13402.1 13910.4 14197.0 14198.9 14415.2 14980.4 12907.2 12953.4 13003.3 13077.7 13210.1 12874.5 13036.2 13328.2 13350.8 13761.1 12549.2 12850.4 12917.7 13019.5 13242.5 9901.8 10039.8 10765.9 11227.3 11295.6 7957.1 9402.5 9603.7 9626.6 11221.0 06:11:02 INFO - PROCESS | 1655 | -------- Summary: end -------- 06:11:02 INFO - PROCESS | 1655 | 06:11:03 INFO - TEST-INFO | 1655: exit 0 06:11:03 INFO - TEST-OK | dromaeo_css | took 1116379ms 06:11:03 INFO - TEST-START | kraken 06:11:03 INFO - operating with platform_type : mac_ 06:11:03 INFO - Initialising browser for kraken test... 06:11:03 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmp7IL9dt/profile http://localhost:49274/getInfo.html) 06:11:05 INFO - PROCESS | 1663 | 2016-11-09 06:11:05.991 plugin-container[1664:10530] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b43, name = 'com.apple.tsm.portname' 06:11:05 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:11:05 INFO - PROCESS | 1663 | 2016-11-09 06:11:05.995 plugin-container[1664:10530] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 06:11:05 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:11:05 INFO - PROCESS | 1663 | 2016-11-09 06:11:05.995 plugin-container[1664:10530] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:11:06 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 06:11:06 INFO - PROCESS | 1663 | colorDepth:24 06:11:06 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 06:11:06 INFO - PROCESS | 1663 | __metrics 06:11:06 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 06:11:06 INFO - TEST-INFO | 1663: exit 0 06:11:06 INFO - Browser initialized. 06:11:06 INFO - Running cycle 1/1 for kraken test... 06:11:06 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmp7IL9dt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:11:09 INFO - PROCESS | 1665 | 2016-11-09 06:11:09.252 plugin-container[1666:10645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 06:11:09 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:11:09 INFO - PROCESS | 1665 | 2016-11-09 06:11:09.252 plugin-container[1666:10645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.CFPasteboardClient' 06:11:09 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:11:09 INFO - PROCESS | 1665 | 2016-11-09 06:11:09.253 plugin-container[1666:10645] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:11:18 INFO - PROCESS | 1665 | RSS: Main: 243089408 06:11:18 INFO - PROCESS | 1665 | 06:11:22 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 06:11:22 INFO - PROCESS | 1665 | RSS: Main: 253779968 06:11:22 INFO - PROCESS | 1665 | 06:11:25 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 06:11:26 INFO - PROCESS | 1665 | RSS: Main: 261308416 06:11:26 INFO - PROCESS | 1665 | 06:11:29 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 06:11:30 INFO - PROCESS | 1665 | RSS: Main: 262721536 06:11:30 INFO - PROCESS | 1665 | 06:11:32 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 06:11:33 INFO - PROCESS | 1665 | RSS: Main: 263593984 06:11:33 INFO - PROCESS | 1665 | 06:11:35 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:11:35 INFO - PROCESS | 1665 | RSS: Main: 264581120 06:11:35 INFO - PROCESS | 1665 | 06:11:38 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 06:11:39 INFO - PROCESS | 1665 | RSS: Main: 264724480 06:11:39 INFO - PROCESS | 1665 | 06:11:42 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 06:11:42 INFO - PROCESS | 1665 | RSS: Main: 264896512 06:11:42 INFO - PROCESS | 1665 | 06:11:46 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 06:11:46 INFO - PROCESS | 1665 | RSS: Main: 264941568 06:11:46 INFO - PROCESS | 1665 | 06:11:48 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:11:48 INFO - PROCESS | 1665 | RSS: Main: 264712192 06:11:48 INFO - PROCESS | 1665 | 06:11:50 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:11:51 INFO - PROCESS | 1665 | RSS: Main: 264781824 06:11:51 INFO - PROCESS | 1665 | 06:11:53 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:11:54 INFO - PROCESS | 1665 | RSS: Main: 264900608 06:11:54 INFO - PROCESS | 1665 | 06:11:57 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:11:58 INFO - PROCESS | 1665 | RSS: Main: 265207808 06:11:58 INFO - PROCESS | 1665 | 06:12:01 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:12:01 INFO - PROCESS | 1665 | RSS: Main: 265129984 06:12:01 INFO - PROCESS | 1665 | 06:12:03 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 06:12:03 INFO - PROCESS | 1665 | RSS: Main: 266809344 06:12:03 INFO - PROCESS | 1665 | 06:12:03 INFO - PROCESS | 1665 | __start_tp_report 06:12:03 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 06:12:03 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 06:12:03 INFO - PROCESS | 1665 | |i|pagename|runs| 06:12:03 INFO - PROCESS | 1665 | |0;ai-astar;106;119;119;118;117;119;137;178;153;119 06:12:03 INFO - PROCESS | 1665 | |1;audio-beat-detection;151;114;115;181;147;125;116;113;143;116 06:12:03 INFO - PROCESS | 1665 | |2;audio-dft;197;204;246;245;283;228;277;270;193;200 06:12:03 INFO - PROCESS | 1665 | |3;audio-fft;81;59;59;84;60;63;53;73;101;82 06:12:03 INFO - PROCESS | 1665 | |4;audio-oscillator;85;79;124;82;69;70;86;71;81;89 06:12:03 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;99;117;91;89;90;87;91;86;136;125 06:12:03 INFO - PROCESS | 1665 | |6;imaging-darkroom;95;96;97;103;139;126;123;104;149;147 06:12:03 INFO - PROCESS | 1665 | |7;imaging-desaturate;136;122;85;84;87;101;143;90;109;144 06:12:03 INFO - PROCESS | 1665 | |8;json-parse-financial;57;56;55;56;56;58;56;59;59;57 06:12:03 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;70;76;73;109;108;64;71;95;104;104 06:12:03 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;94;107;88;85;88;90;90;86;111;87 06:12:03 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;149;173;153;162;157;151;135;162;227;166 06:12:03 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;176;179;177;201;247;176;179;171;174;176 06:12:03 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;67;66;70;73;91;85;68;69;66;69 06:12:03 INFO - PROCESS | 1665 | __end_tp_report 06:12:03 INFO - PROCESS | 1665 | __start_cc_report 06:12:03 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,972 06:12:03 INFO - PROCESS | 1665 | __end_cc_report 06:12:03 INFO - PROCESS | 1665 | __startTimestamp1478700723944__endTimestamp 06:12:03 INFO - PROCESS | 1665 | 06:12:03 INFO - PROCESS | 1665 | ------- Summary: start ------- 06:12:03 INFO - PROCESS | 1665 | Number of tests: 14 06:12:03 INFO - PROCESS | 1665 | 06:12:03 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:128.50 Median:119.00 stddev:21.69 (18.2%) stddev-sans-first:21.42 06:12:03 INFO - PROCESS | 1665 | Values: 106.0 119.0 119.0 118.0 117.0 119.0 137.0 178.0 153.0 119.0 06:12:03 INFO - PROCESS | 1665 | 06:12:03 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:132.10 Median:134.00 stddev:22.71 (16.9%) stddev-sans-first:23.04 06:12:03 INFO - PROCESS | 1665 | Values: 151.0 114.0 115.0 181.0 147.0 125.0 116.0 113.0 143.0 116.0 06:12:03 INFO - PROCESS | 1665 | 06:12:03 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:234.30 Median:245.50 stddev:34.88 (14.2%) stddev-sans-first:34.29 06:12:03 INFO - PROCESS | 1665 | Values: 197.0 204.0 246.0 245.0 283.0 228.0 277.0 270.0 193.0 200.0 06:12:03 INFO - PROCESS | 1665 | 06:12:03 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:71.50 Median:68.00 stddev:15.23 (22.4%) stddev-sans-first:15.76 06:12:03 INFO - PROCESS | 1665 | Values: 81.0 59.0 59.0 84.0 60.0 63.0 53.0 73.0 101.0 82.0 06:12:03 INFO - PROCESS | 1665 | 06:12:03 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:83.60 Median:81.50 stddev:15.83 (19.4%) stddev-sans-first:16.79 06:12:03 INFO - PROCESS | 1665 | Values: 85.0 79.0 124.0 82.0 69.0 70.0 86.0 71.0 81.0 89.0 06:12:03 INFO - PROCESS | 1665 | 06:12:03 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:101.10 Median:89.50 stddev:18.10 (20.2%) stddev-sans-first:19.18 06:12:03 INFO - PROCESS | 1665 | Values: 99.0 117.0 91.0 89.0 90.0 87.0 91.0 86.0 136.0 125.0 06:12:03 INFO - PROCESS | 1665 | 06:12:03 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:117.90 Median:148.00 stddev:21.62 (14.6%) stddev-sans-first:21.28 06:12:03 INFO - PROCESS | 1665 | Values: 95.0 96.0 97.0 103.0 139.0 126.0 123.0 104.0 149.0 147.0 06:12:03 INFO - PROCESS | 1665 | 06:12:03 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:110.10 Median:114.00 stddev:24.44 (21.4%) stddev-sans-first:24.06 06:12:03 INFO - PROCESS | 1665 | Values: 136.0 122.0 85.0 84.0 87.0 101.0 143.0 90.0 109.0 144.0 06:12:03 INFO - PROCESS | 1665 | 06:12:03 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.00 stddev:1.37 (2.4%) stddev-sans-first:1.45 06:12:03 INFO - PROCESS | 1665 | Values: 57.0 56.0 55.0 56.0 56.0 58.0 56.0 59.0 59.0 57.0 06:12:03 INFO - PROCESS | 1665 | 06:12:03 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:87.40 Median:70.50 stddev:18.12 (25.7%) stddev-sans-first:18.10 06:12:03 INFO - PROCESS | 1665 | Values: 70.0 76.0 73.0 109.0 108.0 64.0 71.0 95.0 104.0 104.0 06:12:03 INFO - PROCESS | 1665 | 06:12:03 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:92.60 Median:88.00 stddev:9.05 (10.3%) stddev-sans-first:9.58 06:12:03 INFO - PROCESS | 1665 | Values: 94.0 107.0 88.0 85.0 88.0 90.0 90.0 86.0 111.0 87.0 06:12:03 INFO - PROCESS | 1665 | 06:12:03 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:163.50 Median:162.00 stddev:24.64 (15.2%) stddev-sans-first:25.57 06:12:03 INFO - PROCESS | 1665 | Values: 149.0 173.0 153.0 162.0 157.0 151.0 135.0 162.0 227.0 166.0 06:12:03 INFO - PROCESS | 1665 | 06:12:03 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.60 Median:178.00 stddev:23.08 (13.0%) stddev-sans-first:24.21 06:12:03 INFO - PROCESS | 1665 | Values: 176.0 179.0 177.0 201.0 247.0 176.0 179.0 171.0 174.0 176.0 06:12:03 INFO - PROCESS | 1665 | 06:12:03 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.40 Median:69.50 stddev:8.59 (12.4%) stddev-sans-first:8.89 06:12:03 INFO - PROCESS | 1665 | Values: 67.0 66.0 70.0 73.0 91.0 85.0 68.0 69.0 66.0 69.0 06:12:03 INFO - PROCESS | 1665 | -------- Summary: end -------- 06:12:03 INFO - PROCESS | 1665 | 06:12:04 INFO - TEST-INFO | 1665: exit 0 06:12:04 INFO - TEST-OK | kraken | took 61695ms 06:12:04 INFO - SUITE-END | took 1178s 06:12:05 INFO - Completed test suite (00:19:38) 06:12:05 INFO - javascript benchmark 06:12:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7142.271739696718, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3437.8624855005796, 3453.5855697316324, 3478.1652403423186, 3478.7738796978188, 3484.425069863473, 5659.179418984245, 5749.223854779603, 5842.912356314653, 5908.113782932563, 5913.85215369616, 5731.512821410175, 5799.18811366409, 5828.766849326033, 5885.440883116097, 5966.970165149168, 5228.92156617651, 5241.711705856176, 5242.004019236352, 5340.946590534103, 5379.919301210485, 4841.523335382707, 5345.1447768357075, 5371.838844834641, 5382.085045542267, 5461.918071228915, 5175.171972484422, 5231.24146998683, 5321.813736519222, 5323.893522129574, 5331.067063263913, 3882.6893848492055, 4049.736767110158, 4338.349247612852, 4661.533846615345, 5332.68003919766, 5213.504717051874, 5223.738813059332, 5236.476352364771, 5241.86895327616, 5280.234366016906, 5581.079121944874, 5663.9716801415725, 5692.487676109128, 5731.942680573183, 5827.242458480413, 5391.757370918318, 6119.99004232014, 6230.750769969196, 6268.65522396268, 6277.372262773732, 6308.905366419507, 6311.905321420182, 6314.62112273265, 6317.74729010839, 6317.842053948642, 5875.353711091777, 5915.08316210988, 5954.2259506264345, 5982.072778719218, 6003.519718422515, 6345.460635845912, 6379.936200638027, 6389.680515974275, 6399.264084630234, 6431.5497915146425, 6245.937540624535, 6257.0614407838275, 6274.247090349096, 6286.9056964145575, 6290.119383286344, 4954.801807927714, 6166.0, 6196.318404975449, 6224.595401298901, 6310.274318453345, 6270.31026587075, 6298.149749783812, 6305.243370795534, 6312.274088479883, 6329.082283068929, 6201.596896201732, 6298.0, 6298.527610429236, 6298.874022519524, 6327.651979141099, 4899.338589290472, 4905.87735306613, 4973.104841128559, 5060.291559181644, 5088.0, 3914.432407300925, 4435.733855968652, 4466.7766611669285, 4532.637389008872, 4644.117617652635, 5604.97197514018, 6061.666608336495, 6067.0, 6100.69496525172, 6105.175801266772, 6200.193974783339, 6300.873982520324, 6312.810615681537, 6321.873562528724, 6325.746970121144, 5983.222181116427, 6007.459328660441, 6047.0929360596265, 6077.4226448487325, 6120.0207966726, 5514.944850551524, 5522.364928033247, 5522.364928033247, 5526.06056970316, 5528.695921724263, 5132.178851383761, 6174.073888916699, 6348.238211414659, 6483.481321494363, 6483.610983341015, 6180.196574445294, 6229.781957631461, 6262.091996660368, 6262.499000079892, 6275.6862156892885, 6435.163428754232, 6447.0651755494255, 6452.451541619015, 6453.51598630114, 6478.222613286247, 6086.908696369469, 6109.08363745424, 6142.969285153546, 6213.285472170758, 6220.129181914445, 6203.038529028008, 6255.124282600531, 6266.404691554295, 6286.465650419583, 6308.495320374451, 5976.880462390641, 6105.945000794801, 6130.887362651087, 6150.538709596887, 6197.96901015492, 5891.381404952418, 6022.427869352405, 6138.693065346625, 6165.876682466236, 6173.69131543412, 5225.6603320784025, 5844.2986841577585, 6052.213212282375, 6052.909206361996, 6109.17526133984, 6158.050648819579, 6173.135760993554, 6180.505559555315, 6183.84540386476, 6193.163922870535, 5735.942640573541, 6052.182955300978, 6054.515638748978, 6077.544184186116, 6094.329623741296], "value": 5731.297975136922, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.4415033087776, 501.6830819099863, 516.2746341390348, 522.0133946840471, 525.4036668381387, 39410.60589394109, 40688.593114068884, 40793.98015049625, 40898.0, 40967.59032409679, 33950.32099358018, 41455.0, 41543.0, 42012.0, 42073.0, 9454.24366050716, 9577.281703872202, 9628.614855405775, 9663.275254355916, 9689.515524223796, 9526.23790096794, 9601.087896649842, 9604.231661467065, 9607.2314214863, 9624.47065411402, 9598.0, 9622.807543849154, 9639.421634701905, 9652.0, 9658.565364558612, 9546.761330966712, 9562.61749530018, 9607.951960240223, 9630.988746181654, 9638.228941684649, 9442.57508412123, 9455.763605909839, 9504.714858554185, 9544.665936692183, 9560.856587151198, 39714.80142599298, 39875.800620997, 39955.0, 40083.198336033114, 40225.0, 3370.6629337066342, 3499.947500787465, 3657.9085522861874, 3717.442383642448, 3912.647861692433, 23058.0, 29908.55137172944, 30358.241043973856, 31108.533371999438, 31118.688813112032, 2849.9287517812013, 3180.9006404665324, 3238.5951756030495, 3249.983750081258, 3366.545516355285, 365719.0, 369370.0, 369738.15130924445, 370008.0, 370079.0, 57735.42264577385, 58042.12936805951, 58243.8351232973, 58304.0, 58392.708036459546, 60629.39370606237, 60689.0, 60740.08889866655, 60783.0, 60973.78052439015, 15560.299786509408, 15769.526914192593, 15776.368945242291, 15786.68426631484, 15820.367185312689, 11126.443677816238, 11808.763824723457, 12188.390580470941, 12214.206076605169, 12231.449584768528, 13550.728985420237, 15778.289976951064, 15942.920285398499, 15961.760573591406, 15999.920802272902, 3191.856366463515, 6500.447461965691, 6590.6932573599815, 6624.403803657598, 6663.766768163091, 892.9553522323857, 957.5834511987308, 1030.21703505335, 1030.721705139608, 1032.6385764982197, 27014.32464188411, 27638.0, 29911.25221869427, 30028.399432011236, 31262.531062034086, 925.9490728010024, 1034.4414016431194, 1041.1202533858877, 1100.3782862682558, 1170.169179882293, 15212.391504339923, 15420.845791542166, 15481.5355539334, 15513.612159696098, 15898.364065437252, 662181.0, 668624.0, 669115.0, 671495.0, 673828.0, 47273.0, 55403.72298138564, 57081.0, 57378.7131064342, 57436.712816436484, 1331.8135461035463, 1339.41065930992, 1366.6241783509615, 1366.8291463567055, 1371.9039667223199, 28808.855955720086, 29398.853005734836, 29493.26266843346, 30075.24811879633, 30644.387112258075, 49108.75445622749, 49501.0, 49648.50351496439, 49694.25458618051, 49701.50298496969, 585467.0, 602637.0, 603027.9848600728, 604854.0, 607610.0, 24965.750342496343, 26056.73943260543, 26432.471350572498, 26472.602910955964, 26476.338091547866, 42757.57242427536, 44114.0, 45149.77425112853, 45276.32085518654, 45357.0, 36981.0, 37271.6272837279, 37356.25287494289, 37369.4394584076, 38606.8417947462, 36977.26045479129, 37064.44403334006, 37290.62709372872, 37861.62138378581, 38550.6144938547, 36686.0, 37133.62866371302, 37142.62857371392, 37666.623333767406, 38054.0, 32593.674063259063, 33628.49557256692, 34239.315213696085, 34549.309013820086, 34569.135771605914, 21065.894670526548, 25004.749952500242, 25252.62121068222, 25585.87207063953, 25594.360140996625], "value": 19363.43875984028, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.2093218182636, 371.3000993127954, 378.3907908267689, 382.7990305089826, 397.7553804410289, 18569.071546422674, 19714.0, 19737.60524789505, 20060.99999999998, 20226.29207977721, 19307.613847723038, 19633.705494417594, 19959.501012474695, 20132.0, 20226.89886550569, 4533.977330113361, 5515.117581187001, 5781.913271300934, 5786.334571524287, 5800.506956908668, 5571.387147413781, 5769.24999750034, 5771.50942169916, 5781.161731548923, 5798.768049278045, 5446.455354464561, 5670.886582268352, 5686.772529098831, 5723.227364305807, 5729.512991395736, 4985.853253751658, 5571.13647384654, 5584.0, 5609.793894312742, 5656.151577263402, 4716.38686970695, 4743.0988112258565, 4763.285507173951, 4765.618750499952, 4838.467768545457, 24160.395990100213, 24326.905289262024, 24382.780860956882, 24395.0, 24596.0, 1997.4207479830977, 2111.3032699209225, 2129.8615589986603, 2307.792298693109, 2752.2981639681952, 9647.276454265959, 9952.90047099527, 10335.638252661121, 10396.11633011195, 12446.439910203972, 1822.2164469278205, 1825.7170138628535, 1844.7095404670144, 1880.576870204207, 1916.9904150479297, 127875.0, 130664.346678266, 131775.0, 132587.0, 132960.0, 11443.713907152222, 11488.310701357777, 11700.70748231284, 11714.472848721827, 11825.940870295766, 13034.696530347079, 13342.266175360253, 14578.125312481285, 14609.92695036518, 14658.926705366619, 6758.932410675929, 8223.876641850258, 8355.832883342298, 8369.665213391396, 8531.360147988926, 8174.918250817415, 8175.918240817516, 8312.54281014538, 8326.417150799385, 8365.58172091393, 3901.785401802871, 4335.241332766816, 4406.096750166208, 4559.635229181657, 4667.719936803803, 1688.9493315200564, 1918.6066856294426, 1963.4207908666692, 1967.2131147540813, 2032.2463062550328, 805.9516429014278, 892.1658249536704, 931.8892708016349, 938.5166639180827, 979.5494072726483, 14802.703945921021, 19914.805111693346, 20433.67181133251, 20718.89640551818, 20771.0, 885.968991085309, 900.1898291537668, 922.372786505183, 927.1933417926313, 938.380668758616, 9911.801763964824, 10829.566817327377, 10938.0, 10984.780304393867, 11138.665840024812, 573603.1319843457, 580316.0, 584259.0, 590817.0, 596355.0, 130682.0, 136592.9511057335, 138131.30934345265, 139899.0, 143916.56083439031, 956.7560272130562, 976.2141476111798, 993.2550587059706, 1008.047395211518, 1010.0455069958819, 18952.52618684544, 19214.519637009187, 19228.807711923262, 19239.711404328667, 19357.806421935602, 38615.8415247543, 43138.13723725571, 43737.56262437335, 56795.71602142128, 57373.0, 75135.0, 77590.0, 77614.83577746226, 79244.60377698074, 83932.58033710037, 6960.860782784214, 7920.90096124539, 7926.207379262028, 7954.64204110828, 8193.09875913661, 10001.54993025301, 11911.285322880654, 12218.38908054576, 12697.0, 12795.488180472677, 9460.195883349992, 10629.8937010632, 10632.202584806017, 10865.674029779118, 10936.507857146607, 10395.272330936761, 10634.89365106339, 10818.780219016398, 11020.50407731638, 11077.667669969915, 10897.836532451862, 10959.99960014573, 11055.170862185207, 11132.777344452903, 11412.828807567726, 7164.820879478055, 7330.0, 7397.334239918433, 7550.433717471102, 7651.655675494728, 8838.425502342327, 10806.621768238074, 10844.0, 11138.443077846237, 11148.777024459627], "value": 9327.56638684035, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.9211893345866, 1495.2972103111538, 1685.1995302231442, 1818.654455653425, 1820.4720631017005, 7555.69777208911, 7731.613419329039, 8011.839763204733, 8472.661093556258, 8488.363372747037, 7925.207479252086, 8217.465864718804, 8242.093369729326, 8353.122922093184, 8358.205970432815, 2271.250487339174, 2281.623532117203, 2308.676785250066, 2309.4688221709016, 2310.849794763343, 2284.3832165315357, 2288.8397812153157, 2299.8735069571167, 2344.3787396339985, 2348.58899692554, 2287.9084836606517, 2288.2220045184717, 2311.8034967027825, 2316.652502124686, 2323.0, 2269.1830940861278, 2281.863088214712, 2306.042992158269, 2307.3885420363617, 2310.9768902311016, 1931.4205738278602, 2043.2950632031925, 2075.9065842036994, 2297.448612333045, 2312.595295823241, 8295.751127466187, 8362.331013518964, 8371.16288371164, 8426.747197584082, 8449.788755281106, 5041.369828771403, 5160.716160611165, 5202.141646628302, 5210.713410762407, 5222.190560463135, 3197.520371944227, 3205.4390481665614, 3544.9113772155642, 3545.220051588672, 3557.3952428087027, 1045.9947700261525, 1048.2295512798087, 1082.6481393547128, 1102.1953973599236, 1102.5699977008974, 10821.080208182313, 10871.891281087166, 10909.672709818718, 10965.61620343292, 11002.394868282241, 3654.780713157219, 4524.253498172765, 4621.445426548836, 4631.58315751584, 4667.066586682677, 9423.057694230522, 9439.811203776024, 9443.291753118543, 9471.289653276031, 9495.810083798286, 5119.206522988943, 5138.97430512845, 5158.226266060121, 5170.663906846118, 5185.040767458032, 4224.55642157569, 4450.0, 4661.930071048937, 4683.812647494062, 4747.216709243005, 3762.097096696773, 3792.1088544192094, 3843.461915331856, 3875.3218186817194, 3878.108035151931, 1160.1762748448527, 1217.903886502155, 1263.8925691316158, 1274.796032634774, 1281.9871801282054, 863.2619110660421, 875.6803766625263, 884.363258453925, 914.5244472874201, 924.6856068936594, 5077.1876499759865, 5362.812301569426, 5543.750531226105, 5645.633033852787, 5742.971285143548, 429.08176502285136, 434.2249085382577, 450.5967159392325, 450.7858767085608, 450.8121100899089, 3061.5101583746496, 3217.3243618840306, 3242.9351412972082, 3450.706689931384, 3638.927221455609, 9674.419534827934, 9736.22110231194, 10154.035366640157, 10161.339512931636, 10309.896901031043, 10209.285350025428, 10295.742606434898, 10306.0, 10306.278560500843, 10314.948425257975, 7779.45543811939, 7976.401769867283, 7976.601169941479, 8019.997500312461, 8037.23646253605, 4748.857534273978, 4957.603391728725, 5851.327097383855, 5926.436988486087, 5998.730057147526, 8949.776255593662, 9037.457752534869, 9278.072192780668, 9328.813423731623, 9452.810943780949, 7523.510971786816, 9270.490123043028, 9377.202937750368, 9404.52977351116, 9447.385919915183, 7020.5787652741, 7253.093363329584, 7569.810754731176, 7707.807304817425, 7862.095858976291, 7819.530828150329, 7841.294283514511, 7939.444238903221, 7985.5208687478935, 7990.04119505663, 6963.825904352431, 7010.088688470466, 7043.823904402431, 7072.115985501812, 7078.079849619517, 6864.5881247125335, 6869.553479023847, 6909.585424874524, 6965.33829286217, 7019.964900175466, 6495.382938620824, 6630.30381809903, 6634.170728658918, 6696.19645642526, 6703.128593283037, 4165.312723400605, 4303.419038429897, 4701.670883038291, 4801.4958429365815, 4972.7762250699525, 4788.521147885183, 4830.106430310404, 4959.7272150032095, 4985.8005679772405, 5062.0888240115655], "value": 4295.9308257887005, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [264.33124195784666, 308.15565350938436, 309.44609149622175, 312.84201478253476, 314.88349310755024, 4566.794494247759, 5491.505764481196, 5506.311711036121, 5543.916841247385, 5551.0, 4799.37608110946, 5116.437191908887, 5391.877716169635, 5455.727213639312, 5492.313460817398, 3364.8317584120696, 3938.3698608222617, 4078.775667338295, 4089.6114869087387, 4108.958910410903, 3944.9091981249985, 4102.938455923164, 4139.3997870308785, 4148.174803086647, 4209.936850947223, 3124.875004999797, 3722.813859307024, 3994.9289757129864, 4157.355609880473, 4157.833686652545, 3828.4158193219464, 3994.101327201371, 4071.694622903294, 4112.231740351647, 4186.225548273564, 2936.016434494447, 3405.9489107663403, 3509.2455122148604, 3519.8592056317716, 3533.0, 4418.6023257906945, 4682.531746825324, 4874.2444921037295, 5160.019596276696, 5295.655782374133, 3765.1151979284846, 4355.912881742347, 4638.884027899296, 4680.251159814414, 4710.11595361858, 5696.344920334173, 5731.541476681856, 5751.424857514257, 5772.7113644317615, 5810.709464526757, 3901.200253947934, 3901.882943511699, 3903.5901230370687, 3907.886252418076, 3931.1523583915514, 5812.738426770763, 5955.612885162451, 6057.3639767824625, 6058.878822423527, 6090.604110732833, 3942.48747662802, 3964.6035396460125, 3970.4838371011583, 3979.7612143271494, 3988.501437320335, 3487.4245749451056, 3634.636536346397, 3917.745346552465, 4036.0918793271867, 4062.756234625873, 3794.354959656865, 4518.796654150571, 4668.673192876534, 4675.181843177498, 4698.647601429906, 4479.843205487858, 5624.803131890364, 5788.652680839163, 5790.565707571949, 5821.88356232873, 1293.683047653312, 1298.571471414431, 1299.226959958823, 1299.7335546213003, 1303.2571434282368, 716.8458781362003, 814.6130587970666, 823.6828820903995, 871.4335681806749, 883.1874675298612, 5094.974525127351, 5364.946350536445, 5492.395836457987, 5516.227728118067, 5544.417836127229, 5305.973470132624, 5463.262459567987, 5702.889930725658, 5961.880762384728, 6019.337872833985, 4444.822207111744, 4714.599259062949, 4839.201531747292, 5520.447955204448, 5587.972060139673, 2483.913063042821, 2870.512012957802, 2962.511185654386, 2983.925401864971, 3037.16477968556, 5460.225426736247, 5548.4174161713245, 5574.888502229933, 5945.473173901075, 5965.3139888912465, 5143.405544281285, 5306.787728490817, 5612.102063669793, 5639.945483831, 5781.0, 5802.303723553201, 5873.442023007893, 5920.348761636216, 5968.7015649217365, 6043.2748070231855, 4592.7014744041535, 4686.507916668837, 5057.898842023065, 5152.3044389007, 5580.935054030035, 5140.46025167367, 5289.761960711854, 5955.464008239279, 5988.850278743066, 5998.730057147526, 5590.413006634408, 6202.751889924352, 6260.248770147429, 6262.655553944577, 6284.842878928063, 3291.8313998530175, 3298.356820420091, 3767.3783825668456, 3968.7420317679257, 3981.3430783921485, 4971.428285747185, 5192.299039629602, 5203.609729270395, 5373.677579345252, 5605.850800585951, 5770.711464426846, 5794.594378393472, 5849.561282903885, 5852.244326702057, 5855.0, 4989.900201996013, 5455.781768729206, 5760.395158508464, 5799.478046975623, 5847.433065829925, 4746.57602719176, 5178.359036891137, 5639.8590035249445, 5691.516221121085, 5711.400302968129, 4569.977150114361, 5008.418980428865, 5589.385167631639, 5750.163583962365, 5847.152162936265, 5422.62041657096, 5641.548676105819, 5722.656640601577, 5729.742161602655, 5752.9712351437975], "value": 4106.387132179857, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.517310469224, 695.235241234642, 739.8705226585346, 752.38304590236, 776.5418403142147, 14190.71618567628, 14846.480373186942, 14963.625909352271, 15001.024933379336, 15061.246937653128, 13591.796123058162, 13683.931580342109, 13684.863151368483, 13735.793963090537, 13762.174269543835, 632.3644737039292, 651.9445847102978, 791.1218547412368, 804.9758507244777, 808.2160304504621, 813.6419975210888, 818.8116733151379, 821.4003777242616, 823.0534884882403, 824.4064273722939, 873.5413907698477, 886.1050339157433, 887.6316328723572, 889.0664801957951, 889.6975028490313, 846.1580727176469, 854.2653318146389, 878.6617152396374, 879.1691851200618, 880.3133555826465, 638.2277444292412, 639.4788247578209, 647.5175993884586, 652.2303681655644, 654.5581732330658, 14096.647583810383, 14275.000749947509, 14357.856421435756, 14423.206723630195, 14846.628834279121, 13861.4455421783, 13861.653458663513, 14082.788758168635, 14259.857401425958, 14288.285585720672, 10871.945640271828, 11191.2725672831, 11370.0, 11610.70973225676, 11693.415329233505, 3671.4859919611276, 3672.944905826415, 3685.907852303687, 3701.8519259229597, 3702.6667599916136, 80865.0, 86481.71037413199, 87691.56154219252, 91097.0, 94464.99999999932, 10741.624043158452, 10873.83689244662, 10901.890981090246, 10903.236773425942, 11217.04655104309, 21665.45836354072, 21803.69177849334, 21825.126994920025, 21888.343349699215, 21937.561248775255, 7762.92237077622, 7822.569758663214, 7854.136045035041, 7863.567503787347, 7928.365730741527, 4222.556631553704, 4366.825326986948, 4367.366731823868, 4368.191884501377, 4368.934465983013, 3474.7915125092068, 3532.6290739472497, 3585.964140358615, 3599.9280014399565, 3607.7474576779373, 537.5054949446519, 545.1931141909995, 613.9877202456015, 645.0775391190642, 649.3636236488268, 588.9252114890343, 603.4900509069829, 617.8362733875526, 643.7231990244146, 673.9157605299338, 7529.58587277694, 8423.4945903246, 8731.257843083282, 9165.220956218664, 9235.722928312027, 509.4548832748923, 565.3442007271545, 633.9492840572835, 663.4195079305607, 672.3310306245318, 3520.4015317396106, 3770.4344348347418, 4144.585541445892, 4164.333706606928, 4570.794314255866, 75455.62272188604, 104452.47773761082, 117676.0, 118952.0, 121270.9682257933, 102450.0, 104000.0, 104475.0, 106163.0, 109905.0, 71741.20646983867, 88823.22353552893, 89873.10126898778, 89905.10094899098, 91843.54078229566, 8422.241998219944, 8696.260817830302, 8697.95651021741, 8827.514486703292, 8881.866771998297, 49278.0, 58280.708596456745, 59092.704536477046, 60173.39826601853, 61242.0, 49660.503394965584, 58256.708716456145, 68974.0, 72036.0, 72962.90555641775, 12657.240565566095, 12683.365831708563, 12793.488260469478, 13171.47314107425, 13402.06185567001, 13910.441253703762, 14197.0, 14198.929005354907, 14415.207163605683, 14980.400783968955, 12907.225566466042, 12953.41709623088, 13003.284819335027, 13077.738445230852, 13210.141340812816, 12874.485020599446, 13036.217826930415, 13328.200307981551, 13350.799738003743, 13761.105528140639, 12549.247045177319, 12850.36995893752, 12917.677058073623, 13019.544315948897, 13242.470301187845, 9901.762279715036, 10039.849402259119, 10765.946170269099, 11227.326360418401, 11295.604653837076, 7957.075118542551, 9402.52987350616, 9603.739826863515, 9626.566804493674, 11221.046211072151], "value": 7268.598919433413, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1637.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 119.0, 119.0, 118.0, 117.0, 119.0, 137.0, 178.0, 153.0, 119.0], "value": 128.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 114.0, 115.0, 181.0, 147.0, 125.0, 116.0, 113.0, 143.0, 116.0], "value": 132.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 204.0, 246.0, 245.0, 283.0, 228.0, 277.0, 270.0, 193.0, 200.0], "value": 234.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 84.0, 60.0, 63.0, 53.0, 73.0, 101.0, 82.0], "value": 71.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 79.0, 124.0, 82.0, 69.0, 70.0, 86.0, 71.0, 81.0, 89.0], "value": 83.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 117.0, 91.0, 89.0, 90.0, 87.0, 91.0, 86.0, 136.0, 125.0], "value": 101.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 97.0, 103.0, 139.0, 126.0, 123.0, 104.0, 149.0, 147.0], "value": 117.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 122.0, 85.0, 84.0, 87.0, 101.0, 143.0, 90.0, 109.0, 144.0], "value": 110.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 55.0, 56.0, 56.0, 58.0, 56.0, 59.0, 59.0, 57.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 76.0, 73.0, 109.0, 108.0, 64.0, 71.0, 95.0, 104.0, 104.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 107.0, 88.0, 85.0, 88.0, 90.0, 90.0, 86.0, 111.0, 87.0], "value": 92.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 173.0, 153.0, 162.0, 157.0, 151.0, 135.0, 162.0, 227.0, 166.0], "value": 163.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 179.0, 177.0, 201.0, 247.0, 176.0, 179.0, 171.0, 174.0, 176.0], "value": 185.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 70.0, 73.0, 91.0, 85.0, 68.0, 69.0, 66.0, 69.0], "value": 72.4, "unit": "score"}]}]} 06:12:05 INFO - Return code: 0 06:12:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:12:05 ERROR - # TBPL SUCCESS # 06:12:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:12:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:12:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:12:05 INFO - 2016-11-09 06:12:05.298 screenresolution[1670:11025] starting screenresolution argv=screenresolution get 06:12:05 INFO - 2016-11-09 06:12:05.301 screenresolution[1670:11025] Display 0: 1600x1200x32@60 06:12:05 INFO - 2016-11-09 06:12:05.307 screenresolution[1671:11028] starting screenresolution argv=screenresolution list 06:12:05 INFO - Available Modes on Display 0 06:12:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:12:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:12:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:12:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:12:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:12:05 INFO - Intel Iris: 06:12:05 INFO - Chipset Model: Intel Iris 06:12:05 INFO - Type: GPU 06:12:05 INFO - Bus: Built-In 06:12:05 INFO - VRAM (Dynamic, Max): 1536 MB 06:12:05 INFO - Vendor: Intel (0x8086) 06:12:05 INFO - Device ID: 0x0a2e 06:12:05 INFO - Revision ID: 0x0009 06:12:05 INFO - Displays: 06:12:05 INFO - Display: 06:12:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:12:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:12:05 INFO - Display Serial Number: 1600x1200 60 06:12:05 INFO - Main Display: Yes 06:12:05 INFO - Mirror: Off 06:12:05 INFO - Online: Yes 06:12:05 INFO - Rotation: Supported 06:12:05 INFO - Return code: 0 06:12:05 INFO - Running post-action listener: _resource_record_post_action 06:12:05 INFO - [mozharness: 2016-11-09 14:12:05.543163Z] Finished run-tests step (success) 06:12:05 INFO - Running post-run listener: _resource_record_post_run 06:12:05 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 38341120; Write bytes: 708973568; Read time: 245; Write time: 2051 06:12:05 INFO - TinderboxPrint: CPU usage
54.4% 06:12:05 INFO - TinderboxPrint: I/O read bytes / time
38,341,120 / 245 06:12:05 INFO - TinderboxPrint: I/O write bytes / time
708,973,568 / 2,051 06:12:05 INFO - TinderboxPrint: CPU idle
2,213.1 (45.6%) 06:12:05 INFO - TinderboxPrint: CPU system
978.2 (20.2%) 06:12:05 INFO - TinderboxPrint: CPU user
1,662.2 (34.2%) 06:12:05 INFO - TinderboxPrint: Swap in / out
441,929,728 / 0 06:12:05 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 395827712; Write bytes: 388491264; Read time: 29365; Write time: 834 06:12:05 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 31248896; Write bytes: 300170240; Read time: 190; Write time: 1147 06:12:06 INFO - Running post-run listener: _upload_blobber_files 06:12:06 INFO - Blob upload gear active. 06:12:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:12:06 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 06:12:06 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:12:06 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:12:06 INFO - (blobuploader) - INFO - Open directory for files ... 06:12:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:12:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:12:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:12:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:12:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:12:07 INFO - (blobuploader) - INFO - Done attempting. 06:12:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:12:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:12:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:12:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:12:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:12:09 INFO - (blobuploader) - INFO - Done attempting. 06:12:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:12:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:12:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:12:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:12:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:12:12 INFO - (blobuploader) - INFO - Done attempting. 06:12:12 INFO - (blobuploader) - INFO - Iteration through files over. 06:12:12 INFO - Return code: 0 06:12:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:12:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:12:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/29e97ccd9acf59bf2025981bf5ea2328a6b3ad56108ecfb7d1b68bfbeb1e638c9f53bd916d850bd836f8c48e7b7a2ba1394de65e70fd620d31202e60581c29fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/424c9311926b0b76bda31f890227abf64df029af4cebc2b49f817329c9e5faf4462946ad3229a78c2a4bf32e42392d5ca1ea2b8200d7cf3ce54f665c6f66d3e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/88f264c3f92b212cd505f034291cd10e68d2ccb2fccb2d440bdc5598be4ea2a7a9a7e0fda341364097e9c17465c4a951da5a5b1a81b63c21d88c467cbacf00f3"} 06:12:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:12:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:12:12 INFO - Contents: 06:12:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/29e97ccd9acf59bf2025981bf5ea2328a6b3ad56108ecfb7d1b68bfbeb1e638c9f53bd916d850bd836f8c48e7b7a2ba1394de65e70fd620d31202e60581c29fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/424c9311926b0b76bda31f890227abf64df029af4cebc2b49f817329c9e5faf4462946ad3229a78c2a4bf32e42392d5ca1ea2b8200d7cf3ce54f665c6f66d3e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/88f264c3f92b212cd505f034291cd10e68d2ccb2fccb2d440bdc5598be4ea2a7a9a7e0fda341364097e9c17465c4a951da5a5b1a81b63c21d88c467cbacf00f3"} 06:12:12 INFO - Running post-run listener: copy_logs_to_upload_dir 06:12:12 INFO - Copying logs to upload dir... 06:12:12 INFO - mkdir: /builds/slave/test/build/upload/logs 06:12:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.328302 ========= master_lag: 2.26 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-11-09 06:12:14.298545) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-09 06:12:14.306305) ========= 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.LZUrGeOmNy/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.oaaENf5HKI/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/29e97ccd9acf59bf2025981bf5ea2328a6b3ad56108ecfb7d1b68bfbeb1e638c9f53bd916d850bd836f8c48e7b7a2ba1394de65e70fd620d31202e60581c29fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/424c9311926b0b76bda31f890227abf64df029af4cebc2b49f817329c9e5faf4462946ad3229a78c2a4bf32e42392d5ca1ea2b8200d7cf3ce54f665c6f66d3e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/88f264c3f92b212cd505f034291cd10e68d2ccb2fccb2d440bdc5598be4ea2a7a9a7e0fda341364097e9c17465c4a951da5a5b1a81b63c21d88c467cbacf00f3"} build_url:https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011189 build_url: 'https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/29e97ccd9acf59bf2025981bf5ea2328a6b3ad56108ecfb7d1b68bfbeb1e638c9f53bd916d850bd836f8c48e7b7a2ba1394de65e70fd620d31202e60581c29fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/424c9311926b0b76bda31f890227abf64df029af4cebc2b49f817329c9e5faf4462946ad3229a78c2a4bf32e42392d5ca1ea2b8200d7cf3ce54f665c6f66d3e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/88f264c3f92b212cd505f034291cd10e68d2ccb2fccb2d440bdc5598be4ea2a7a9a7e0fda341364097e9c17465c4a951da5a5b1a81b63c21d88c467cbacf00f3"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-09 06:12:14.344321) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 06:12:14.344638) ========= 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.LZUrGeOmNy/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.oaaENf5HKI/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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.005207 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 06:12:14.395394) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-09 06:12:14.396168) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-09 06:12:14.396751) ========= ========= Total master_lag: 2.40 =========