builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0039 starttime: 1480487009.84 results: success (0) buildid: 20161129195959 builduid: 8ab0850f85dc472a83277bb799eb1e38 revision: 96503957841c8c7617a416719c89a06778de396a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:29.842905) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:29.843566) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:29.844057) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:29.872618) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:29.873144) ========= 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.9J2HCYhioV/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.CU78kMB9gf/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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-29 22:23:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 168M=0s 2016-11-29 22:23:30 (168 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121214 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:30.018950) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:30.019265) ========= 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.9J2HCYhioV/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.CU78kMB9gf/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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.027451 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:30.068150) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 22:23:30.068491) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 96503957841c8c7617a416719c89a06778de396a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 96503957841c8c7617a416719c89a06778de396a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9J2HCYhioV/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.CU78kMB9gf/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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-29 22:23:30,163 truncating revision to first 12 chars 2016-11-29 22:23:30,164 Setting DEBUG logging. 2016-11-29 22:23:30,164 attempt 1/10 2016-11-29 22:23:30,164 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/96503957841c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-29 22:23:31,466 unpacking tar archive at: mozilla-aurora-96503957841c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.571318 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 22:23:31.662900) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:31.663230) ========= script_repo_revision: 96503957841c8c7617a416719c89a06778de396a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:31.663639) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:31.663984) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 22:23:31.683656) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-29 22:23:31.684083) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9J2HCYhioV/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.CU78kMB9gf/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 22:23:31 INFO - MultiFileLogger online at 20161129 22:23:31 in /builds/slave/test 22:23:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 22:23:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:23:31 INFO - {'append_to_log': False, 22:23:31 INFO - 'base_work_dir': '/builds/slave/test', 22:23:31 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:23:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:23:31 INFO - 'branch': 'Mozilla-Aurora', 22:23:31 INFO - 'buildbot_json_path': 'buildprops.json', 22:23:31 INFO - 'config_files': ('talos/mac_config.py',), 22:23:31 INFO - 'default_actions': ('clobber', 22:23:31 INFO - 'read-buildbot-config', 22:23:31 INFO - 'download-and-extract', 22:23:31 INFO - 'populate-webroot', 22:23:31 INFO - 'create-virtualenv', 22:23:31 INFO - 'install', 22:23:31 INFO - 'run-tests'), 22:23:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:23:31 INFO - 'download_minidump_stackwalk': True, 22:23:31 INFO - 'download_symbols': 'ondemand', 22:23:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:23:31 INFO - 'tooltool.py': '/tools/tooltool.py', 22:23:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:23:31 INFO - '/tools/misc-python/virtualenv.py')}, 22:23:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:23:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:23:31 INFO - 'installer_path': 'installer.exe', 22:23:31 INFO - 'log_level': 'info', 22:23:31 INFO - 'log_name': 'talos', 22:23:31 INFO - 'log_to_console': True, 22:23:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:23:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:23:31 INFO - 'opt_config_files': (), 22:23:31 INFO - 'pip_index': False, 22:23:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:23:31 INFO - 'cmd': ('bash', 22:23:31 INFO - '-c', 22:23:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:23:31 INFO - 'enabled': True, 22:23:31 INFO - 'halt_on_failure': False, 22:23:31 INFO - 'name': 'check_screen_resolution'},), 22:23:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:23:31 INFO - 'cmd': ('bash', 22:23:31 INFO - '-c', 22:23:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:23:31 INFO - 'enabled': True, 22:23:31 INFO - 'halt_on_failure': False, 22:23:31 INFO - 'name': 'check_screen_resolution'},), 22:23:31 INFO - 'run_cmd_checks_enabled': True, 22:23:31 INFO - 'sps_profile': False, 22:23:31 INFO - 'sps_profile_interval': 0, 22:23:31 INFO - 'suite': 'dromaeojs-e10s', 22:23:31 INFO - 'system_bits': '32', 22:23:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:23:31 INFO - 'title': 't-yosemite-r7-0039', 22:23:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:23:31 INFO - 'use_talos_json': True, 22:23:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:23:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:23:31 INFO - 'work_dir': 'build'} 22:23:31 INFO - [mozharness: 2016-11-30 06:23:31.887192Z] Running clobber step. 22:23:31 INFO - Running pre-action listener: _resource_record_pre_action 22:23:31 INFO - Running main action method: clobber 22:23:31 INFO - rmtree: /builds/slave/test/build 22:23:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:23:34 INFO - Running post-action listener: _resource_record_post_action 22:23:34 INFO - [mozharness: 2016-11-30 06:23:34.386763Z] Finished clobber step (success) 22:23:34 INFO - [mozharness: 2016-11-30 06:23:34.386881Z] Running read-buildbot-config step. 22:23:34 INFO - Running pre-action listener: _resource_record_pre_action 22:23:34 INFO - Running main action method: read_buildbot_config 22:23:34 INFO - Using buildbot properties: 22:23:34 INFO - { 22:23:34 INFO - "project": "", 22:23:34 INFO - "product": "firefox", 22:23:34 INFO - "script_repo_revision": "production", 22:23:34 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 22:23:34 INFO - "repository": "", 22:23:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 22:23:34 INFO - "buildid": "20161129195959", 22:23:34 INFO - "pgo_build": "False", 22:23:34 INFO - "basedir": "/builds/slave/test", 22:23:34 INFO - "buildnumber": 34, 22:23:34 INFO - "slavename": "t-yosemite-r7-0039", 22:23:34 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 22:23:34 INFO - "platform": "yosemite_r7", 22:23:34 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 22:23:34 INFO - "branch": "mozilla-aurora", 22:23:34 INFO - "repo_path": "releases/mozilla-aurora", 22:23:34 INFO - "revision": "96503957841c8c7617a416719c89a06778de396a", 22:23:34 INFO - "stage_platform": "macosx64", 22:23:34 INFO - "builduid": "8ab0850f85dc472a83277bb799eb1e38", 22:23:34 INFO - "slavebuilddir": "test" 22:23:34 INFO - } 22:23:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 22:23:34 INFO - Running post-action listener: _resource_record_post_action 22:23:34 INFO - [mozharness: 2016-11-30 06:23:34.390301Z] Finished read-buildbot-config step (success) 22:23:34 INFO - [mozharness: 2016-11-30 06:23:34.390390Z] Running download-and-extract step. 22:23:34 INFO - Running pre-action listener: _resource_record_pre_action 22:23:34 INFO - Running main action method: download_and_extract 22:23:34 INFO - mkdir: /builds/slave/test/build/tests 22:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:34 INFO - trying https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 22:23:34 INFO - Downloading https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 22:23:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 22:23:35 INFO - Downloaded 1199 bytes. 22:23:35 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 22:23:35 INFO - Using the following test package requirements: 22:23:35 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 22:23:35 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:23:35 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 22:23:35 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:23:35 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 22:23:35 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:23:35 INFO - u'jsshell-mac.zip'], 22:23:35 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:23:35 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 22:23:35 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 22:23:35 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:23:35 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 22:23:35 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:23:35 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 22:23:35 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:23:35 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 22:23:35 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:23:35 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 22:23:35 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 22:23:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 22:23:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:23:35 INFO - Fetch https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 22:23:35 INFO - Http code: 200 22:23:35 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 22:23:35 INFO - x-amz-cf-id: b1UNdoQvBXpFNWTU4hcXR6J7aUaSGmf6Sbb9RN2MY52q3pfn9lOGkA== 22:23:35 INFO - x-amz-version-id: KH8RTMEoNzOQfBh29dxyKIIMRgP.fvst 22:23:36 INFO - Expected file size: 16285135 22:23:36 INFO - Obtained file size: 16285135 22:23:43 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 22:23:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 22:23:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:23:43 INFO - Fetch https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 22:23:45 INFO - Http code: 200 22:23:45 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 22:23:45 INFO - x-amz-cf-id: Z_uQScr_JyF2LCmLd-JMfJ_wHgkjzhrDD_Hu7S3HfmrcBW3_99dn3g== 22:23:45 INFO - x-amz-version-id: KH8RTMEoNzOQfBh29dxyKIIMRgP.fvst 22:23:46 INFO - Expected file size: 16285135 22:23:46 INFO - Obtained file size: 16285135 22:23:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 22:23:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 22:23:52 INFO - Fetch https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 22:23:53 INFO - Http code: 200 22:23:53 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 22:23:53 INFO - x-amz-cf-id: IAsL_gYr8V5QoPuJ7N-qJ-j5yNRZ4rBcrMy4tbDzaeCpiRMk2_bgEQ== 22:23:53 INFO - x-amz-version-id: q.A7Vhr.U0WcK0jZeTfVlaTpZKatDnjE 22:23:53 INFO - Expected file size: 14409038 22:23:53 INFO - Obtained file size: 14409038 22:23:54 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')]} 22:23:54 INFO - trying https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 22:23:54 INFO - Downloading https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 22:23:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 22:23:56 INFO - Downloaded 109716068 bytes. 22:23:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 22:23:56 INFO - mkdir: /builds/slave/test/properties 22:23:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:23:56 INFO - Writing to file /builds/slave/test/properties/build_url 22:23:56 INFO - Contents: 22:23:56 INFO - build_url:https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 22:23:57 INFO - Running post-action listener: _resource_record_post_action 22:23:57 INFO - Running post-action listener: set_extra_try_arguments 22:23:57 INFO - [mozharness: 2016-11-30 06:23:57.567133Z] Finished download-and-extract step (success) 22:23:57 INFO - [mozharness: 2016-11-30 06:23:57.567251Z] Running populate-webroot step. 22:23:57 INFO - Running pre-action listener: _resource_record_pre_action 22:23:57 INFO - Running main action method: populate_webroot 22:23:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:23:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:23:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:23:57 INFO - '--tppagecycles', 22:23:57 INFO - '7'], 22:23:57 INFO - 'tests': ['tsvgm']}}, 22:23:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:23:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:23:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:23:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:23:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:23:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:23:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:57 INFO - 'talos_options': ['--disable-e10s'], 22:23:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:23:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:23:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:57 INFO - 'talos_options': ['--disable-e10s'], 22:23:57 INFO - 'tests': ['damp', 'tps']}, 22:23:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:57 INFO - 'tests': ['damp', 'tps']}, 22:23:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:23:57 INFO - 'tests': ['dromaeo_dom']}, 22:23:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:23:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:23:57 INFO - 'tests': ['basic_compositor_video']}, 22:23:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:23:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:23:57 INFO - 'tests': ['a11yr', 22:23:57 INFO - 'ts_paint', 22:23:57 INFO - 'tpaint', 22:23:57 INFO - 'sessionrestore', 22:23:57 INFO - 'sessionrestore_no_auto_restore', 22:23:57 INFO - 'tabpaint']}, 22:23:57 INFO - 'other-e10s': {'tests': ['a11yr', 22:23:57 INFO - 'ts_paint', 22:23:57 INFO - 'tpaint', 22:23:57 INFO - 'sessionrestore', 22:23:57 INFO - 'sessionrestore_no_auto_restore', 22:23:57 INFO - 'tabpaint']}, 22:23:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:23:57 INFO - 'ts_paint', 22:23:57 INFO - 'tpaint', 22:23:57 INFO - 'sessionrestore', 22:23:57 INFO - 'sessionrestore_no_auto_restore', 22:23:57 INFO - 'tabpaint']}, 22:23:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:23:57 INFO - 'ts_paint', 22:23:57 INFO - 'tpaint', 22:23:57 INFO - 'sessionrestore', 22:23:57 INFO - 'sessionrestore_no_auto_restore', 22:23:57 INFO - 'tabpaint']}, 22:23:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:23:57 INFO - 'tests': ['a11yr', 22:23:57 INFO - 'ts_paint', 22:23:57 INFO - 'tpaint', 22:23:57 INFO - 'sessionrestore', 22:23:57 INFO - 'sessionrestore_no_auto_restore', 22:23:57 INFO - 'tabpaint']}, 22:23:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:23:57 INFO - 'tests': ['a11yr', 22:23:57 INFO - 'ts_paint', 22:23:57 INFO - 'tpaint', 22:23:57 INFO - 'sessionrestore', 22:23:57 INFO - 'sessionrestore_no_auto_restore', 22:23:57 INFO - 'tabpaint']}, 22:23:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:23:57 INFO - 'tests': ['tsvgx', 22:23:57 INFO - 'tsvgr_opacity', 22:23:57 INFO - 'tart', 22:23:57 INFO - 'tscrollx', 22:23:57 INFO - 'cart']}, 22:23:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:23:57 INFO - 'tsvgr_opacity', 22:23:57 INFO - 'tart', 22:23:57 INFO - 'tscrollx', 22:23:57 INFO - 'cart']}, 22:23:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:57 INFO - 'talos_options': ['--disable-e10s'], 22:23:57 INFO - 'tests': ['tp5o']}, 22:23:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:57 INFO - 'tests': ['tp5o']}, 22:23:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:57 INFO - 'talos_options': ['--disable-e10s', 22:23:57 INFO - '--xperf_path', 22:23:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:23:57 INFO - 'tests': ['tp5n']}, 22:23:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:57 INFO - 'talos_options': ['--xperf_path', 22:23:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:23:57 INFO - 'tests': ['tp5n']}}, 22:23:57 INFO - 'talos.zip': {'path': '', 22:23:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:23:57 INFO - Running post-action listener: _resource_record_post_action 22:23:57 INFO - [mozharness: 2016-11-30 06:23:57.585649Z] Finished populate-webroot step (success) 22:23:57 INFO - [mozharness: 2016-11-30 06:23:57.585761Z] Running create-virtualenv step. 22:23:57 INFO - Running pre-action listener: _resource_record_pre_action 22:23:57 INFO - Running main action method: create_virtualenv 22:23:57 INFO - Creating virtualenv /builds/slave/test/build/venv 22:23:57 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 22:23:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:23:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:23:57 INFO - Using real prefix '/tools/python27' 22:23:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:23:58 INFO - Installing distribute.............................................................................................................................................................................................done. 22:24:01 INFO - Installing pip.................done. 22:24:01 INFO - Return code: 0 22:24:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:24:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:24:01 INFO - Reading from file tmpfile_stdout 22:24:01 INFO - Output received: 22:24:01 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) 22:24:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:24:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:01 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 0x103ab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd5a5a3a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038c4d30>, '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 0x10372adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038bcb20>, '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.9J2HCYhioV/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.CU78kMB9gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 22:24:01 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 22:24:01 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 22:24:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9J2HCYhioV/Render', 22:24:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:01 INFO - 'HOME': '/Users/cltbld', 22:24:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:01 INFO - 'LOGNAME': 'cltbld', 22:24:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:01 INFO - 'MOZ_NO_REMOTE': '1', 22:24:01 INFO - 'NO_EM_RESTART': '1', 22:24:01 INFO - 'PAGER': '/bin/cat', 22:24:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:01 INFO - 'PWD': '/builds/slave/test', 22:24:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:01 INFO - 'SHELL': '/bin/bash', 22:24:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU78kMB9gf/Listeners', 22:24:01 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 22:24:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:01 INFO - 'USER': 'cltbld', 22:24:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:01 INFO - 'XPC_FLAGS': '0x0', 22:24:01 INFO - 'XPC_SERVICE_NAME': '0', 22:24:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:01 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 22:24:01 INFO - Cleaning up... 22:24:01 INFO - Return code: 0 22:24:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:24:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:01 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 0x103ab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd5a5a3a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038c4d30>, '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 0x10372adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038bcb20>, '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.9J2HCYhioV/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.CU78kMB9gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 22:24:01 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 22:24:01 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 22:24:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9J2HCYhioV/Render', 22:24:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:01 INFO - 'HOME': '/Users/cltbld', 22:24:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:01 INFO - 'LOGNAME': 'cltbld', 22:24:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:01 INFO - 'MOZ_NO_REMOTE': '1', 22:24:01 INFO - 'NO_EM_RESTART': '1', 22:24:01 INFO - 'PAGER': '/bin/cat', 22:24:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:01 INFO - 'PWD': '/builds/slave/test', 22:24:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:01 INFO - 'SHELL': '/bin/bash', 22:24:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU78kMB9gf/Listeners', 22:24:01 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 22:24:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:01 INFO - 'USER': 'cltbld', 22:24:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:01 INFO - 'XPC_FLAGS': '0x0', 22:24:01 INFO - 'XPC_SERVICE_NAME': '0', 22:24:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:02 INFO - Downloading/unpacking psutil>=3.1.1 22:24:02 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 22:24:02 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 22:24:02 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 22:24:02 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 22:24:02 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 22:24:02 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 22:24:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:24:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:24:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:24:05 INFO - Installing collected packages: psutil 22:24:05 INFO - Running setup.py install for psutil 22:24:05 INFO - building 'psutil._psutil_osx' extension 22:24:05 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 22:24:06 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 22:24:06 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 22:24:06 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 22:24:06 INFO - building 'psutil._psutil_posix' extension 22:24:06 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 22:24:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:24:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:24:06 INFO - ^ 22:24:06 INFO - 1 warning generated. 22:24:06 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 22:24:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:24:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:24:06 INFO - Successfully installed psutil 22:24:06 INFO - Cleaning up... 22:24:06 INFO - Return code: 0 22:24:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:24:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103ab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd5a5a3a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038c4d30>, '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 0x10372adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038bcb20>, '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.9J2HCYhioV/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.CU78kMB9gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 22:24:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:24:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:24:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9J2HCYhioV/Render', 22:24:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:06 INFO - 'HOME': '/Users/cltbld', 22:24:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:06 INFO - 'LOGNAME': 'cltbld', 22:24:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:06 INFO - 'MOZ_NO_REMOTE': '1', 22:24:06 INFO - 'NO_EM_RESTART': '1', 22:24:06 INFO - 'PAGER': '/bin/cat', 22:24:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:06 INFO - 'PWD': '/builds/slave/test', 22:24:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:06 INFO - 'SHELL': '/bin/bash', 22:24:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU78kMB9gf/Listeners', 22:24:06 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 22:24:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:06 INFO - 'USER': 'cltbld', 22:24:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:06 INFO - 'XPC_FLAGS': '0x0', 22:24:06 INFO - 'XPC_SERVICE_NAME': '0', 22:24:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:24:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:24:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:24:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:24:09 INFO - Installing collected packages: mozsystemmonitor 22:24:09 INFO - Running setup.py install for mozsystemmonitor 22:24:09 INFO - Successfully installed mozsystemmonitor 22:24:09 INFO - Cleaning up... 22:24:09 INFO - Return code: 0 22:24:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:24:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103ab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd5a5a3a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038c4d30>, '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 0x10372adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038bcb20>, '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.9J2HCYhioV/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.CU78kMB9gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 22:24:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:24:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:24:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9J2HCYhioV/Render', 22:24:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:09 INFO - 'HOME': '/Users/cltbld', 22:24:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:09 INFO - 'LOGNAME': 'cltbld', 22:24:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:09 INFO - 'MOZ_NO_REMOTE': '1', 22:24:09 INFO - 'NO_EM_RESTART': '1', 22:24:09 INFO - 'PAGER': '/bin/cat', 22:24:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:09 INFO - 'PWD': '/builds/slave/test', 22:24:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:09 INFO - 'SHELL': '/bin/bash', 22:24:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU78kMB9gf/Listeners', 22:24:09 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 22:24:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:09 INFO - 'USER': 'cltbld', 22:24:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:09 INFO - 'XPC_FLAGS': '0x0', 22:24:09 INFO - 'XPC_SERVICE_NAME': '0', 22:24:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:10 INFO - Downloading/unpacking jsonschema==2.5.1 22:24:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:24:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:24:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:13 INFO - Downloading functools32-3.2.3-2.tar.gz 22:24:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:24:13 INFO - warning: no files found matching '*.txt' 22:24:13 INFO - no previously-included directories found matching 'build' 22:24:13 INFO - no previously-included directories found matching 'dist' 22:24:13 INFO - no previously-included directories found matching '.git*' 22:24:13 INFO - Installing collected packages: jsonschema, functools32 22:24:13 INFO - Running setup.py install for functools32 22:24:13 INFO - warning: no files found matching '*.txt' 22:24:13 INFO - no previously-included directories found matching 'build' 22:24:13 INFO - no previously-included directories found matching 'dist' 22:24:13 INFO - no previously-included directories found matching '.git*' 22:24:13 INFO - Successfully installed jsonschema functools32 22:24:13 INFO - Cleaning up... 22:24:13 INFO - Return code: 0 22:24:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:24:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103ab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd5a5a3a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038c4d30>, '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 0x10372adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038bcb20>, '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.9J2HCYhioV/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.CU78kMB9gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 22:24:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:24:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:24:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9J2HCYhioV/Render', 22:24:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:13 INFO - 'HOME': '/Users/cltbld', 22:24:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:13 INFO - 'LOGNAME': 'cltbld', 22:24:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:13 INFO - 'MOZ_NO_REMOTE': '1', 22:24:13 INFO - 'NO_EM_RESTART': '1', 22:24:13 INFO - 'PAGER': '/bin/cat', 22:24:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:13 INFO - 'PWD': '/builds/slave/test', 22:24:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:13 INFO - 'SHELL': '/bin/bash', 22:24:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU78kMB9gf/Listeners', 22:24:13 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 22:24:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:13 INFO - 'USER': 'cltbld', 22:24:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:13 INFO - 'XPC_FLAGS': '0x0', 22:24:13 INFO - 'XPC_SERVICE_NAME': '0', 22:24:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:24:13 INFO - Cleaning up... 22:24:13 INFO - Return code: 0 22:24:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:24:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103ab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd5a5a3a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038c4d30>, '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 0x10372adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038bcb20>, '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.9J2HCYhioV/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.CU78kMB9gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 22:24:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:24:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:24:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9J2HCYhioV/Render', 22:24:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:13 INFO - 'HOME': '/Users/cltbld', 22:24:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:13 INFO - 'LOGNAME': 'cltbld', 22:24:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:13 INFO - 'MOZ_NO_REMOTE': '1', 22:24:13 INFO - 'NO_EM_RESTART': '1', 22:24:13 INFO - 'PAGER': '/bin/cat', 22:24:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:13 INFO - 'PWD': '/builds/slave/test', 22:24:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:13 INFO - 'SHELL': '/bin/bash', 22:24:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU78kMB9gf/Listeners', 22:24:13 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 22:24:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:13 INFO - 'USER': 'cltbld', 22:24:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:13 INFO - 'XPC_FLAGS': '0x0', 22:24:13 INFO - 'XPC_SERVICE_NAME': '0', 22:24:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:14 INFO - Downloading/unpacking blobuploader==1.2.4 22:24:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:16 INFO - Downloading blobuploader-1.2.4.tar.gz 22:24:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:24:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:24:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:24:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:17 INFO - Downloading docopt-0.6.1.tar.gz 22:24:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:24:17 INFO - Installing collected packages: blobuploader, requests, docopt 22:24:17 INFO - Running setup.py install for blobuploader 22:24:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:24:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:24:18 INFO - Running setup.py install for requests 22:24:18 INFO - Running setup.py install for docopt 22:24:18 INFO - Successfully installed blobuploader requests docopt 22:24:18 INFO - Cleaning up... 22:24:18 INFO - Return code: 0 22:24:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:24:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:18 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 0x103ab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd5a5a3a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038c4d30>, '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 0x10372adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038bcb20>, '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.9J2HCYhioV/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.CU78kMB9gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 22:24:18 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 22:24:18 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 22:24:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9J2HCYhioV/Render', 22:24:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:18 INFO - 'HOME': '/Users/cltbld', 22:24:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:18 INFO - 'LOGNAME': 'cltbld', 22:24:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:18 INFO - 'MOZ_NO_REMOTE': '1', 22:24:18 INFO - 'NO_EM_RESTART': '1', 22:24:18 INFO - 'PAGER': '/bin/cat', 22:24:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:18 INFO - 'PWD': '/builds/slave/test', 22:24:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:18 INFO - 'SHELL': '/bin/bash', 22:24:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU78kMB9gf/Listeners', 22:24:18 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 22:24:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:18 INFO - 'USER': 'cltbld', 22:24:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:18 INFO - 'XPC_FLAGS': '0x0', 22:24:18 INFO - 'XPC_SERVICE_NAME': '0', 22:24:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:24:19 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ZdBFsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:24:19 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-IU3OH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:24:19 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ty1f4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:24:19 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-890sDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:24:19 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-0ZV_Tn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:24:19 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-JrGSbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:24:19 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-DYToi9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:24:19 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-fQfYze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:24:19 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-KJp5T3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:24:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:24:20 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-cpdS2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:24:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:24:20 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-_gcdKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:24:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:24:20 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-KCdLyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:24:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:24:20 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-wgNdI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:24:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:24:20 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-yaCMEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:24:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:20 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-oq86rz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:24:20 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-R7XIc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:24:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:24:20 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-NS5lBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:24:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:24:20 INFO - Running setup.py install for manifestparser 22:24:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:24:21 INFO - Running setup.py install for mozcrash 22:24:21 INFO - Running setup.py install for mozdebug 22:24:21 INFO - Running setup.py install for mozdevice 22:24:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:24:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:24:21 INFO - Running setup.py install for mozfile 22:24:21 INFO - Running setup.py install for mozhttpd 22:24:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:24:21 INFO - Running setup.py install for mozinfo 22:24:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:24:21 INFO - Running setup.py install for mozInstall 22:24:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:24:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:24:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:24:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:24:22 INFO - Running setup.py install for mozleak 22:24:22 INFO - Running setup.py install for mozlog 22:24:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:24:22 INFO - Running setup.py install for moznetwork 22:24:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:24:22 INFO - Running setup.py install for mozprocess 22:24:22 INFO - Running setup.py install for mozprofile 22:24:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:24:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:24:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:24:22 INFO - Running setup.py install for mozrunner 22:24:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:24:22 INFO - Running setup.py install for mozscreenshot 22:24:23 INFO - Running setup.py install for moztest 22:24:23 INFO - Running setup.py install for mozversion 22:24:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:24:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:24:23 INFO - Cleaning up... 22:24:23 INFO - Return code: 0 22:24:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:24: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')]} 22:24:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24: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')]} 22:24:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:23 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 0x103ab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd5a5a3a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038c4d30>, '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 0x10372adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038bcb20>, '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.9J2HCYhioV/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.CU78kMB9gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 22:24:23 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 22:24:23 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 22:24:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9J2HCYhioV/Render', 22:24:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:23 INFO - 'HOME': '/Users/cltbld', 22:24:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:23 INFO - 'LOGNAME': 'cltbld', 22:24:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:23 INFO - 'MOZ_NO_REMOTE': '1', 22:24:23 INFO - 'NO_EM_RESTART': '1', 22:24:23 INFO - 'PAGER': '/bin/cat', 22:24:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:23 INFO - 'PWD': '/builds/slave/test', 22:24:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:23 INFO - 'SHELL': '/bin/bash', 22:24:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU78kMB9gf/Listeners', 22:24:23 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 22:24:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:23 INFO - 'USER': 'cltbld', 22:24:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:23 INFO - 'XPC_FLAGS': '0x0', 22:24:23 INFO - 'XPC_SERVICE_NAME': '0', 22:24:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:24:23 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-zVGWW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:24:23 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)) 22:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:24:23 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-63WxUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:24:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 22:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:24:23 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-m3087l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:24:24 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)) 22:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:24:24 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-Cr69d2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:24:24 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)) 22:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:24:24 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-DAQ8nm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:24:24 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)) 22:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:24:24 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-YQ9pzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:24:24 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)) 22:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:24:24 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-1syf5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:24:24 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)) 22:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:24:24 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-AxSL21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:24:24 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)) 22:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:24:24 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-heEHIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:24:24 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)) 22:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:24:24 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-GDGz3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:24:24 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)) 22:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:24:24 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-OYmQD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:24:24 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)) 22:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:24:24 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-hGhuo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:24:24 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)) 22:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:24:24 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-tD8Bfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:24:25 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)) 22:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:24:25 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-Yg268Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:24:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 22:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:25 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-mUaWUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:25 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)) 22:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:24:25 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-JbVywb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:24:25 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)) 22:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:24:25 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-zgbWNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:24:25 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)) 22:24:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:24:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:24:25 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)) 22:24:25 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)) 22:24:25 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)) 22:24:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:24:25 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 22:24:25 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 22:24:25 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 22:24:25 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 22:24:25 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 22:24:25 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 22:24:27 INFO - Downloading blessings-1.6.tar.gz 22:24:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:24:28 INFO - Installing collected packages: blessings 22:24:28 INFO - Running setup.py install for blessings 22:24:28 INFO - Successfully installed blessings 22:24:28 INFO - Cleaning up... 22:24:28 INFO - Return code: 0 22:24:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:24:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:24:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:24:28 INFO - Reading from file tmpfile_stdout 22:24:28 INFO - Current package versions: 22:24:28 INFO - blessings == 1.6 22:24:28 INFO - blobuploader == 1.2.4 22:24:28 INFO - docopt == 0.6.1 22:24:28 INFO - functools32 == 3.2.3-2 22:24:28 INFO - jsonschema == 2.5.1 22:24:28 INFO - manifestparser == 1.1 22:24:28 INFO - mozInstall == 1.12 22:24:28 INFO - mozcrash == 1.0 22:24:28 INFO - mozdebug == 0.1 22:24:28 INFO - mozdevice == 0.48 22:24:28 INFO - mozfile == 1.2 22:24:28 INFO - mozhttpd == 0.7 22:24:28 INFO - mozinfo == 0.9 22:24:28 INFO - mozleak == 0.1 22:24:28 INFO - mozlog == 3.4 22:24:28 INFO - moznetwork == 0.27 22:24:28 INFO - mozprocess == 0.23 22:24:28 INFO - mozprofile == 0.28 22:24:28 INFO - mozrunner == 6.13 22:24:28 INFO - mozscreenshot == 0.1 22:24:28 INFO - mozsystemmonitor == 0.3 22:24:28 INFO - moztest == 0.7 22:24:28 INFO - mozversion == 1.4 22:24:28 INFO - psutil == 3.1.1 22:24:28 INFO - requests == 1.2.3 22:24:28 INFO - wsgiref == 0.1.2 22:24:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:24:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24: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')]} 22:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:29 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 0x103ab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd5a5a3a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038c4d30>, '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 0x10372adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038bcb20>, '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.9J2HCYhioV/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.CU78kMB9gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 22:24:29 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 22:24:29 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 22:24:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9J2HCYhioV/Render', 22:24:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:29 INFO - 'HOME': '/Users/cltbld', 22:24:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:29 INFO - 'LOGNAME': 'cltbld', 22:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:29 INFO - 'MOZ_NO_REMOTE': '1', 22:24:29 INFO - 'NO_EM_RESTART': '1', 22:24:29 INFO - 'PAGER': '/bin/cat', 22:24:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:29 INFO - 'PWD': '/builds/slave/test', 22:24:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:29 INFO - 'SHELL': '/bin/bash', 22:24:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU78kMB9gf/Listeners', 22:24:29 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 22:24:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:29 INFO - 'USER': 'cltbld', 22:24:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:29 INFO - 'XPC_FLAGS': '0x0', 22:24:29 INFO - 'XPC_SERVICE_NAME': '0', 22:24:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:29 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)) 22:24:29 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)) 22:24:29 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)) 22:24:29 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)) 22:24:29 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)) 22:24:29 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)) 22:24:29 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)) 22:24:29 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)) 22:24:29 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)) 22:24:29 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)) 22:24:29 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)) 22:24:29 INFO - Cleaning up... 22:24:29 INFO - Return code: 0 22:24:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:24: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')]} 22:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24: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')]} 22:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:30 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 0x103ab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd5a5a3a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038c4d30>, '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 0x10372adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038bcb20>, '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.9J2HCYhioV/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.CU78kMB9gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 22:24:30 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 22:24:30 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 22:24:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9J2HCYhioV/Render', 22:24:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:30 INFO - 'HOME': '/Users/cltbld', 22:24:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:30 INFO - 'LOGNAME': 'cltbld', 22:24:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:30 INFO - 'MOZ_NO_REMOTE': '1', 22:24:30 INFO - 'NO_EM_RESTART': '1', 22:24:30 INFO - 'PAGER': '/bin/cat', 22:24:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:30 INFO - 'PWD': '/builds/slave/test', 22:24:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:30 INFO - 'SHELL': '/bin/bash', 22:24:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU78kMB9gf/Listeners', 22:24:30 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 22:24:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:30 INFO - 'USER': 'cltbld', 22:24:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:30 INFO - 'XPC_FLAGS': '0x0', 22:24:30 INFO - 'XPC_SERVICE_NAME': '0', 22:24:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:24:30 INFO - Cleaning up... 22:24:30 INFO - Return code: 0 22:24:30 INFO - Running post-action listener: _resource_record_post_action 22:24:30 INFO - Running post-action listener: _start_resource_monitoring 22:24:30 INFO - Starting resource monitoring. 22:24:30 INFO - [mozharness: 2016-11-30 06:24:30.361000Z] Finished create-virtualenv step (success) 22:24:30 INFO - [mozharness: 2016-11-30 06:24:30.362043Z] Running install step. 22:24:30 INFO - Running pre-action listener: _resource_record_pre_action 22:24:30 INFO - Running main action method: install 22:24:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:24:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:24:30 INFO - Reading from file tmpfile_stdout 22:24:30 INFO - Detecting whether we're running mozinstall >=1.0... 22:24:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:24:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:24:30 INFO - Reading from file tmpfile_stdout 22:24:30 INFO - Output received: 22:24:30 INFO - Usage: mozinstall [options] installer 22:24:30 INFO - Options: 22:24:30 INFO - -h, --help show this help message and exit 22:24:30 INFO - -d DEST, --destination=DEST 22:24:30 INFO - Directory to install application into. [default: 22:24:30 INFO - "/builds/slave/test"] 22:24:30 INFO - --app=APP Application being installed. [default: firefox] 22:24:30 INFO - mkdir: /builds/slave/test/build/application 22:24:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:24:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 22:25:02 INFO - Reading from file tmpfile_stdout 22:25:02 INFO - Output received: 22:25:02 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 22:25:02 INFO - Running post-action listener: _resource_record_post_action 22:25:02 INFO - [mozharness: 2016-11-30 06:25:02.342160Z] Finished install step (success) 22:25:02 INFO - [mozharness: 2016-11-30 06:25:02.342309Z] Running run-tests step. 22:25:02 INFO - Running pre-action listener: _resource_record_pre_action 22:25:02 INFO - Running main action method: run_tests 22:25:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:25:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:25:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:25:02 INFO - 2016-11-29 22:25:02.357 screenresolution[1628:7429] starting screenresolution argv=screenresolution get 22:25:02 INFO - 2016-11-29 22:25:02.360 screenresolution[1628:7429] Display 0: 1600x1200x32@60 22:25:02 INFO - 2016-11-29 22:25:02.365 screenresolution[1629:7432] starting screenresolution argv=screenresolution list 22:25:02 INFO - Available Modes on Display 0 22:25:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:25:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:25:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:25:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:25:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:25:02 INFO - Intel Iris: 22:25:02 INFO - Chipset Model: Intel Iris 22:25:02 INFO - Type: GPU 22:25:02 INFO - Bus: Built-In 22:25:02 INFO - VRAM (Dynamic, Max): 1536 MB 22:25:02 INFO - Vendor: Intel (0x8086) 22:25:02 INFO - Device ID: 0x0a2e 22:25:02 INFO - Revision ID: 0x0009 22:25:02 INFO - Displays: 22:25:02 INFO - Display: 22:25:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:25:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:25:02 INFO - Display Serial Number: 1600x1200 60 22:25:02 INFO - Main Display: Yes 22:25:02 INFO - Mirror: Off 22:25:02 INFO - Online: Yes 22:25:02 INFO - Rotation: Supported 22:25:02 INFO - Return code: 0 22:25:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:25:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:25:02 INFO - Python 2.7.3 22:25:02 INFO - Return code: 0 22:25:02 INFO - grabbing minidump binary from tooltool 22:25:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:25:02 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 0x1038c4d30>, '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 0x10372adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038bcb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:25:02 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 22:25:02 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 22:25:02 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 22:25:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:25:02 INFO - Return code: 0 22:25:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:25:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:25:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:25:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:25:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:25:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--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 22:25:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0039 --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 22:25:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9J2HCYhioV/Render', 22:25:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:25:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:25:02 INFO - 'HOME': '/Users/cltbld', 22:25:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:25:02 INFO - 'LOGNAME': 'cltbld', 22:25:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:25:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:25:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:25:02 INFO - 'MOZ_NO_REMOTE': '1', 22:25:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:25:02 INFO - 'NO_EM_RESTART': '1', 22:25:02 INFO - 'PAGER': '/bin/cat', 22:25:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:25:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:25:02 INFO - 'PWD': '/builds/slave/test', 22:25:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:25:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:25:02 INFO - 'SHELL': '/bin/bash', 22:25:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CU78kMB9gf/Listeners', 22:25:02 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 22:25:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:25:02 INFO - 'USER': 'cltbld', 22:25:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:25:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:25:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:25:02 INFO - 'XPC_FLAGS': '0x0', 22:25:02 INFO - 'XPC_SERVICE_NAME': '0', 22:25:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:25:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--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 22:25:02 INFO - mozversion application_buildid: 20161129195959 22:25:02 INFO - mozversion application_changeset: 96503957841c8c7617a416719c89a06778de396a 22:25:02 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:25:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:25:02 INFO - mozversion application_name: Firefox 22:25:02 INFO - mozversion application_remotingname: firefox-dev 22:25:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:25:02 INFO - mozversion application_vendor: Mozilla 22:25:02 INFO - mozversion application_version: 52.0a2 22:25:02 INFO - mozversion platform_buildid: 20161129195959 22:25:02 INFO - mozversion platform_changeset: 96503957841c8c7617a416719c89a06778de396a 22:25:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:25:02 INFO - mozversion platform_version: 52.0a2 22:25:02 INFO - using testdate: 1480487102 22:25:02 INFO - actual date: 1480487102 22:25:02 INFO - starting webserver on 'localhost:49242' 22:25:02 INFO - SUITE-START | Running 2 tests 22:25:02 INFO - TEST-START | dromaeo_css 22:25:02 INFO - operating with platform_type : mac_ 22:25:02 INFO - Initialising browser for dromaeo_css test... 22:25:02 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpzr19i5/profile http://localhost:49242/getInfo.html) 22:25:06 INFO - PROCESS | 1639 | 2016-11-29 22:25:06.028 plugin-container[1641:7620] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e37, name = 'com.apple.tsm.portname' 22:25:06 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:25:06 INFO - PROCESS | 1639 | 2016-11-29 22:25:06.031 plugin-container[1641:7620] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f5b, name = 'com.apple.CFPasteboardClient' 22:25:06 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:25:06 INFO - PROCESS | 1639 | 2016-11-29 22:25:06.031 plugin-container[1641:7620] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:25:06 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 22:25:06 INFO - PROCESS | 1639 | colorDepth:24 22:25:06 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 22:25:06 INFO - PROCESS | 1639 | __metrics 22:25:06 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 22:25:06 INFO - TEST-INFO | 1639: exit 0 22:25:06 INFO - Browser initialized. 22:25:06 INFO - Running cycle 1/1 for dromaeo_css test... 22:25:06 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpzr19i5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:25:09 INFO - PROCESS | 1642 | 2016-11-29 22:25:09.400 plugin-container[1643:7750] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 22:25:09 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:25:09 INFO - PROCESS | 1642 | 2016-11-29 22:25:09.400 plugin-container[1643:7750] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 22:25:09 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:25:09 INFO - PROCESS | 1642 | 2016-11-29 22:25:09.400 plugin-container[1643:7750] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:25:19 INFO - PROCESS | 1642 | RSS: Main: 229617664 22:25:19 INFO - PROCESS | 1642 | 22:26:08 INFO - PROCESS | 1642 | 1480487168736 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] 22:28:23 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 22:28:24 INFO - PROCESS | 1642 | RSS: Main: 232316928 22:28:24 INFO - PROCESS | 1642 | 22:31:27 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 22:31:28 INFO - PROCESS | 1642 | RSS: Main: 244641792 22:31:28 INFO - PROCESS | 1642 | 22:34:30 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 22:34:30 INFO - PROCESS | 1642 | RSS: Main: 248229888 22:34:30 INFO - PROCESS | 1642 | 22:37:32 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 22:37:33 INFO - PROCESS | 1642 | RSS: Main: 247537664 22:37:33 INFO - PROCESS | 1642 | 22:40:36 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 22:40:36 INFO - PROCESS | 1642 | RSS: Main: 250949632 22:40:36 INFO - PROCESS | 1642 | 22:43:39 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 22:43:39 INFO - PROCESS | 1642 | RSS: Main: 252370944 22:43:39 INFO - PROCESS | 1642 | 22:43:39 INFO - PROCESS | 1642 | __start_tp_report 22:43:39 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 22:43:39 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 22:43:39 INFO - PROCESS | 1642 | |i|pagename|runs| 22:43:39 INFO - PROCESS | 1642 | |0;dojo.html;3181.8409079546022;3424.5719285089363;3444.6727560881723;3451.2752322012375;3490.685838274555;5476.808311709086;5786.479216870481;5919.260092488434;5929.644221346723;5950.315713692927;5083;5745.994450971084;5859.7070146492715;5940.138679891413;5997.100434934751;5290.894182116365;5371.221172929922;5383.353997520303;5413.566914646819;5427.240186373911;5304.496072873071;5315.548178404821;5416.214648875911;5418.972905135489;5445.782168713246;5168.9741551292;5198.610104242159;5267.868303292448;5349.117395629736;5382.300300960889;5050.242463630448;5076.771545280471;5094.541491265767;5195.896082078375;5348.679079255257;5084.898302033938;5146.871328216787;5165.560927321182;5175.948240517584;5182.585393168538;5485.670859748388;5641.8589535261945;5645.633033852787;5712.857178570527;5783.855403614902;6241.687915604201;6261.27995280544;6262.874742505124;6283.591566548205;6288;6274.341194174637;6284.654344011077;6298.527610429191;6303.149074874925;6310.5267104967315;5788.797392091299;5804.651720896675;5812.186293918855;5908.793192238337;6014;5228.241904923765;5248.317718696545;5370.650907690988;5614.606977511535;6250.499960003189;6171.876562468726;6197.907031394533;6227.06594010893;6228.781992630236;6257.9687101564205;6070.453659170696;6205.348438413992;6289.528285378615;6313.621182728959;6327.557071005078;6113.205283313141;6120.0205129226715;6125.510168486406;6127.571070025056;6161.075838624242;6135.875804921825;6215.34738852423;6227.06594010893;6241.687915604292;6273.560850740496;5012.674176178537;5031.823886163967;5037.622178336566;5052.0653679068755;5053.444121146671;4447.62195213404;4462.263726485094;4469.9553004469535;4494.191045611821;4534.206513860061;5797.246357973437;5949.762009519657;5995.52035837132;6061.393860613904;6124.908126378108;6162.414570615784;6221.417161397526;6235.657038862906;6272.933601287792;6340.080688300171;6046.27444706638;6055.878882422327;6069.15031895535;6078.057901025348;6097.5731698780655;5384.380796208487;5458.5087342139395;5482.506574408322;5563.638363506359;5566.2207290979295;6300.558960872695;6361.332060133805;6375.585586936834;6388.329225431356;6488.253850807028;6313.779017734358;6317.210348706411;6318.178636777189;6320.494360451245;6329.493640508656;6478.352164783484;6493.253275873335;6497.545171837926;6508.967455162694;6521.934780652133;6095;6134.405972711667;6135.906433916272;6176.505879529716;6223.377662233922;6183.659898705614;6215.471684906834;6275.5920865145445;6278.591891527036;6293.96853015732;5106.46382129871;5806.1000544915605;6149.477294429845;6182.876342473215;6206.255249370104;4921.434035085867;6097.603655762538;6134.723937422916;6208.875822483435;6217.844553886189;5993.250843644544;6004.069369247599;6039.335673075871;6098.5426093042315;6106.794380079809;6170.722317495633;6172.969135154295;6190.566660333733;6198.845028874314;6209.689515524296;6031.185789918481;6053.848653783691;6064.575479716377;6090.939090609215;6121.724522396592 22:43:39 INFO - PROCESS | 1642 | |1;ext.html;475.93812804335454;488.8826681596416;520.4665218151395;523.1081006883264;554.3237250554325;24370.87814560927;40548;41728;41804.58195418045;44094.77952610233;42696.14607707844;42937.570624293745;43178.35232471507;43229.35155972663;43294.56705432945;9351.672691455817;9541.570629321697;9561.952190239073;9569.425834449921;9624.47065411402;9382;9500.28747843911;9500.857487137699;9519.286053546011;9577.569009394594;9485.905140948571;9551.99704031077;9553.856692149624;9555.80888382232;9591.904080959206;9494.52527373632;9497.857532137023;9516.762080947963;9526.761830954212;9535.856962145574;8717.4333668312;9174.862377064283;9413.905860941371;9490.383125096847;9499.335046546745;38068.61931380678;40447.393289100684;40971.59028409737;41011.58988410108;41091.767246982636;3138.529220616903;3274.4597141471627;3759.853244760347;3921.392184211452;3980.1840622672285;29661.110166695034;30111.548326775115;30368.54447183294;31265;31519.84240078808;2738.411241583049;2968.812475009992;3110.8600112994745;3216.678332166788;3219.661935496786;508870;514859;517417.41291293304;519610;520712;62014.37985620176;62187.6890615544;62411.37588624146;62415;62471.687641561504;64483.67758161273;65108;65276.673616632565;65403.67298163479;66001;16415.753763693556;16542.83457165437;16618.50144495667;16704;16737.581560460843;12586.993040556734;12660.620181394574;12755.93622031884;12884.355782210852;12890.871091289155;17363.652726945642;17427.04151271692;17675.027873467083;17703.29186832538;17762.733558996624;2890.9855450723035;3215.5015972524297;3314.436545787222;6810;6895.413889819321;805.6737021506299;934.514052692596;965.4496936746128;1052.7210289273348;1085.1698450685233;30892.073237802902;31249.375012499622;31277.68722312748;31569.684303157133;31787.046388608378;1026.625281772148;1069.6897899609025;1084.5878566144972;1092.7213560542011;1150.7238262817032;15931;15992.520224393287;16035.198240088183;16304.043514778303;16415.91792041032;635947;637052;641043;641724;643907;60375.698121509115;60424.39575604276;60665.69667151636;60937.08594371088;60937.39062609406;1363.2979015806868;1393.2894223945862;1406.4374250299757;1411.647088227943;1413.9151650900978;25862.870685646452;29909.70090299069;30499.390012200074;30536.694633053386;30684;51546;51885.74057129841;52308;52391.47608523866;52413.475865242384;521242;524979;525323;526906;527414;26581.867090664422;27307.59038614462;27535.586966195922;27539;28574.57138142888;39307;47366.52633473759;47517;47705.761471192425;48401.27398088961;38553.80723096366;39447.605523944396;39637.80181099076;40242.95748243987;41288.174236514504;38786.80606596949;39165.61805241311;39563.40654890236;39886.800565996986;40387.596124038384;38187.42718859164;38757.224855502165;39048.41427378535;39082.2183556333;39217.21565568616;33088.66911330856;34328;34866.65133348634;35034.29931401409;35113.473297901066;20632.381028569165;25837.095701650353;26042.95828166852;26314.947402104466;26382.868085659447 22:43:39 INFO - PROCESS | 1642 | |2;jquery.html;365.5814092863671;373.38205270277683;380.64219633544457;384.96535311821964;397.8442624176766;16316.857819952607;18941.62116757664;20047;20433.284835030794;20667.793322066776;19414.933487911894;19477.610447791034;19566.31517896874;19871.900640496813;20181.798182018174;3981.3278423062384;5175.275461435393;5331.040412725708;5390.434004429537;5525.806596769114;5760.337561180476;5955.761769529236;5963.9403605964035;5985.191999080112;5987.880242395149;5286.471352864722;5309.73451327434;5502.9174562381395;5911.970440147815;5923;5245.606579506533;5286.524212820865;5294.814681486129;5299.867503312429;5306.8407947761625;4942.555170034678;5172.612054095958;5178.0679477694;5221.086309895791;5274.26160337553;23980.160694375787;23981.040758369643;24138.637920431207;24158.396040098964;24674.259772206864;2073.419442556087;2116.8306535477127;2118;2323.014925820313;2392.880355982194;10322.277440579162;10367.32612380193;12303.32331721755;14429.422823087063;14788.112713237133;1826.7168588868615;1859.3399343233093;1860.479065861561;1896.3268039845932;1952.1410579345184;115863;120863.58272834495;122909.71903268955;124503.152326622;126974.36512817469;11345.659630211107;11562.88437115635;11570.652880413601;11720.824187637192;11805.23265987708;14667.77998330026;14689.412423503154;14694.11835289886;14714.779278310834;14719.92640036793;6804.387605115465;7005.299470053056;8562.700305489278;8687.913120868838;8710.912890871137;8483.363747718946;8491.278241349555;8506.106858779864;8640.78398040054;8663.220310172115;4454.665900057444;4559.726416415026;4593.402857628487;4625.352450656911;4724.645651576146;1651.6614094110678;1914.9138288777037;1958.3831093205595;1962.519182800223;1962.5878565501405;863.749512641327;910.7905181808221;925.1673493855584;938.4744543055774;994.7264502261936;20231.696524552142;20259.987000650202;20664.173433062508;20741.688874666892;21021;893.6112790935964;923.0999775219108;924.6347692661361;925.1488630459993;926.342296969146;10575.788484230427;10970.616028439126;11011;11018.559257629606;11086.445677716081;602620;615269;615801;625254;634137;101263;127141.36429317795;129690;130718;131359.68640313475;956.1490273656312;962.0716009051299;990.9950450247702;992.2409356842022;992.3500107429501;17938.91030544839;18161.36435224761;18178.36375726843;18563.90718046401;18621.348252811087;37731;38555;50758.49241507538;52081.47918520766;54613;53492.66268343323;53812;62284;65472.67263663651;73840;7239.348458638748;7470.4770666052855;7579.242075792376;7636.12184598756;7853.410994175574;8579.442336248125;10732.570697172026;11786.174967752519;12056.457459414523;12103.576374826838;7923;8585.613647385757;9540.90459095428;9807.754806129904;10399.532021059222;5171.974140129426;9931.553080111267;10238.641647542301;10384.42885641297;10691.625793097515;10228.386296822215;10366.481675916024;10456.477176141314;10525.000124988113;10525.631602893862;7617.162112167768;7672.156062832973;7733.961330193313;7745.186755390603;7844.254795794391;8849.672484173847;9982.35114717541;10285.640002599874;10435.582576696846;10623.415712135598 22:43:39 INFO - PROCESS | 1642 | |3;mootools.html;1462.656275775193;1491.2692780537536;1650.4718490083162;1667.8248783877693;1802.3691707902235;6265.843353916149;7103.609301488416;7336.449766267525;7968.28285454309;7987.480813747103;7489.251074892508;7757.418193635472;7774.611269436534;7847.058352997648;7853.096893857208;2274.306336567342;2313.7339205991275;2319.3389883883096;2328.115316179858;2330.627099664054;2305.9538809223804;2332.078828862598;2340.1458467659268;2340.1458467659354;2344.0975224538506;2189.408859607905;2209.591225623257;2218.822494200468;2301.1830800065904;2315.90736370545;2265.852719573231;2275.7496675365696;2285.223024171773;2293.128611127769;2298.586254474202;2283.5547068321684;2309.711286089239;2310.7342655594484;2313.9190128345595;2317.478567322339;7851.921480785176;7909.52542847431;7911.406644501628;7915.050193976702;7943.086545047336;4936.284238785392;5073.2643766654;5073.34046573943;5078.898422031539;5085.466026067284;3146.386454641346;3245.6916592923635;3462.5844898612077;3465.28961562879;3465.6707612776745;994.6369575105107;1019.1031891935124;1040.9271351005432;1049.5277125293649;1050.9211809114347;8044.396670249755;8720.345974051908;9177.898652161717;9282.90717092834;9682.806343873082;3829.7319187656594;4389.780510974439;4509.526499717548;4604.654650901196;4621.1219868224925;7478.813029674302;8739.737807865775;8857.689980850639;8873.866891996626;8878.86681699775;4169.353750168729;4176.2900306947895;5026.371703537058;5030.3963524377305;5107.7446127693465;3420.281740834403;3766.9434958475645;3780.981095094507;4315.482142142901;4607.35497030416;3712.6472985066375;3742.3263812513464;3786.242751449666;3795.791231482295;3867.2652196082654;1258.5028913579085;1266.050462153385;1269.4604792962955;1277.1251692590606;1283.4224598930332;774.9496282741604;878.5299864572423;893.8122994171297;900.0414558495181;918.3663272342062;4677.853925788136;5559.249501317351;5681.630694004876;5709.400512946164;5902.321233058254;885.4775682347446;898.1467605774541;906.9410488318238;955.7180631713661;967.1730670276953;3615.1685112423766;3747;3770.170562476251;3827.770333779982;3844.0966372902344;9231.446113233227;9342.299327550461;9509.904900950902;9535.2371810255;9608.75978100553;9328.766780830398;9391.013943535967;9407.90592094084;9477.241820654332;9504.192143667728;7136.678849451787;7473.88789168152;7476.850462990818;7480.775576732707;7494.812629684193;5478.45215478449;5520;5579.302587176603;5668.631538949955;5671.517920976598;8367.372447066373;8710.085441028601;8728.69449569262;8739.12608739121;8753.824923501366;7178.28217178278;7248.89126663111;8818.294536437197;8940.374173807772;9112.453252804853;6228.190335256388;6394.968025159845;6643.701033453603;6759.763408280686;6916.757913473004;7329.377002954809;7519.43604229674;7529.548227106391;7535.359494443033;7625.161232264336;5855.590108692521;6722.9663851680425;6826.146731658543;6844.657767111525;7034.155901291673;5697.4587414195585;5867.9413205867395;6766.729330826712;6816.931830681629;6844.486663500357;6405.19935008124;6418.614883107029;6485.740570377321;6503.512236582181;6565.015247712881;3590.7486475947476;4584.793684284148;4644.838790302424;4764.118638051971;4870.366852309177;4351.369051487453;4569.880379306958;4657.068586282825;4711.882202944954;4795.95204047955 22:43:39 INFO - PROCESS | 1642 | |4;prototype.html;254.55325903040162;293.09726043785145;309.8125633991436;315.1679565945902;316.42568737741;5248.973755131228;5368.30212072431;5466.699331536769;5543.861403464915;5555.527780138688;4167;4941;5170.767315470804;5247.431746412438;5432.750467392502;3744.577060716924;4172.70791044627;4174.728642638234;4211.073563815956;4223.91552168958;3946.901327466822;4001.5998400159897;4205.642520385771;4224.556421575737;4237.703360764748;4073.653739432137;4124.237016152007;4129.772862492562;4131.338985762279;4141.875743727693;4124.979375103136;4135.255653982282;4143.937840932404;4144.5026596808275;4163.2298024865195;3586.9597816633145;3624.6194149614175;3651.379265524867;3658.579263384718;3662.414013757786;4783.330333753277;4884.633652476078;5004.9499505005215;5277.788888444457;5313.203019547061;4298.634616057608;4678.181318269323;4693.366395536593;4699.694519856198;4709.529047095263;5686.090225563931;5700.719712115146;5705.800296989585;5748.942510574924;5803.82588522345;3418.931621367559;3599.8020108894;3637.945430818513;3871.7870517121546;3874.74814137083;5698.800541981011;5930.258717660293;5958.553108516879;5968.37332080134;5991.700414979277;3254.23525471514;3483.8606455741487;3816.6260146347295;3848.2111167210655;3928.175083232496;3081.9829456279376;3611.7065663303993;3784.4701741756176;3984.063745019918;4013.9162426145363;3849.538055433366;4518.774061296922;4646.442426908792;4654.837080702159;4665.6034237090225;5725.910042834419;5741;5742.511886489611;5767.163761254595;5792.160136780143;989.1196834817018;1126.4930781148516;1150.2293463379556;1224.9448774805155;1267.1309818018349;730.6960636066513;757.7840315510053;812.5815205169342;834.7662654456641;882.4043770454917;4372.628326592276;4587.839425620087;4649.5350464953235;5011.699298042129;5036.539403572996;4665.7604349912235;4666.976665116653;4844.176489996709;5052.863105801165;5349.010433069894;4152.065785198367;4559.085184222635;4625.213713668684;4853.927191092136;5044.596432285409;2576;2596.519643865891;2743.547314693093;2830.872610732522;2926.517124674405;4950.777215025332;5087.923681144785;5172;5473.67157970523;5819.447152520504;4604.635599526514;5419.485148910847;5654.660720356792;5666.433356664383;5724.427557244242;4769.42766867978;5699.800506982318;5747.568932330092;5768.855778605568;5850.765969361178;4834.274858771212;5052.91362357095;5421.19348138679;5585.748641311232;5657.32112146545;4492.528284530077;4693.615383461955;4707.223308154116;5412.296401467784;5526.530244929226;5160.612954028388;5647.543318656398;5661.2923384576925;5689.151025916676;5768.336641286306;3244.707976282146;3248.8213148276127;3310.519974603717;3340.2985373070956;3676.3566375884648;4908.337374454403;4979.875503112451;5472.589555783253;5593.972030139823;5621.707007388405;4680.648951328732;5170.069387510284;5354.244187498377;5472.343318801769;5742.339630942495;5148.164617359941;5742.16738572913;5761.983305842921;5768.826935191951;5776.566757493289;4707;5299.178627312773;5413.890152518646;5513.283273174462;5660.29246344207;4779.832705855279;5415.566754659539;5437.411308536347;5766.8846623066465;5800.6229595077875;4465.4864690561;5036.783815121335;5477.67133971963;5591.245181900392;5652.699879027765 22:43:39 INFO - PROCESS | 1642 | |5;yui.html;679.7315060551083;687.1753895325608;696.5855316086928;696.9616671083091;729.6169511006724;13742.312884355792;14589.416423343053;14602.12387256764;14608.342624581892;14630.707385852289;13094.279814610192;13158.407871645775;13189;13216.801747973788;13239.93380033101;639.0605809460086;654.479688647524;800.5997001499251;818.3984771193168;826.5040975414763;686.8282929267683;711.0471967563464;783.3772151139857;823.5964377455034;831.1190138453259;847.1993965702413;882.1531329923281;882.6028287270753;882.867569864519;886.0076714080207;846.4455781463121;846.9576521173948;879.4503435352905;880.1594477274188;880.4233227236139;646.1761254400677;649.2144505148775;653.8823011857863;656.0814859197114;657.2966925389787;11412.372319522423;12763.170393924365;13450.596482105553;13469.12450690702;13539.255340956244;12538.81191782124;12615.747685046339;12679.873201268054;12702.618921432371;13051.151675141085;11026.393548354838;11134.109271258361;11282.605108821152;11283.984441400313;11325.150613704005;3464.185916309665;3500.8949731508096;3655.5796083450477;3663.7435379523445;3680.3743363628246;73720.52558948845;91519.16961660796;93113;95202.52398738031;95769.52115239449;10039.849402258971;10198.949005254926;10233.385996840214;10345.43100129485;10584.629537966135;19749.01254937276;19877.701834472493;19897.303594374127;19951;20165.798342016686;7018.263082376379;7102.573845569283;7354.816129596803;7531.548107113591;7556.848863022708;3995.700322475826;4068.572799856032;4072.1448495815616;4088.4276201331836;4278.807453664593;3150.8582113804932;3236.757243206769;3237.579114715119;3243.0595127413258;3248.918777030593;551.9779208831602;587.6209844650176;606.6623096073148;616.1251023546574;620.8075496596069;645.241840837024;653.5719103986896;661.6724721263006;665.7503436211421;674.416629615389;8642.870356944652;8674.30605551567;8698.260647845003;8813.691520796741;9303.95348023269;636.818506725581;645.5190882792256;651.8859199640044;668.0613737698542;693.9791806245821;4378.877182825876;4419.535948725402;4443.244648409778;4463.352813841975;4575.565321294472;70432.29567704258;71788;99287;113101;114807;60456;63732.36267637264;63755.68122159359;63999.72000559963;73156.53686926232;52588.73705631447;88555;89308.5534572323;90306;92269.53865230764;8656.22094011542;8705.825883482168;8754.51850148248;8791.648334066822;8853;34997;44664.55335446604;55428;55669.44330556805;57697.55756106131;52603.79169666494;60498;71032;73749;75110.87333689889;12248.938755306166;12293.385330733248;12324.753504929671;12441.046517400298;12602.936985315382;13656.795148072986;13715.382807773474;13756.380962856496;13845.930770346084;14108.082974606617;11311.472951151818;12166.878331216929;12867.099303048697;12948;13019.869801301866;12355.25868447896;12687.873121268669;12725.55460558876;12735.55425560101;13337.53318633843;12262;12347.75304493923;12429.005679545433;12515.56195533152;12529.561465348668;10916.7270818227;11038;11144.888551114385;11441.084713223088;11522.308661480338;10947.507362168562;10970.96125857649;11230.775384492428;11309.69938457088;11346.94326528362 22:43:39 INFO - PROCESS | 1642 | __end_tp_report 22:43:39 INFO - PROCESS | 1642 | __start_cc_report 22:43:39 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,363 22:43:39 INFO - PROCESS | 1642 | __end_cc_report 22:43:39 INFO - PROCESS | 1642 | __startTimestamp1480488219621__endTimestamp 22:43:39 INFO - PROCESS | 1642 | 22:43:39 INFO - PROCESS | 1642 | ------- Summary: start ------- 22:43:39 INFO - PROCESS | 1642 | Number of tests: 6 22:43:39 INFO - PROCESS | 1642 | 22:43:39 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5779.83 Median:6061.39 stddev:646.40 (10.7%) stddev-sans-first:615.42 22:43:39 INFO - PROCESS | 1642 | Values: 3181.8 3424.6 3444.7 3451.3 3490.7 5476.8 5786.5 5919.3 5929.6 5950.3 5083.0 5746.0 5859.7 5940.1 5997.1 5290.9 5371.2 5383.4 5413.6 5427.2 5304.5 5315.5 5416.2 5419.0 5445.8 5169.0 5198.6 5267.9 5349.1 5382.3 5050.2 5076.8 5094.5 5195.9 5348.7 5084.9 5146.9 5165.6 5175.9 5182.6 5485.7 5641.9 5645.6 5712.9 5783.9 6241.7 6261.3 6262.9 6283.6 6288.0 6274.3 6284.7 6298.5 6303.1 6310.5 5788.8 5804.7 5812.2 5908.8 6014.0 5228.2 5248.3 5370.7 5614.6 6250.5 6171.9 6197.9 6227.1 6228.8 6258.0 6070.5 6205.3 6289.5 6313.6 6327.6 6113.2 6120.0 6125.5 6127.6 6161.1 6135.9 6215.3 6227.1 6241.7 6273.6 5012.7 5031.8 5037.6 5052.1 5053.4 4447.6 4462.3 4470.0 4494.2 4534.2 5797.2 5949.8 5995.5 6061.4 6124.9 6162.4 6221.4 6235.7 6272.9 6340.1 6046.3 6055.9 6069.2 6078.1 6097.6 5384.4 5458.5 5482.5 5563.6 5566.2 6300.6 6361.3 6375.6 6388.3 6488.3 6313.8 6317.2 6318.2 6320.5 6329.5 6478.4 6493.3 6497.5 6509.0 6521.9 6095.0 6134.4 6135.9 6176.5 6223.4 6183.7 6215.5 6275.6 6278.6 6294.0 5106.5 5806.1 6149.5 6182.9 6206.3 4921.4 6097.6 6134.7 6208.9 6217.8 5993.3 6004.1 6039.3 6098.5 6106.8 6170.7 6173.0 6190.6 6198.8 6209.7 6031.2 6053.8 6064.6 6090.9 6121.7 22:43:39 INFO - PROCESS | 1642 | 22:43:39 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:69780.45 Median:40315.28 stddev:150404.08 (373.1%) stddev-sans-first:150735.99 22:43:39 INFO - PROCESS | 1642 | Values: 475.9 488.9 520.5 523.1 554.3 24370.9 40548.0 41728.0 41804.6 44094.8 42696.1 42937.6 43178.4 43229.4 43294.6 9351.7 9541.6 9562.0 9569.4 9624.5 9382.0 9500.3 9500.9 9519.3 9577.6 9485.9 9552.0 9553.9 9555.8 9591.9 9494.5 9497.9 9516.8 9526.8 9535.9 8717.4 9174.9 9413.9 9490.4 9499.3 38068.6 40447.4 40971.6 41011.6 41091.8 3138.5 3274.5 3759.9 3921.4 3980.2 29661.1 30111.5 30368.5 31265.0 31519.8 2738.4 2968.8 3110.9 3216.7 3219.7 508870.0 514859.0 517417.4 519610.0 520712.0 62014.4 62187.7 62411.4 62415.0 62471.7 64483.7 65108.0 65276.7 65403.7 66001.0 16415.8 16542.8 16618.5 16704.0 16737.6 12587.0 12660.6 12755.9 12884.4 12890.9 17363.7 17427.0 17675.0 17703.3 17762.7 2891.0 3215.5 3314.4 6810.0 6895.4 805.7 934.5 965.4 1052.7 1085.2 30892.1 31249.4 31277.7 31569.7 31787.0 1026.6 1069.7 1084.6 1092.7 1150.7 15931.0 15992.5 16035.2 16304.0 16415.9 635947.0 637052.0 641043.0 641724.0 643907.0 60375.7 60424.4 60665.7 60937.1 60937.4 1363.3 1393.3 1406.4 1411.6 1413.9 25862.9 29909.7 30499.4 30536.7 30684.0 51546.0 51885.7 52308.0 52391.5 52413.5 521242.0 524979.0 525323.0 526906.0 527414.0 26581.9 27307.6 27535.6 27539.0 28574.6 39307.0 47366.5 47517.0 47705.8 48401.3 38553.8 39447.6 39637.8 40243.0 41288.2 38786.8 39165.6 39563.4 39886.8 40387.6 38187.4 38757.2 39048.4 39082.2 39217.2 33088.7 34328.0 34866.7 35034.3 35113.5 20632.4 25837.1 26043.0 26314.9 26382.9 22:43:39 INFO - PROCESS | 1642 | 22:43:39 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:34660.15 Median:19469.98 stddev:103001.42 (529.0%) stddev-sans-first:103258.18 22:43:39 INFO - PROCESS | 1642 | Values: 365.6 373.4 380.6 385.0 397.8 16316.9 18941.6 20047.0 20433.3 20667.8 19414.9 19477.6 19566.3 19871.9 20181.8 3981.3 5175.3 5331.0 5390.4 5525.8 5760.3 5955.8 5963.9 5985.2 5987.9 5286.5 5309.7 5502.9 5912.0 5923.0 5245.6 5286.5 5294.8 5299.9 5306.8 4942.6 5172.6 5178.1 5221.1 5274.3 23980.2 23981.0 24138.6 24158.4 24674.3 2073.4 2116.8 2118.0 2323.0 2392.9 10322.3 10367.3 12303.3 14429.4 14788.1 1826.7 1859.3 1860.5 1896.3 1952.1 115863.0 120863.6 122909.7 124503.2 126974.4 11345.7 11562.9 11570.7 11720.8 11805.2 14667.8 14689.4 14694.1 14714.8 14719.9 6804.4 7005.3 8562.7 8687.9 8710.9 8483.4 8491.3 8506.1 8640.8 8663.2 4454.7 4559.7 4593.4 4625.4 4724.6 1651.7 1914.9 1958.4 1962.5 1962.6 863.7 910.8 925.2 938.5 994.7 20231.7 20260.0 20664.2 20741.7 21021.0 893.6 923.1 924.6 925.1 926.3 10575.8 10970.6 11011.0 11018.6 11086.4 602620.0 615269.0 615801.0 625254.0 634137.0 101263.0 127141.4 129690.0 130718.0 131359.7 956.1 962.1 991.0 992.2 992.4 17938.9 18161.4 18178.4 18563.9 18621.3 37731.0 38555.0 50758.5 52081.5 54613.0 53492.7 53812.0 62284.0 65472.7 73840.0 7239.3 7470.5 7579.2 7636.1 7853.4 8579.4 10732.6 11786.2 12056.5 12103.6 7923.0 8585.6 9540.9 9807.8 10399.5 5172.0 9931.6 10238.6 10384.4 10691.6 10228.4 10366.5 10456.5 10525.0 10525.6 7617.2 7672.2 7734.0 7745.2 7844.3 8849.7 9982.4 10285.6 10435.6 10623.4 22:43:39 INFO - PROCESS | 1642 | 22:43:39 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:5104.74 Median:5676.57 stddev:2680.65 (47.2%) stddev-sans-first:2674.20 22:43:39 INFO - PROCESS | 1642 | Values: 1462.7 1491.3 1650.5 1667.8 1802.4 6265.8 7103.6 7336.4 7968.3 7987.5 7489.3 7757.4 7774.6 7847.1 7853.1 2274.3 2313.7 2319.3 2328.1 2330.6 2306.0 2332.1 2340.1 2340.1 2344.1 2189.4 2209.6 2218.8 2301.2 2315.9 2265.9 2275.7 2285.2 2293.1 2298.6 2283.6 2309.7 2310.7 2313.9 2317.5 7851.9 7909.5 7911.4 7915.1 7943.1 4936.3 5073.3 5073.3 5078.9 5085.5 3146.4 3245.7 3462.6 3465.3 3465.7 994.6 1019.1 1040.9 1049.5 1050.9 8044.4 8720.3 9177.9 9282.9 9682.8 3829.7 4389.8 4509.5 4604.7 4621.1 7478.8 8739.7 8857.7 8873.9 8878.9 4169.4 4176.3 5026.4 5030.4 5107.7 3420.3 3766.9 3781.0 4315.5 4607.4 3712.6 3742.3 3786.2 3795.8 3867.3 1258.5 1266.1 1269.5 1277.1 1283.4 774.9 878.5 893.8 900.0 918.4 4677.9 5559.2 5681.6 5709.4 5902.3 885.5 898.1 906.9 955.7 967.2 3615.2 3747.0 3770.2 3827.8 3844.1 9231.4 9342.3 9509.9 9535.2 9608.8 9328.8 9391.0 9407.9 9477.2 9504.2 7136.7 7473.9 7476.9 7480.8 7494.8 5478.5 5520.0 5579.3 5668.6 5671.5 8367.4 8710.1 8728.7 8739.1 8753.8 7178.3 7248.9 8818.3 8940.4 9112.5 6228.2 6395.0 6643.7 6759.8 6916.8 7329.4 7519.4 7529.5 7535.4 7625.2 5855.6 6723.0 6826.1 6844.7 7034.2 5697.5 5867.9 6766.7 6816.9 6844.5 6405.2 6418.6 6485.7 6503.5 6565.0 3590.7 4584.8 4644.8 4764.1 4870.4 4351.4 4569.9 4657.1 4711.9 4796.0 22:43:39 INFO - PROCESS | 1642 | 22:43:39 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4448.87 Median:4869.28 stddev:1385.21 (28.4%) stddev-sans-first:1352.85 22:43:39 INFO - PROCESS | 1642 | Values: 254.6 293.1 309.8 315.2 316.4 5249.0 5368.3 5466.7 5543.9 5555.5 4167.0 4941.0 5170.8 5247.4 5432.8 3744.6 4172.7 4174.7 4211.1 4223.9 3946.9 4001.6 4205.6 4224.6 4237.7 4073.7 4124.2 4129.8 4131.3 4141.9 4125.0 4135.3 4143.9 4144.5 4163.2 3587.0 3624.6 3651.4 3658.6 3662.4 4783.3 4884.6 5004.9 5277.8 5313.2 4298.6 4678.2 4693.4 4699.7 4709.5 5686.1 5700.7 5705.8 5748.9 5803.8 3418.9 3599.8 3637.9 3871.8 3874.7 5698.8 5930.3 5958.6 5968.4 5991.7 3254.2 3483.9 3816.6 3848.2 3928.2 3082.0 3611.7 3784.5 3984.1 4013.9 3849.5 4518.8 4646.4 4654.8 4665.6 5725.9 5741.0 5742.5 5767.2 5792.2 989.1 1126.5 1150.2 1224.9 1267.1 730.7 757.8 812.6 834.8 882.4 4372.6 4587.8 4649.5 5011.7 5036.5 4665.8 4667.0 4844.2 5052.9 5349.0 4152.1 4559.1 4625.2 4853.9 5044.6 2576.0 2596.5 2743.5 2830.9 2926.5 4950.8 5087.9 5172.0 5473.7 5819.4 4604.6 5419.5 5654.7 5666.4 5724.4 4769.4 5699.8 5747.6 5768.9 5850.8 4834.3 5052.9 5421.2 5585.7 5657.3 4492.5 4693.6 4707.2 5412.3 5526.5 5160.6 5647.5 5661.3 5689.2 5768.3 3244.7 3248.8 3310.5 3340.3 3676.4 4908.3 4979.9 5472.6 5594.0 5621.7 4680.6 5170.1 5354.2 5472.3 5742.3 5148.2 5742.2 5762.0 5768.8 5776.6 4707.0 5299.2 5413.9 5513.3 5660.3 4779.8 5415.6 5437.4 5766.9 5800.6 4465.5 5036.8 5477.7 5591.2 5652.7 22:43:39 INFO - PROCESS | 1642 | 22:43:39 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:18759.75 Median:4453.30 stddev:26790.40 (601.6%) stddev-sans-first:26831.16 22:43:39 INFO - PROCESS | 1642 | Values: 679.7 687.2 696.6 697.0 729.6 13742.3 14589.4 14602.1 14608.3 14630.7 13094.3 13158.4 13189.0 13216.8 13239.9 639.1 654.5 800.6 818.4 826.5 686.8 711.0 783.4 823.6 831.1 847.2 882.2 882.6 882.9 886.0 846.4 847.0 879.5 880.2 880.4 646.2 649.2 653.9 656.1 657.3 11412.4 12763.2 13450.6 13469.1 13539.3 12538.8 12615.7 12679.9 12702.6 13051.2 11026.4 11134.1 11282.6 11284.0 11325.2 3464.2 3500.9 3655.6 3663.7 3680.4 73720.5 91519.2 93113.0 95202.5 95769.5 10039.8 10198.9 10233.4 10345.4 10584.6 19749.0 19877.7 19897.3 19951.0 20165.8 7018.3 7102.6 7354.8 7531.5 7556.8 3995.7 4068.6 4072.1 4088.4 4278.8 3150.9 3236.8 3237.6 3243.1 3248.9 552.0 587.6 606.7 616.1 620.8 645.2 653.6 661.7 665.8 674.4 8642.9 8674.3 8698.3 8813.7 9304.0 636.8 645.5 651.9 668.1 694.0 4378.9 4419.5 4443.2 4463.4 4575.6 70432.3 71788.0 99287.0 113101.0 114807.0 60456.0 63732.4 63755.7 63999.7 73156.5 52588.7 88555.0 89308.6 90306.0 92269.5 8656.2 8705.8 8754.5 8791.6 8853.0 34997.0 44664.6 55428.0 55669.4 57697.6 52603.8 60498.0 71032.0 73749.0 75110.9 12248.9 12293.4 12324.8 12441.0 12602.9 13656.8 13715.4 13756.4 13845.9 14108.1 11311.5 12166.9 12867.1 12948.0 13019.9 12355.3 12687.9 12725.6 12735.6 13337.5 12262.0 12347.8 12429.0 12515.6 12529.6 10916.7 11038.0 11144.9 11441.1 11522.3 10947.5 10971.0 11230.8 11309.7 11346.9 22:43:39 INFO - PROCESS | 1642 | -------- Summary: end -------- 22:43:39 INFO - PROCESS | 1642 | 22:43:40 INFO - TEST-INFO | 1642: exit 0 22:43:40 INFO - TEST-OK | dromaeo_css | took 1117325ms 22:43:40 INFO - TEST-START | kraken 22:43:40 INFO - operating with platform_type : mac_ 22:43:40 INFO - Initialising browser for kraken test... 22:43:40 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpuq78pq/profile http://localhost:49242/getInfo.html) 22:43:43 INFO - PROCESS | 1655 | 2016-11-29 22:43:43.317 plugin-container[1656:10185] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a43, name = 'com.apple.tsm.portname' 22:43:43 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:43:43 INFO - PROCESS | 1655 | 2016-11-29 22:43:43.321 plugin-container[1656:10185] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4807, name = 'com.apple.CFPasteboardClient' 22:43:43 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:43:43 INFO - PROCESS | 1655 | 2016-11-29 22:43:43.321 plugin-container[1656:10185] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:43:43 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 22:43:43 INFO - PROCESS | 1655 | colorDepth:24 22:43:43 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 22:43:43 INFO - PROCESS | 1655 | __metrics 22:43:43 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 22:43:44 INFO - TEST-INFO | 1655: exit 0 22:43:44 INFO - Browser initialized. 22:43:44 INFO - Running cycle 1/1 for kraken test... 22:43:44 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpuq78pq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:43:46 INFO - PROCESS | 1657 | 2016-11-29 22:43:46.919 plugin-container[1658:10319] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 22:43:46 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:43:46 INFO - PROCESS | 1657 | 2016-11-29 22:43:46.919 plugin-container[1658:10319] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b17, name = 'com.apple.CFPasteboardClient' 22:43:46 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:43:46 INFO - PROCESS | 1657 | 2016-11-29 22:43:46.919 plugin-container[1658:10319] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:43:56 INFO - PROCESS | 1657 | RSS: Main: 229335040 22:43:56 INFO - PROCESS | 1657 | 22:43:59 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 22:44:00 INFO - PROCESS | 1657 | RSS: Main: 241491968 22:44:00 INFO - PROCESS | 1657 | 22:44:02 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 22:44:03 INFO - PROCESS | 1657 | RSS: Main: 249188352 22:44:03 INFO - PROCESS | 1657 | 22:44:06 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 22:44:07 INFO - PROCESS | 1657 | RSS: Main: 252977152 22:44:07 INFO - PROCESS | 1657 | 22:44:09 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 22:44:09 INFO - PROCESS | 1657 | RSS: Main: 253480960 22:44:09 INFO - PROCESS | 1657 | 22:44:12 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:44:12 INFO - PROCESS | 1657 | RSS: Main: 253341696 22:44:12 INFO - PROCESS | 1657 | 22:44:15 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 22:44:16 INFO - PROCESS | 1657 | RSS: Main: 257732608 22:44:16 INFO - PROCESS | 1657 | 22:44:19 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 22:44:19 INFO - PROCESS | 1657 | RSS: Main: 258748416 22:44:19 INFO - PROCESS | 1657 | 22:44:22 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 22:44:23 INFO - PROCESS | 1657 | RSS: Main: 258408448 22:44:23 INFO - PROCESS | 1657 | 22:44:24 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:44:25 INFO - PROCESS | 1657 | RSS: Main: 257388544 22:44:25 INFO - PROCESS | 1657 | 22:44:27 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:44:27 INFO - PROCESS | 1657 | RSS: Main: 257372160 22:44:27 INFO - PROCESS | 1657 | 22:44:29 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:44:30 INFO - PROCESS | 1657 | RSS: Main: 257355776 22:44:30 INFO - PROCESS | 1657 | 22:44:33 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:44:34 INFO - PROCESS | 1657 | RSS: Main: 257486848 22:44:34 INFO - PROCESS | 1657 | 22:44:37 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:44:38 INFO - PROCESS | 1657 | RSS: Main: 257818624 22:44:38 INFO - PROCESS | 1657 | 22:44:39 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 22:44:40 INFO - PROCESS | 1657 | RSS: Main: 259297280 22:44:40 INFO - PROCESS | 1657 | 22:44:40 INFO - PROCESS | 1657 | __start_tp_report 22:44:40 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 22:44:40 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 22:44:40 INFO - PROCESS | 1657 | |i|pagename|runs| 22:44:40 INFO - PROCESS | 1657 | |0;ai-astar;115;119;117;117;120;118;144;170;117;118 22:44:40 INFO - PROCESS | 1657 | |1;audio-beat-detection;148;120;118;150;117;118;122;146;176;119 22:44:40 INFO - PROCESS | 1657 | |2;audio-dft;195;194;229;225;192;196;194;195;194;199 22:44:40 INFO - PROCESS | 1657 | |3;audio-fft;89;60;59;85;59;61;60;62;101;81 22:44:40 INFO - PROCESS | 1657 | |4;audio-oscillator;87;71;94;100;96;81;87;70;69;89 22:44:40 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;91;89;89;86;90;86;90;103;90;89 22:44:40 INFO - PROCESS | 1657 | |6;imaging-darkroom;152;151;96;97;96;96;98;96;104;103 22:44:40 INFO - PROCESS | 1657 | |7;imaging-desaturate;89;94;86;89;101;89;89;90;92;89 22:44:40 INFO - PROCESS | 1657 | |8;json-parse-financial;58;57;57;59;60;60;61;60;60;60 22:44:40 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;70;79;71;86;77;78;69;71;72;70 22:44:40 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;90;85;83;84;86;85;88;99;84;87 22:44:40 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;151;152;157;146;172;164;166;166;244;148 22:44:40 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;173;171;169;167;166;191;221;178;170;169 22:44:40 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;66;69;67;69;66;73;87;86;88;84 22:44:40 INFO - PROCESS | 1657 | __end_tp_report 22:44:40 INFO - PROCESS | 1657 | __start_cc_report 22:44:40 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,975 22:44:40 INFO - PROCESS | 1657 | __end_cc_report 22:44:40 INFO - PROCESS | 1657 | __startTimestamp1480488280071__endTimestamp 22:44:40 INFO - PROCESS | 1657 | 22:44:40 INFO - PROCESS | 1657 | ------- Summary: start ------- 22:44:40 INFO - PROCESS | 1657 | Number of tests: 14 22:44:40 INFO - PROCESS | 1657 | 22:44:40 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:125.50 Median:118.50 stddev:17.75 (15.0%) stddev-sans-first:18.41 22:44:40 INFO - PROCESS | 1657 | Values: 115.0 119.0 117.0 117.0 120.0 118.0 144.0 170.0 117.0 118.0 22:44:40 INFO - PROCESS | 1657 | 22:44:40 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:133.40 Median:134.00 stddev:20.34 (15.2%) stddev-sans-first:20.87 22:44:40 INFO - PROCESS | 1657 | Values: 148.0 120.0 118.0 150.0 117.0 118.0 122.0 146.0 176.0 119.0 22:44:40 INFO - PROCESS | 1657 | 22:44:40 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:201.30 Median:195.50 stddev:13.70 (7.0%) stddev-sans-first:14.34 22:44:40 INFO - PROCESS | 1657 | Values: 195.0 194.0 229.0 225.0 192.0 196.0 194.0 195.0 194.0 199.0 22:44:40 INFO - PROCESS | 1657 | 22:44:40 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:71.70 Median:61.50 stddev:15.73 (25.6%) stddev-sans-first:15.38 22:44:40 INFO - PROCESS | 1657 | Values: 89.0 60.0 59.0 85.0 59.0 61.0 60.0 62.0 101.0 81.0 22:44:40 INFO - PROCESS | 1657 | 22:44:40 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:84.40 Median:87.00 stddev:11.26 (12.9%) stddev-sans-first:11.90 22:44:40 INFO - PROCESS | 1657 | Values: 87.0 71.0 94.0 100.0 96.0 81.0 87.0 70.0 69.0 89.0 22:44:40 INFO - PROCESS | 1657 | 22:44:40 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:90.30 Median:89.50 stddev:4.76 (5.3%) stddev-sans-first:5.04 22:44:40 INFO - PROCESS | 1657 | Values: 91.0 89.0 89.0 86.0 90.0 86.0 90.0 103.0 90.0 89.0 22:44:40 INFO - PROCESS | 1657 | 22:44:40 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:108.90 Median:96.00 stddev:22.64 (23.6%) stddev-sans-first:17.86 22:44:40 INFO - PROCESS | 1657 | Values: 152.0 151.0 96.0 97.0 96.0 96.0 98.0 96.0 104.0 103.0 22:44:40 INFO - PROCESS | 1657 | 22:44:40 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:90.80 Median:89.00 stddev:4.16 (4.7%) stddev-sans-first:4.36 22:44:40 INFO - PROCESS | 1657 | Values: 89.0 94.0 86.0 89.0 101.0 89.0 89.0 90.0 92.0 89.0 22:44:40 INFO - PROCESS | 1657 | 22:44:40 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:59.20 Median:60.00 stddev:1.40 (2.3%) stddev-sans-first:1.41 22:44:40 INFO - PROCESS | 1657 | Values: 58.0 57.0 57.0 59.0 60.0 60.0 61.0 60.0 60.0 60.0 22:44:40 INFO - PROCESS | 1657 | 22:44:40 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:74.30 Median:74.50 stddev:5.50 (7.4%) stddev-sans-first:5.61 22:44:40 INFO - PROCESS | 1657 | Values: 70.0 79.0 71.0 86.0 77.0 78.0 69.0 71.0 72.0 70.0 22:44:40 INFO - PROCESS | 1657 | 22:44:40 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:86.50 stddev:4.68 (5.4%) stddev-sans-first:4.84 22:44:40 INFO - PROCESS | 1657 | Values: 90.0 85.0 83.0 84.0 86.0 85.0 88.0 99.0 84.0 87.0 22:44:40 INFO - PROCESS | 1657 | 22:44:40 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:166.60 Median:165.00 stddev:28.57 (17.3%) stddev-sans-first:29.74 22:44:40 INFO - PROCESS | 1657 | Values: 151.0 152.0 157.0 146.0 172.0 164.0 166.0 166.0 244.0 148.0 22:44:40 INFO - PROCESS | 1657 | 22:44:40 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.50 Median:172.00 stddev:16.93 (9.8%) stddev-sans-first:17.88 22:44:40 INFO - PROCESS | 1657 | Values: 173.0 171.0 169.0 167.0 166.0 191.0 221.0 178.0 170.0 169.0 22:44:40 INFO - PROCESS | 1657 | 22:44:40 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.50 Median:78.50 stddev:9.51 (12.1%) stddev-sans-first:9.45 22:44:40 INFO - PROCESS | 1657 | Values: 66.0 69.0 67.0 69.0 66.0 73.0 87.0 86.0 88.0 84.0 22:44:40 INFO - PROCESS | 1657 | -------- Summary: end -------- 22:44:40 INFO - PROCESS | 1657 | 22:44:41 INFO - TEST-INFO | 1657: exit 0 22:44:41 INFO - TEST-OK | kraken | took 60848ms 22:44:41 INFO - SUITE-END | took 1178s 22:44:41 INFO - Completed test suite (00:19:38) 22:44:41 INFO - javascript benchmark 22:44:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7054.188477924974, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3181.8409079546022, 3424.5719285089363, 3444.6727560881723, 3451.2752322012375, 3490.685838274555, 5476.808311709086, 5786.479216870481, 5919.260092488434, 5929.644221346723, 5950.315713692927, 5083.0, 5745.994450971084, 5859.7070146492715, 5940.138679891413, 5997.100434934751, 5290.894182116365, 5371.221172929922, 5383.353997520303, 5413.566914646819, 5427.240186373911, 5304.496072873071, 5315.548178404821, 5416.214648875911, 5418.972905135489, 5445.782168713246, 5168.9741551292, 5198.610104242159, 5267.868303292448, 5349.117395629736, 5382.300300960889, 5050.242463630448, 5076.771545280471, 5094.541491265767, 5195.896082078375, 5348.679079255257, 5084.898302033938, 5146.871328216787, 5165.560927321182, 5175.948240517584, 5182.585393168538, 5485.670859748388, 5641.8589535261945, 5645.633033852787, 5712.857178570527, 5783.855403614902, 6241.687915604201, 6261.27995280544, 6262.874742505124, 6283.591566548205, 6288.0, 6274.341194174637, 6284.654344011077, 6298.527610429191, 6303.149074874925, 6310.5267104967315, 5788.797392091299, 5804.651720896675, 5812.186293918855, 5908.793192238337, 6014.0, 5228.241904923765, 5248.317718696545, 5370.650907690988, 5614.606977511535, 6250.499960003189, 6171.876562468726, 6197.907031394533, 6227.06594010893, 6228.781992630236, 6257.9687101564205, 6070.453659170696, 6205.348438413992, 6289.528285378615, 6313.621182728959, 6327.557071005078, 6113.205283313141, 6120.0205129226715, 6125.510168486406, 6127.571070025056, 6161.075838624242, 6135.875804921825, 6215.34738852423, 6227.06594010893, 6241.687915604292, 6273.560850740496, 5012.674176178537, 5031.823886163967, 5037.622178336566, 5052.0653679068755, 5053.444121146671, 4447.62195213404, 4462.263726485094, 4469.9553004469535, 4494.191045611821, 4534.206513860061, 5797.246357973437, 5949.762009519657, 5995.52035837132, 6061.393860613904, 6124.908126378108, 6162.414570615784, 6221.417161397526, 6235.657038862906, 6272.933601287792, 6340.080688300171, 6046.27444706638, 6055.878882422327, 6069.15031895535, 6078.057901025348, 6097.5731698780655, 5384.380796208487, 5458.5087342139395, 5482.506574408322, 5563.638363506359, 5566.2207290979295, 6300.558960872695, 6361.332060133805, 6375.585586936834, 6388.329225431356, 6488.253850807028, 6313.779017734358, 6317.210348706411, 6318.178636777189, 6320.494360451245, 6329.493640508656, 6478.352164783484, 6493.253275873335, 6497.545171837926, 6508.967455162694, 6521.934780652133, 6095.0, 6134.405972711667, 6135.906433916272, 6176.505879529716, 6223.377662233922, 6183.659898705614, 6215.471684906834, 6275.5920865145445, 6278.591891527036, 6293.96853015732, 5106.46382129871, 5806.1000544915605, 6149.477294429845, 6182.876342473215, 6206.255249370104, 4921.434035085867, 6097.603655762538, 6134.723937422916, 6208.875822483435, 6217.844553886189, 5993.250843644544, 6004.069369247599, 6039.335673075871, 6098.5426093042315, 6106.794380079809, 6170.722317495633, 6172.969135154295, 6190.566660333733, 6198.845028874314, 6209.689515524296, 6031.185789918481, 6053.848653783691, 6064.575479716377, 6090.939090609215, 6121.724522396592], "value": 5739.489777532616, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.93812804335454, 488.8826681596416, 520.4665218151395, 523.1081006883264, 554.3237250554325, 24370.87814560927, 40548.0, 41728.0, 41804.58195418045, 44094.77952610233, 42696.14607707844, 42937.570624293745, 43178.35232471507, 43229.35155972663, 43294.56705432945, 9351.672691455817, 9541.570629321697, 9561.952190239073, 9569.425834449921, 9624.47065411402, 9382.0, 9500.28747843911, 9500.857487137699, 9519.286053546011, 9577.569009394594, 9485.905140948571, 9551.99704031077, 9553.856692149624, 9555.80888382232, 9591.904080959206, 9494.52527373632, 9497.857532137023, 9516.762080947963, 9526.761830954212, 9535.856962145574, 8717.4333668312, 9174.862377064283, 9413.905860941371, 9490.383125096847, 9499.335046546745, 38068.61931380678, 40447.393289100684, 40971.59028409737, 41011.58988410108, 41091.767246982636, 3138.529220616903, 3274.4597141471627, 3759.853244760347, 3921.392184211452, 3980.1840622672285, 29661.110166695034, 30111.548326775115, 30368.54447183294, 31265.0, 31519.84240078808, 2738.411241583049, 2968.812475009992, 3110.8600112994745, 3216.678332166788, 3219.661935496786, 508870.0, 514859.0, 517417.41291293304, 519610.0, 520712.0, 62014.37985620176, 62187.6890615544, 62411.37588624146, 62415.0, 62471.687641561504, 64483.67758161273, 65108.0, 65276.673616632565, 65403.67298163479, 66001.0, 16415.753763693556, 16542.83457165437, 16618.50144495667, 16704.0, 16737.581560460843, 12586.993040556734, 12660.620181394574, 12755.93622031884, 12884.355782210852, 12890.871091289155, 17363.652726945642, 17427.04151271692, 17675.027873467083, 17703.29186832538, 17762.733558996624, 2890.9855450723035, 3215.5015972524297, 3314.436545787222, 6810.0, 6895.413889819321, 805.6737021506299, 934.514052692596, 965.4496936746128, 1052.7210289273348, 1085.1698450685233, 30892.073237802902, 31249.375012499622, 31277.68722312748, 31569.684303157133, 31787.046388608378, 1026.625281772148, 1069.6897899609025, 1084.5878566144972, 1092.7213560542011, 1150.7238262817032, 15931.0, 15992.520224393287, 16035.198240088183, 16304.043514778303, 16415.91792041032, 635947.0, 637052.0, 641043.0, 641724.0, 643907.0, 60375.698121509115, 60424.39575604276, 60665.69667151636, 60937.08594371088, 60937.39062609406, 1363.2979015806868, 1393.2894223945862, 1406.4374250299757, 1411.647088227943, 1413.9151650900978, 25862.870685646452, 29909.70090299069, 30499.390012200074, 30536.694633053386, 30684.0, 51546.0, 51885.74057129841, 52308.0, 52391.47608523866, 52413.475865242384, 521242.0, 524979.0, 525323.0, 526906.0, 527414.0, 26581.867090664422, 27307.59038614462, 27535.586966195922, 27539.0, 28574.57138142888, 39307.0, 47366.52633473759, 47517.0, 47705.761471192425, 48401.27398088961, 38553.80723096366, 39447.605523944396, 39637.80181099076, 40242.95748243987, 41288.174236514504, 38786.80606596949, 39165.61805241311, 39563.40654890236, 39886.800565996986, 40387.596124038384, 38187.42718859164, 38757.224855502165, 39048.41427378535, 39082.2183556333, 39217.21565568616, 33088.66911330856, 34328.0, 34866.65133348634, 35034.29931401409, 35113.473297901066, 20632.381028569165, 25837.095701650353, 26042.95828166852, 26314.947402104466, 26382.868085659447], "value": 19822.941816743663, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.5814092863671, 373.38205270277683, 380.64219633544457, 384.96535311821964, 397.8442624176766, 16316.857819952607, 18941.62116757664, 20047.0, 20433.284835030794, 20667.793322066776, 19414.933487911894, 19477.610447791034, 19566.31517896874, 19871.900640496813, 20181.798182018174, 3981.3278423062384, 5175.275461435393, 5331.040412725708, 5390.434004429537, 5525.806596769114, 5760.337561180476, 5955.761769529236, 5963.9403605964035, 5985.191999080112, 5987.880242395149, 5286.471352864722, 5309.73451327434, 5502.9174562381395, 5911.970440147815, 5923.0, 5245.606579506533, 5286.524212820865, 5294.814681486129, 5299.867503312429, 5306.8407947761625, 4942.555170034678, 5172.612054095958, 5178.0679477694, 5221.086309895791, 5274.26160337553, 23980.160694375787, 23981.040758369643, 24138.637920431207, 24158.396040098964, 24674.259772206864, 2073.419442556087, 2116.8306535477127, 2118.0, 2323.014925820313, 2392.880355982194, 10322.277440579162, 10367.32612380193, 12303.32331721755, 14429.422823087063, 14788.112713237133, 1826.7168588868615, 1859.3399343233093, 1860.479065861561, 1896.3268039845932, 1952.1410579345184, 115863.0, 120863.58272834495, 122909.71903268955, 124503.152326622, 126974.36512817469, 11345.659630211107, 11562.88437115635, 11570.652880413601, 11720.824187637192, 11805.23265987708, 14667.77998330026, 14689.412423503154, 14694.11835289886, 14714.779278310834, 14719.92640036793, 6804.387605115465, 7005.299470053056, 8562.700305489278, 8687.913120868838, 8710.912890871137, 8483.363747718946, 8491.278241349555, 8506.106858779864, 8640.78398040054, 8663.220310172115, 4454.665900057444, 4559.726416415026, 4593.402857628487, 4625.352450656911, 4724.645651576146, 1651.6614094110678, 1914.9138288777037, 1958.3831093205595, 1962.519182800223, 1962.5878565501405, 863.749512641327, 910.7905181808221, 925.1673493855584, 938.4744543055774, 994.7264502261936, 20231.696524552142, 20259.987000650202, 20664.173433062508, 20741.688874666892, 21021.0, 893.6112790935964, 923.0999775219108, 924.6347692661361, 925.1488630459993, 926.342296969146, 10575.788484230427, 10970.616028439126, 11011.0, 11018.559257629606, 11086.445677716081, 602620.0, 615269.0, 615801.0, 625254.0, 634137.0, 101263.0, 127141.36429317795, 129690.0, 130718.0, 131359.68640313475, 956.1490273656312, 962.0716009051299, 990.9950450247702, 992.2409356842022, 992.3500107429501, 17938.91030544839, 18161.36435224761, 18178.36375726843, 18563.90718046401, 18621.348252811087, 37731.0, 38555.0, 50758.49241507538, 52081.47918520766, 54613.0, 53492.66268343323, 53812.0, 62284.0, 65472.67263663651, 73840.0, 7239.348458638748, 7470.4770666052855, 7579.242075792376, 7636.12184598756, 7853.410994175574, 8579.442336248125, 10732.570697172026, 11786.174967752519, 12056.457459414523, 12103.576374826838, 7923.0, 8585.613647385757, 9540.90459095428, 9807.754806129904, 10399.532021059222, 5171.974140129426, 9931.553080111267, 10238.641647542301, 10384.42885641297, 10691.625793097515, 10228.386296822215, 10366.481675916024, 10456.477176141314, 10525.000124988113, 10525.631602893862, 7617.162112167768, 7672.156062832973, 7733.961330193313, 7745.186755390603, 7844.254795794391, 8849.672484173847, 9982.35114717541, 10285.640002599874, 10435.582576696846, 10623.415712135598], "value": 9175.156475630198, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1462.656275775193, 1491.2692780537536, 1650.4718490083162, 1667.8248783877693, 1802.3691707902235, 6265.843353916149, 7103.609301488416, 7336.449766267525, 7968.28285454309, 7987.480813747103, 7489.251074892508, 7757.418193635472, 7774.611269436534, 7847.058352997648, 7853.096893857208, 2274.306336567342, 2313.7339205991275, 2319.3389883883096, 2328.115316179858, 2330.627099664054, 2305.9538809223804, 2332.078828862598, 2340.1458467659268, 2340.1458467659354, 2344.0975224538506, 2189.408859607905, 2209.591225623257, 2218.822494200468, 2301.1830800065904, 2315.90736370545, 2265.852719573231, 2275.7496675365696, 2285.223024171773, 2293.128611127769, 2298.586254474202, 2283.5547068321684, 2309.711286089239, 2310.7342655594484, 2313.9190128345595, 2317.478567322339, 7851.921480785176, 7909.52542847431, 7911.406644501628, 7915.050193976702, 7943.086545047336, 4936.284238785392, 5073.2643766654, 5073.34046573943, 5078.898422031539, 5085.466026067284, 3146.386454641346, 3245.6916592923635, 3462.5844898612077, 3465.28961562879, 3465.6707612776745, 994.6369575105107, 1019.1031891935124, 1040.9271351005432, 1049.5277125293649, 1050.9211809114347, 8044.396670249755, 8720.345974051908, 9177.898652161717, 9282.90717092834, 9682.806343873082, 3829.7319187656594, 4389.780510974439, 4509.526499717548, 4604.654650901196, 4621.1219868224925, 7478.813029674302, 8739.737807865775, 8857.689980850639, 8873.866891996626, 8878.86681699775, 4169.353750168729, 4176.2900306947895, 5026.371703537058, 5030.3963524377305, 5107.7446127693465, 3420.281740834403, 3766.9434958475645, 3780.981095094507, 4315.482142142901, 4607.35497030416, 3712.6472985066375, 3742.3263812513464, 3786.242751449666, 3795.791231482295, 3867.2652196082654, 1258.5028913579085, 1266.050462153385, 1269.4604792962955, 1277.1251692590606, 1283.4224598930332, 774.9496282741604, 878.5299864572423, 893.8122994171297, 900.0414558495181, 918.3663272342062, 4677.853925788136, 5559.249501317351, 5681.630694004876, 5709.400512946164, 5902.321233058254, 885.4775682347446, 898.1467605774541, 906.9410488318238, 955.7180631713661, 967.1730670276953, 3615.1685112423766, 3747.0, 3770.170562476251, 3827.770333779982, 3844.0966372902344, 9231.446113233227, 9342.299327550461, 9509.904900950902, 9535.2371810255, 9608.75978100553, 9328.766780830398, 9391.013943535967, 9407.90592094084, 9477.241820654332, 9504.192143667728, 7136.678849451787, 7473.88789168152, 7476.850462990818, 7480.775576732707, 7494.812629684193, 5478.45215478449, 5520.0, 5579.302587176603, 5668.631538949955, 5671.517920976598, 8367.372447066373, 8710.085441028601, 8728.69449569262, 8739.12608739121, 8753.824923501366, 7178.28217178278, 7248.89126663111, 8818.294536437197, 8940.374173807772, 9112.453252804853, 6228.190335256388, 6394.968025159845, 6643.701033453603, 6759.763408280686, 6916.757913473004, 7329.377002954809, 7519.43604229674, 7529.548227106391, 7535.359494443033, 7625.161232264336, 5855.590108692521, 6722.9663851680425, 6826.146731658543, 6844.657767111525, 7034.155901291673, 5697.4587414195585, 5867.9413205867395, 6766.729330826712, 6816.931830681629, 6844.486663500357, 6405.19935008124, 6418.614883107029, 6485.740570377321, 6503.512236582181, 6565.015247712881, 3590.7486475947476, 4584.793684284148, 4644.838790302424, 4764.118638051971, 4870.366852309177, 4351.369051487453, 4569.880379306958, 4657.068586282825, 4711.882202944954, 4795.95204047955], "value": 4213.284232183891, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [254.55325903040162, 293.09726043785145, 309.8125633991436, 315.1679565945902, 316.42568737741, 5248.973755131228, 5368.30212072431, 5466.699331536769, 5543.861403464915, 5555.527780138688, 4167.0, 4941.0, 5170.767315470804, 5247.431746412438, 5432.750467392502, 3744.577060716924, 4172.70791044627, 4174.728642638234, 4211.073563815956, 4223.91552168958, 3946.901327466822, 4001.5998400159897, 4205.642520385771, 4224.556421575737, 4237.703360764748, 4073.653739432137, 4124.237016152007, 4129.772862492562, 4131.338985762279, 4141.875743727693, 4124.979375103136, 4135.255653982282, 4143.937840932404, 4144.5026596808275, 4163.2298024865195, 3586.9597816633145, 3624.6194149614175, 3651.379265524867, 3658.579263384718, 3662.414013757786, 4783.330333753277, 4884.633652476078, 5004.9499505005215, 5277.788888444457, 5313.203019547061, 4298.634616057608, 4678.181318269323, 4693.366395536593, 4699.694519856198, 4709.529047095263, 5686.090225563931, 5700.719712115146, 5705.800296989585, 5748.942510574924, 5803.82588522345, 3418.931621367559, 3599.8020108894, 3637.945430818513, 3871.7870517121546, 3874.74814137083, 5698.800541981011, 5930.258717660293, 5958.553108516879, 5968.37332080134, 5991.700414979277, 3254.23525471514, 3483.8606455741487, 3816.6260146347295, 3848.2111167210655, 3928.175083232496, 3081.9829456279376, 3611.7065663303993, 3784.4701741756176, 3984.063745019918, 4013.9162426145363, 3849.538055433366, 4518.774061296922, 4646.442426908792, 4654.837080702159, 4665.6034237090225, 5725.910042834419, 5741.0, 5742.511886489611, 5767.163761254595, 5792.160136780143, 989.1196834817018, 1126.4930781148516, 1150.2293463379556, 1224.9448774805155, 1267.1309818018349, 730.6960636066513, 757.7840315510053, 812.5815205169342, 834.7662654456641, 882.4043770454917, 4372.628326592276, 4587.839425620087, 4649.5350464953235, 5011.699298042129, 5036.539403572996, 4665.7604349912235, 4666.976665116653, 4844.176489996709, 5052.863105801165, 5349.010433069894, 4152.065785198367, 4559.085184222635, 4625.213713668684, 4853.927191092136, 5044.596432285409, 2576.0, 2596.519643865891, 2743.547314693093, 2830.872610732522, 2926.517124674405, 4950.777215025332, 5087.923681144785, 5172.0, 5473.67157970523, 5819.447152520504, 4604.635599526514, 5419.485148910847, 5654.660720356792, 5666.433356664383, 5724.427557244242, 4769.42766867978, 5699.800506982318, 5747.568932330092, 5768.855778605568, 5850.765969361178, 4834.274858771212, 5052.91362357095, 5421.19348138679, 5585.748641311232, 5657.32112146545, 4492.528284530077, 4693.615383461955, 4707.223308154116, 5412.296401467784, 5526.530244929226, 5160.612954028388, 5647.543318656398, 5661.2923384576925, 5689.151025916676, 5768.336641286306, 3244.707976282146, 3248.8213148276127, 3310.519974603717, 3340.2985373070956, 3676.3566375884648, 4908.337374454403, 4979.875503112451, 5472.589555783253, 5593.972030139823, 5621.707007388405, 4680.648951328732, 5170.069387510284, 5354.244187498377, 5472.343318801769, 5742.339630942495, 5148.164617359941, 5742.16738572913, 5761.983305842921, 5768.826935191951, 5776.566757493289, 4707.0, 5299.178627312773, 5413.890152518646, 5513.283273174462, 5660.29246344207, 4779.832705855279, 5415.566754659539, 5437.411308536347, 5766.8846623066465, 5800.6229595077875, 4465.4864690561, 5036.783815121335, 5477.67133971963, 5591.245181900392, 5652.699879027765], "value": 3994.3963663483933, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.7315060551083, 687.1753895325608, 696.5855316086928, 696.9616671083091, 729.6169511006724, 13742.312884355792, 14589.416423343053, 14602.12387256764, 14608.342624581892, 14630.707385852289, 13094.279814610192, 13158.407871645775, 13189.0, 13216.801747973788, 13239.93380033101, 639.0605809460086, 654.479688647524, 800.5997001499251, 818.3984771193168, 826.5040975414763, 686.8282929267683, 711.0471967563464, 783.3772151139857, 823.5964377455034, 831.1190138453259, 847.1993965702413, 882.1531329923281, 882.6028287270753, 882.867569864519, 886.0076714080207, 846.4455781463121, 846.9576521173948, 879.4503435352905, 880.1594477274188, 880.4233227236139, 646.1761254400677, 649.2144505148775, 653.8823011857863, 656.0814859197114, 657.2966925389787, 11412.372319522423, 12763.170393924365, 13450.596482105553, 13469.12450690702, 13539.255340956244, 12538.81191782124, 12615.747685046339, 12679.873201268054, 12702.618921432371, 13051.151675141085, 11026.393548354838, 11134.109271258361, 11282.605108821152, 11283.984441400313, 11325.150613704005, 3464.185916309665, 3500.8949731508096, 3655.5796083450477, 3663.7435379523445, 3680.3743363628246, 73720.52558948845, 91519.16961660796, 93113.0, 95202.52398738031, 95769.52115239449, 10039.849402258971, 10198.949005254926, 10233.385996840214, 10345.43100129485, 10584.629537966135, 19749.01254937276, 19877.701834472493, 19897.303594374127, 19951.0, 20165.798342016686, 7018.263082376379, 7102.573845569283, 7354.816129596803, 7531.548107113591, 7556.848863022708, 3995.700322475826, 4068.572799856032, 4072.1448495815616, 4088.4276201331836, 4278.807453664593, 3150.8582113804932, 3236.757243206769, 3237.579114715119, 3243.0595127413258, 3248.918777030593, 551.9779208831602, 587.6209844650176, 606.6623096073148, 616.1251023546574, 620.8075496596069, 645.241840837024, 653.5719103986896, 661.6724721263006, 665.7503436211421, 674.416629615389, 8642.870356944652, 8674.30605551567, 8698.260647845003, 8813.691520796741, 9303.95348023269, 636.818506725581, 645.5190882792256, 651.8859199640044, 668.0613737698542, 693.9791806245821, 4378.877182825876, 4419.535948725402, 4443.244648409778, 4463.352813841975, 4575.565321294472, 70432.29567704258, 71788.0, 99287.0, 113101.0, 114807.0, 60456.0, 63732.36267637264, 63755.68122159359, 63999.72000559963, 73156.53686926232, 52588.73705631447, 88555.0, 89308.5534572323, 90306.0, 92269.53865230764, 8656.22094011542, 8705.825883482168, 8754.51850148248, 8791.648334066822, 8853.0, 34997.0, 44664.55335446604, 55428.0, 55669.44330556805, 57697.55756106131, 52603.79169666494, 60498.0, 71032.0, 73749.0, 75110.87333689889, 12248.938755306166, 12293.385330733248, 12324.753504929671, 12441.046517400298, 12602.936985315382, 13656.795148072986, 13715.382807773474, 13756.380962856496, 13845.930770346084, 14108.082974606617, 11311.472951151818, 12166.878331216929, 12867.099303048697, 12948.0, 13019.869801301866, 12355.25868447896, 12687.873121268669, 12725.55460558876, 12735.55425560101, 13337.53318633843, 12262.0, 12347.75304493923, 12429.005679545433, 12515.56195533152, 12529.561465348668, 10916.7270818227, 11038.0, 11144.888551114385, 11441.084713223088, 11522.308661480338, 10947.507362168562, 10970.96125857649, 11230.775384492428, 11309.69938457088, 11346.94326528362], "value": 7013.040277699735, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1546.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 119.0, 117.0, 117.0, 120.0, 118.0, 144.0, 170.0, 117.0, 118.0], "value": 125.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 120.0, 118.0, 150.0, 117.0, 118.0, 122.0, 146.0, 176.0, 119.0], "value": 133.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 194.0, 229.0, 225.0, 192.0, 196.0, 194.0, 195.0, 194.0, 199.0], "value": 201.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 60.0, 59.0, 85.0, 59.0, 61.0, 60.0, 62.0, 101.0, 81.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 94.0, 100.0, 96.0, 81.0, 87.0, 70.0, 69.0, 89.0], "value": 84.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 89.0, 86.0, 90.0, 86.0, 90.0, 103.0, 90.0, 89.0], "value": 90.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 96.0, 97.0, 96.0, 96.0, 98.0, 96.0, 104.0, 103.0], "value": 108.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 94.0, 86.0, 89.0, 101.0, 89.0, 89.0, 90.0, 92.0, 89.0], "value": 90.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 57.0, 59.0, 60.0, 60.0, 61.0, 60.0, 60.0, 60.0], "value": 59.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 79.0, 71.0, 86.0, 77.0, 78.0, 69.0, 71.0, 72.0, 70.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 85.0, 83.0, 84.0, 86.0, 85.0, 88.0, 99.0, 84.0, 87.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 152.0, 157.0, 146.0, 172.0, 164.0, 166.0, 166.0, 244.0, 148.0], "value": 166.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 171.0, 169.0, 167.0, 166.0, 191.0, 221.0, 178.0, 170.0, 169.0], "value": 177.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 67.0, 69.0, 66.0, 73.0, 87.0, 86.0, 88.0, 84.0], "value": 75.5, "unit": "score"}]}]} 22:44:41 INFO - Return code: 0 22:44:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:44:41 ERROR - # TBPL SUCCESS # 22:44:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:44:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:44:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:44:41 INFO - 2016-11-29 22:44:41.402 screenresolution[1662:10710] starting screenresolution argv=screenresolution get 22:44:41 INFO - 2016-11-29 22:44:41.405 screenresolution[1662:10710] Display 0: 1600x1200x32@60 22:44:41 INFO - 2016-11-29 22:44:41.412 screenresolution[1663:10713] starting screenresolution argv=screenresolution list 22:44:41 INFO - Available Modes on Display 0 22:44:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:44:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:44:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:44:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:44:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:44:41 INFO - Intel Iris: 22:44:41 INFO - Chipset Model: Intel Iris 22:44:41 INFO - Type: GPU 22:44:41 INFO - Bus: Built-In 22:44:41 INFO - VRAM (Dynamic, Max): 1536 MB 22:44:41 INFO - Vendor: Intel (0x8086) 22:44:41 INFO - Device ID: 0x0a2e 22:44:41 INFO - Revision ID: 0x0009 22:44:41 INFO - Displays: 22:44:41 INFO - Display: 22:44:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:44:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:44:41 INFO - Display Serial Number: 1600x1200 60 22:44:41 INFO - Main Display: Yes 22:44:41 INFO - Mirror: Off 22:44:41 INFO - Online: Yes 22:44:41 INFO - Rotation: Supported 22:44:41 INFO - Return code: 0 22:44:41 INFO - Running post-action listener: _resource_record_post_action 22:44:41 INFO - [mozharness: 2016-11-30 06:44:41.661983Z] Finished run-tests step (success) 22:44:41 INFO - Running post-run listener: _resource_record_post_run 22:44:41 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 39963136; Write bytes: 658927616; Read time: 387; Write time: 1999 22:44:41 INFO - TinderboxPrint: CPU usage
54.3% 22:44:41 INFO - TinderboxPrint: I/O read bytes / time
39,963,136 / 387 22:44:41 INFO - TinderboxPrint: I/O write bytes / time
658,927,616 / 1,999 22:44:41 INFO - TinderboxPrint: CPU idle
2,211.0 (45.7%) 22:44:41 INFO - TinderboxPrint: CPU system
975.2 (20.1%) 22:44:41 INFO - TinderboxPrint: CPU user
1,655.6 (34.2%) 22:44:41 INFO - TinderboxPrint: Swap in / out
388,919,296 / 0 22:44:41 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 334123520; Write bytes: 332519424; Read time: 26561; Write time: 582 22:44:41 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 39033344; Write bytes: 304316416; Read time: 374; Write time: 1254 22:44:42 INFO - Running post-run listener: _upload_blobber_files 22:44:42 INFO - Blob upload gear active. 22:44:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:44:42 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 22:44:42 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:44:42 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:44:42 INFO - (blobuploader) - INFO - Open directory for files ... 22:44:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:44:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:44:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:43 INFO - (blobuploader) - INFO - Done attempting. 22:44:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:44:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:44:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:44 INFO - (blobuploader) - INFO - Done attempting. 22:44:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:44:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:44:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:47 INFO - (blobuploader) - INFO - Done attempting. 22:44:47 INFO - (blobuploader) - INFO - Iteration through files over. 22:44:47 INFO - Return code: 0 22:44:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:44:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:44:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5e834f3a963d999a03ad977112a575dd4e2e71f22080837d630d0daa1d70ecdddaa2a9901c109d8be937d77f1fc1ade25d377f2a55ecacf97fad67b4f223151", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c28c76b769e58b97d530e08eadc4c9b1738e5c142cd4cde55b27446060d44dfac0c2f76cd6fcd30376d5e88b6371325875c395ef6e8041758095175eeb093f8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9990b65458917e6dd57ce33da32306308cd1d4fde541b1bae5a374999bb7396d3fb6de1cbac09a200f23a47dae3a7253c4f27ae948c6cf66a53c5a46047d78dc"} 22:44:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:44:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:44:47 INFO - Contents: 22:44:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5e834f3a963d999a03ad977112a575dd4e2e71f22080837d630d0daa1d70ecdddaa2a9901c109d8be937d77f1fc1ade25d377f2a55ecacf97fad67b4f223151", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c28c76b769e58b97d530e08eadc4c9b1738e5c142cd4cde55b27446060d44dfac0c2f76cd6fcd30376d5e88b6371325875c395ef6e8041758095175eeb093f8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9990b65458917e6dd57ce33da32306308cd1d4fde541b1bae5a374999bb7396d3fb6de1cbac09a200f23a47dae3a7253c4f27ae948c6cf66a53c5a46047d78dc"} 22:44:47 INFO - Running post-run listener: copy_logs_to_upload_dir 22:44:47 INFO - Copying logs to upload dir... 22:44:47 INFO - mkdir: /builds/slave/test/build/upload/logs 22:44:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.491349 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-29 22:44:47.295275) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 22:44:47.298356) ========= 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.9J2HCYhioV/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.CU78kMB9gf/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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/Mozilla-Aurora/sha512/a5e834f3a963d999a03ad977112a575dd4e2e71f22080837d630d0daa1d70ecdddaa2a9901c109d8be937d77f1fc1ade25d377f2a55ecacf97fad67b4f223151", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c28c76b769e58b97d530e08eadc4c9b1738e5c142cd4cde55b27446060d44dfac0c2f76cd6fcd30376d5e88b6371325875c395ef6e8041758095175eeb093f8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9990b65458917e6dd57ce33da32306308cd1d4fde541b1bae5a374999bb7396d3fb6de1cbac09a200f23a47dae3a7253c4f27ae948c6cf66a53c5a46047d78dc"} build_url:https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011004 build_url: 'https://queue.taskcluster.net/v1/task/EudH9QZASNuvq-jyIqIoXA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5e834f3a963d999a03ad977112a575dd4e2e71f22080837d630d0daa1d70ecdddaa2a9901c109d8be937d77f1fc1ade25d377f2a55ecacf97fad67b4f223151", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c28c76b769e58b97d530e08eadc4c9b1738e5c142cd4cde55b27446060d44dfac0c2f76cd6fcd30376d5e88b6371325875c395ef6e8041758095175eeb093f8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9990b65458917e6dd57ce33da32306308cd1d4fde541b1bae5a374999bb7396d3fb6de1cbac09a200f23a47dae3a7253c4f27ae948c6cf66a53c5a46047d78dc"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 22:44:47.340387) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 22:44:47.340725) ========= 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.9J2HCYhioV/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.CU78kMB9gf/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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.005196 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 22:44:47.389740) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 22:44:47.393108) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 22:44:47.393478) ========= ========= Total master_lag: 0.26 =========