builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0164 starttime: 1477755237.15 results: success (0) buildid: 20161029061822 builduid: 5140b3c3bab3447b967a6610286c5c7b revision: 409d8a10d867013fd454ae4e27a42d5b0e40aa31 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-29 08:33:57.148841) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-29 08:33:57.149315) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-29 08:33:57.149590) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-29 08:33:57.175412) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 08:33:57.175803) ========= 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.776cMy3Jwb/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.3FIZgaSBtd/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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-10-29 08:33:57-- 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% 67.1M=0s 2016-10-29 08:33:57 (67.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132374 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 08:33:57.329594) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 08:33:57.329904) ========= 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.776cMy3Jwb/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.3FIZgaSBtd/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.026858 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 08:33:57.379127) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 08:33:57.379487) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 409d8a10d867013fd454ae4e27a42d5b0e40aa31 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 409d8a10d867013fd454ae4e27a42d5b0e40aa31 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.776cMy3Jwb/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.3FIZgaSBtd/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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-10-29 08:33:57,456 truncating revision to first 12 chars 2016-10-29 08:33:57,456 Setting DEBUG logging. 2016-10-29 08:33:57,456 attempt 1/10 2016-10-29 08:33:57,456 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/409d8a10d867?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-29 08:33:58,260 unpacking tar archive at: mozilla-central-409d8a10d867/testing/mozharness/ program finished with exit code 0 elapsedTime=1.060600 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 08:33:58.462571) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-29 08:33:58.462974) ========= script_repo_revision: 409d8a10d867013fd454ae4e27a42d5b0e40aa31 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-29 08:33:58.463399) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-29 08:33:58.463672) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-29 08:33:58.482558) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-29 08:33:58.482891) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.776cMy3Jwb/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.3FIZgaSBtd/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:33:58 INFO - MultiFileLogger online at 20161029 08:33:58 in /builds/slave/test 08:33:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:33:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:33:58 INFO - {'append_to_log': False, 08:33:58 INFO - 'base_work_dir': '/builds/slave/test', 08:33:58 INFO - 'blob_upload_branch': 'Firefox', 08:33:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:33:58 INFO - 'branch': 'Firefox', 08:33:58 INFO - 'buildbot_json_path': 'buildprops.json', 08:33:58 INFO - 'config_files': ('talos/mac_config.py',), 08:33:58 INFO - 'default_actions': ('clobber', 08:33:58 INFO - 'read-buildbot-config', 08:33:58 INFO - 'download-and-extract', 08:33:58 INFO - 'populate-webroot', 08:33:58 INFO - 'create-virtualenv', 08:33:58 INFO - 'install', 08:33:58 INFO - 'run-tests'), 08:33:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:33:58 INFO - 'download_minidump_stackwalk': True, 08:33:58 INFO - 'download_symbols': 'ondemand', 08:33:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:33:58 INFO - 'tooltool.py': '/tools/tooltool.py', 08:33:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:33:58 INFO - '/tools/misc-python/virtualenv.py')}, 08:33:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:33:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:33:58 INFO - 'installer_path': 'installer.exe', 08:33:58 INFO - 'log_level': 'info', 08:33:58 INFO - 'log_name': 'talos', 08:33:58 INFO - 'log_to_console': True, 08:33:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:33:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:33:58 INFO - 'opt_config_files': (), 08:33:58 INFO - 'pip_index': False, 08:33:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:33:58 INFO - 'cmd': ('bash', 08:33:58 INFO - '-c', 08:33:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:33:58 INFO - 'enabled': True, 08:33:58 INFO - 'halt_on_failure': False, 08:33:58 INFO - 'name': 'check_screen_resolution'},), 08:33:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:33:58 INFO - 'cmd': ('bash', 08:33:58 INFO - '-c', 08:33:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:33:58 INFO - 'enabled': True, 08:33:58 INFO - 'halt_on_failure': False, 08:33:58 INFO - 'name': 'check_screen_resolution'},), 08:33:58 INFO - 'run_cmd_checks_enabled': True, 08:33:58 INFO - 'sps_profile': False, 08:33:58 INFO - 'sps_profile_interval': 0, 08:33:58 INFO - 'suite': 'dromaeojs-e10s', 08:33:58 INFO - 'system_bits': '32', 08:33:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:33:58 INFO - 'title': 't-yosemite-r7-0164', 08:33:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:33:58 INFO - 'use_talos_json': True, 08:33:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:33:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:33:58 INFO - 'work_dir': 'build'} 08:33:58 INFO - [mozharness: 2016-10-29 15:33:58.691896Z] Running clobber step. 08:33:58 INFO - Running pre-action listener: _resource_record_pre_action 08:33:58 INFO - Running main action method: clobber 08:33:58 INFO - rmtree: /builds/slave/test/build 08:33:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:34:00 INFO - Running post-action listener: _resource_record_post_action 08:34:00 INFO - [mozharness: 2016-10-29 15:34:00.909288Z] Finished clobber step (success) 08:34:00 INFO - [mozharness: 2016-10-29 15:34:00.909481Z] Running read-buildbot-config step. 08:34:00 INFO - Running pre-action listener: _resource_record_pre_action 08:34:00 INFO - Running main action method: read_buildbot_config 08:34:00 INFO - Using buildbot properties: 08:34:00 INFO - { 08:34:00 INFO - "project": "", 08:34:00 INFO - "product": "firefox", 08:34:00 INFO - "script_repo_revision": "production", 08:34:00 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:34:00 INFO - "repository": "", 08:34:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:34:00 INFO - "buildid": "20161029061822", 08:34:00 INFO - "pgo_build": "False", 08:34:00 INFO - "basedir": "/builds/slave/test", 08:34:00 INFO - "buildnumber": 56, 08:34:00 INFO - "slavename": "t-yosemite-r7-0164", 08:34:00 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 08:34:00 INFO - "platform": "yosemite_r7", 08:34:00 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:34:00 INFO - "branch": "mozilla-central", 08:34:00 INFO - "repo_path": "mozilla-central", 08:34:00 INFO - "revision": "409d8a10d867013fd454ae4e27a42d5b0e40aa31", 08:34:00 INFO - "stage_platform": "macosx64", 08:34:00 INFO - "builduid": "5140b3c3bab3447b967a6610286c5c7b", 08:34:00 INFO - "slavebuilddir": "test" 08:34:00 INFO - } 08:34:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 08:34:00 INFO - Running post-action listener: _resource_record_post_action 08:34:00 INFO - [mozharness: 2016-10-29 15:34:00.913351Z] Finished read-buildbot-config step (success) 08:34:00 INFO - [mozharness: 2016-10-29 15:34:00.913484Z] Running download-and-extract step. 08:34:00 INFO - Running pre-action listener: _resource_record_pre_action 08:34:00 INFO - Running main action method: download_and_extract 08:34:00 INFO - mkdir: /builds/slave/test/build/tests 08:34:00 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')]} 08:34:00 INFO - trying https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 08:34:00 INFO - Downloading https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:34:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 08:34:01 INFO - Downloaded 1199 bytes. 08:34:01 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:34:01 INFO - Using the following test package requirements: 08:34:01 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:34:01 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:34:01 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 08:34:01 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:34:01 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 08:34:01 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:34:01 INFO - u'jsshell-mac.zip'], 08:34:01 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:34:01 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 08:34:01 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:34:01 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:34:01 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 08:34:01 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:34:01 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 08:34:01 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:34:01 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 08:34:01 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:34:01 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 08:34:01 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:34:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:34:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:34:01 INFO - Fetch https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:34:02 INFO - Http code: 200 08:34:02 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 08:34:02 INFO - x-amz-cf-id: ZQPHOGRgr2YaNAaXhbsLWrLjS-eR2F84aA5MBRRWEA7FWNUtX9c-qw== 08:34:02 INFO - x-amz-version-id: CHJoX68ckdOu7xGt73RFLol0FIy2zHjD 08:34:03 INFO - Expected file size: 16391684 08:34:03 INFO - Obtained file size: 16391684 08:34:10 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:34:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:34:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:34:10 INFO - Fetch https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:34:10 INFO - Http code: 200 08:34:10 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 08:34:10 INFO - x-amz-cf-id: jyeuoG7Cv9O9qpP5nZm3HLVSFj_AXbF_s1XVfckq5vLDIGaA7FN-VA== 08:34:10 INFO - x-amz-version-id: CHJoX68ckdOu7xGt73RFLol0FIy2zHjD 08:34:11 INFO - Expected file size: 16391684 08:34:11 INFO - Obtained file size: 16391684 08:34:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 08:34:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:34:17 INFO - Fetch https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 08:34:18 INFO - Http code: 200 08:34:18 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 08:34:18 INFO - x-amz-cf-id: ZF3DBblqWpsTMjtssrAW_9_lUCLsoFN97gmV7xM6FwYeKt12_TEJDg== 08:34:18 INFO - x-amz-version-id: LJVKjmMN8uJ.NbyjduhY3FuQQB3YfI1j 08:34:19 INFO - Expected file size: 14409038 08:34:19 INFO - Obtained file size: 14409038 08:34:19 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')]} 08:34:19 INFO - trying https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:34:19 INFO - Downloading https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 08:34:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 08:34:23 INFO - Downloaded 112036755 bytes. 08:34:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:34:23 INFO - mkdir: /builds/slave/test/properties 08:34:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:34:23 INFO - Writing to file /builds/slave/test/properties/build_url 08:34:23 INFO - Contents: 08:34:23 INFO - build_url:https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:34:24 INFO - Running post-action listener: _resource_record_post_action 08:34:24 INFO - Running post-action listener: set_extra_try_arguments 08:34:24 INFO - [mozharness: 2016-10-29 15:34:24.640373Z] Finished download-and-extract step (success) 08:34:24 INFO - [mozharness: 2016-10-29 15:34:24.640479Z] Running populate-webroot step. 08:34:24 INFO - Running pre-action listener: _resource_record_pre_action 08:34:24 INFO - Running main action method: populate_webroot 08:34:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:34:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:34:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:34:24 INFO - '--tppagecycles', 08:34:24 INFO - '7'], 08:34:24 INFO - 'tests': ['tsvgm']}}, 08:34:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:34:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:34:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:34:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:34:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:34:24 INFO - 'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:34:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:34:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:34:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:34:24 INFO - 'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['damp', 'tps']}, 08:34:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:34:24 INFO - 'tests': ['damp', 'tps']}, 08:34:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['dromaeo_dom']}, 08:34:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:34:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['basic_compositor_video']}, 08:34:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:34:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['a11yr', 08:34:24 INFO - 'ts_paint', 08:34:24 INFO - 'tpaint', 08:34:24 INFO - 'sessionrestore', 08:34:24 INFO - 'sessionrestore_no_auto_restore', 08:34:24 INFO - 'tabpaint']}, 08:34:24 INFO - 'other-e10s': {'tests': ['a11yr', 08:34:24 INFO - 'ts_paint', 08:34:24 INFO - 'tpaint', 08:34:24 INFO - 'sessionrestore', 08:34:24 INFO - 'sessionrestore_no_auto_restore', 08:34:24 INFO - 'tabpaint']}, 08:34:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:34:24 INFO - 'ts_paint', 08:34:24 INFO - 'tpaint', 08:34:24 INFO - 'sessionrestore', 08:34:24 INFO - 'sessionrestore_no_auto_restore', 08:34:24 INFO - 'tabpaint']}, 08:34:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:34:24 INFO - 'ts_paint', 08:34:24 INFO - 'tpaint', 08:34:24 INFO - 'sessionrestore', 08:34:24 INFO - 'sessionrestore_no_auto_restore', 08:34:24 INFO - 'tabpaint']}, 08:34:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['a11yr', 08:34:24 INFO - 'ts_paint', 08:34:24 INFO - 'tpaint', 08:34:24 INFO - 'sessionrestore', 08:34:24 INFO - 'sessionrestore_no_auto_restore', 08:34:24 INFO - 'tabpaint']}, 08:34:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['a11yr', 08:34:24 INFO - 'ts_paint', 08:34:24 INFO - 'tpaint', 08:34:24 INFO - 'sessionrestore', 08:34:24 INFO - 'sessionrestore_no_auto_restore', 08:34:24 INFO - 'tabpaint']}, 08:34:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['tsvgx', 08:34:24 INFO - 'tsvgr_opacity', 08:34:24 INFO - 'tart', 08:34:24 INFO - 'tscrollx', 08:34:24 INFO - 'cart']}, 08:34:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:34:24 INFO - 'tsvgr_opacity', 08:34:24 INFO - 'tart', 08:34:24 INFO - 'tscrollx', 08:34:24 INFO - 'cart']}, 08:34:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:34:24 INFO - 'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['tp5o']}, 08:34:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:34:24 INFO - 'tests': ['tp5o']}, 08:34:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:34:24 INFO - 'talos_options': ['--disable-e10s', 08:34:24 INFO - '--xperf_path', 08:34:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:34:24 INFO - 'tests': ['tp5n']}, 08:34:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:34:24 INFO - 'talos_options': ['--xperf_path', 08:34:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:34:24 INFO - 'tests': ['tp5n']}}, 08:34:24 INFO - 'talos.zip': {'path': '', 08:34:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:34:24 INFO - Running post-action listener: _resource_record_post_action 08:34:24 INFO - [mozharness: 2016-10-29 15:34:24.659271Z] Finished populate-webroot step (success) 08:34:24 INFO - [mozharness: 2016-10-29 15:34:24.659379Z] Running create-virtualenv step. 08:34:24 INFO - Running pre-action listener: _resource_record_pre_action 08:34:24 INFO - Running main action method: create_virtualenv 08:34:24 INFO - Creating virtualenv /builds/slave/test/build/venv 08:34:24 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 08:34:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:34:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:34:24 INFO - Using real prefix '/tools/python27' 08:34:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:34:25 INFO - Installing distribute.............................................................................................................................................................................................done. 08:34:28 INFO - Installing pip.................done. 08:34:28 INFO - Return code: 0 08:34:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:34:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:34:28 INFO - Reading from file tmpfile_stdout 08:34:28 INFO - Output received: 08:34:28 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) 08:34:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:34: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')]} 08:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34: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')]} 08:34:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:28 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 0x108795490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10870b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff863d65d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a9d30>, '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 0x1083cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085a1b20>, '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.776cMy3Jwb/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.3FIZgaSBtd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:34:28 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 08:34:28 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 08:34:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.776cMy3Jwb/Render', 08:34:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:28 INFO - 'HOME': '/Users/cltbld', 08:34:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:28 INFO - 'LOGNAME': 'cltbld', 08:34:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:28 INFO - 'MOZ_NO_REMOTE': '1', 08:34:28 INFO - 'NO_EM_RESTART': '1', 08:34:28 INFO - 'PAGER': '/bin/cat', 08:34:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:28 INFO - 'PWD': '/builds/slave/test', 08:34:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:28 INFO - 'SHELL': '/bin/bash', 08:34:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3FIZgaSBtd/Listeners', 08:34:28 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:34:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:28 INFO - 'USER': 'cltbld', 08:34:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:28 INFO - 'XPC_FLAGS': '0x0', 08:34:28 INFO - 'XPC_SERVICE_NAME': '0', 08:34:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:28 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 08:34:28 INFO - Cleaning up... 08:34:28 INFO - Return code: 0 08:34:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:34: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')]} 08:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34: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')]} 08:34:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:28 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 0x108795490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10870b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff863d65d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a9d30>, '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 0x1083cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085a1b20>, '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.776cMy3Jwb/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.3FIZgaSBtd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:34:28 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 08:34:28 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 08:34:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.776cMy3Jwb/Render', 08:34:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:28 INFO - 'HOME': '/Users/cltbld', 08:34:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:28 INFO - 'LOGNAME': 'cltbld', 08:34:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:28 INFO - 'MOZ_NO_REMOTE': '1', 08:34:28 INFO - 'NO_EM_RESTART': '1', 08:34:28 INFO - 'PAGER': '/bin/cat', 08:34:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:28 INFO - 'PWD': '/builds/slave/test', 08:34:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:28 INFO - 'SHELL': '/bin/bash', 08:34:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3FIZgaSBtd/Listeners', 08:34:28 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:34:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:28 INFO - 'USER': 'cltbld', 08:34:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:28 INFO - 'XPC_FLAGS': '0x0', 08:34:28 INFO - 'XPC_SERVICE_NAME': '0', 08:34:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:29 INFO - Downloading/unpacking psutil>=3.1.1 08:34:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:34:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:34:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:34:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:34:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:34:32 INFO - Installing collected packages: psutil 08:34:32 INFO - Running setup.py install for psutil 08:34:32 INFO - building 'psutil._psutil_osx' extension 08:34:32 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 08:34:33 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 08:34:33 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 08:34:33 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 08:34:33 INFO - building 'psutil._psutil_posix' extension 08:34:33 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 08:34:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:34:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:34:33 INFO - ^ 08:34:33 INFO - 1 warning generated. 08:34:33 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 08:34:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:34:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:34:33 INFO - Successfully installed psutil 08:34:33 INFO - Cleaning up... 08:34:33 INFO - Return code: 0 08:34:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:34:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:33 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 0x108795490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10870b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff863d65d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a9d30>, '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 0x1083cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085a1b20>, '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.776cMy3Jwb/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.3FIZgaSBtd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:34:33 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 08:34:33 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 08:34:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.776cMy3Jwb/Render', 08:34:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:33 INFO - 'HOME': '/Users/cltbld', 08:34:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:33 INFO - 'LOGNAME': 'cltbld', 08:34:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:33 INFO - 'MOZ_NO_REMOTE': '1', 08:34:33 INFO - 'NO_EM_RESTART': '1', 08:34:33 INFO - 'PAGER': '/bin/cat', 08:34:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:33 INFO - 'PWD': '/builds/slave/test', 08:34:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:33 INFO - 'SHELL': '/bin/bash', 08:34:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3FIZgaSBtd/Listeners', 08:34:33 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:34:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:33 INFO - 'USER': 'cltbld', 08:34:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:33 INFO - 'XPC_FLAGS': '0x0', 08:34:33 INFO - 'XPC_SERVICE_NAME': '0', 08:34:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:34:34 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 08:34:34 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 08:34:34 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 08:34:34 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 08:34:34 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 08:34:34 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 08:34:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:34:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:34:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:34:36 INFO - Installing collected packages: mozsystemmonitor 08:34:36 INFO - Running setup.py install for mozsystemmonitor 08:34:36 INFO - Successfully installed mozsystemmonitor 08:34:36 INFO - Cleaning up... 08:34:36 INFO - Return code: 0 08:34:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:34:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108795490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10870b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff863d65d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a9d30>, '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 0x1083cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085a1b20>, '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.776cMy3Jwb/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.3FIZgaSBtd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:34:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:34:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:34:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.776cMy3Jwb/Render', 08:34:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:36 INFO - 'HOME': '/Users/cltbld', 08:34:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:36 INFO - 'LOGNAME': 'cltbld', 08:34:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:36 INFO - 'MOZ_NO_REMOTE': '1', 08:34:36 INFO - 'NO_EM_RESTART': '1', 08:34:36 INFO - 'PAGER': '/bin/cat', 08:34:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:36 INFO - 'PWD': '/builds/slave/test', 08:34:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:36 INFO - 'SHELL': '/bin/bash', 08:34:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3FIZgaSBtd/Listeners', 08:34:36 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:34:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:36 INFO - 'USER': 'cltbld', 08:34:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:36 INFO - 'XPC_FLAGS': '0x0', 08:34:36 INFO - 'XPC_SERVICE_NAME': '0', 08:34:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:37 INFO - Downloading/unpacking jsonschema==2.5.1 08:34:37 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 08:34:37 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 08:34:37 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 08:34:37 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 08:34:37 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 08:34:37 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 08:34:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:34:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:34:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:34:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:34:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:39 INFO - Downloading functools32-3.2.3-2.tar.gz 08:34:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:34:40 INFO - warning: no files found matching '*.txt' 08:34:40 INFO - no previously-included directories found matching 'build' 08:34:40 INFO - no previously-included directories found matching 'dist' 08:34:40 INFO - no previously-included directories found matching '.git*' 08:34:40 INFO - Installing collected packages: jsonschema, functools32 08:34:40 INFO - Running setup.py install for functools32 08:34:40 INFO - warning: no files found matching '*.txt' 08:34:40 INFO - no previously-included directories found matching 'build' 08:34:40 INFO - no previously-included directories found matching 'dist' 08:34:40 INFO - no previously-included directories found matching '.git*' 08:34:40 INFO - Successfully installed jsonschema functools32 08:34:40 INFO - Cleaning up... 08:34:40 INFO - Return code: 0 08:34:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:34:40 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')]} 08:34:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:40 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')]} 08:34:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:40 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 0x108795490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10870b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff863d65d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a9d30>, '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 0x1083cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085a1b20>, '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.776cMy3Jwb/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.3FIZgaSBtd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:34:40 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 08:34:40 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 08:34:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.776cMy3Jwb/Render', 08:34:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:40 INFO - 'HOME': '/Users/cltbld', 08:34:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:40 INFO - 'LOGNAME': 'cltbld', 08:34:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:40 INFO - 'MOZ_NO_REMOTE': '1', 08:34:40 INFO - 'NO_EM_RESTART': '1', 08:34:40 INFO - 'PAGER': '/bin/cat', 08:34:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:40 INFO - 'PWD': '/builds/slave/test', 08:34:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:40 INFO - 'SHELL': '/bin/bash', 08:34:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3FIZgaSBtd/Listeners', 08:34:40 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:34:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:40 INFO - 'USER': 'cltbld', 08:34:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:40 INFO - 'XPC_FLAGS': '0x0', 08:34:40 INFO - 'XPC_SERVICE_NAME': '0', 08:34:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:34:40 INFO - Cleaning up... 08:34:40 INFO - Return code: 0 08:34:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:34:40 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')]} 08:34:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:40 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')]} 08:34:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:40 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 0x108795490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10870b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff863d65d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a9d30>, '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 0x1083cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085a1b20>, '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.776cMy3Jwb/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.3FIZgaSBtd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:34:40 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 08:34:40 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 08:34:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.776cMy3Jwb/Render', 08:34:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:40 INFO - 'HOME': '/Users/cltbld', 08:34:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:40 INFO - 'LOGNAME': 'cltbld', 08:34:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:40 INFO - 'MOZ_NO_REMOTE': '1', 08:34:40 INFO - 'NO_EM_RESTART': '1', 08:34:40 INFO - 'PAGER': '/bin/cat', 08:34:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:40 INFO - 'PWD': '/builds/slave/test', 08:34:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:40 INFO - 'SHELL': '/bin/bash', 08:34:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3FIZgaSBtd/Listeners', 08:34:40 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:34:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:40 INFO - 'USER': 'cltbld', 08:34:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:40 INFO - 'XPC_FLAGS': '0x0', 08:34:40 INFO - 'XPC_SERVICE_NAME': '0', 08:34:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:40 INFO - Downloading/unpacking blobuploader==1.2.4 08:34:40 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 08:34:40 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 08:34:40 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 08:34:40 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 08:34:40 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 08:34:40 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 08:34:43 INFO - Downloading blobuploader-1.2.4.tar.gz 08:34:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:34:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:34:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:34:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:34:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:34:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:34:44 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 08:34:44 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 08:34:44 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 08:34:44 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 08:34:44 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 08:34:44 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 08:34:44 INFO - Downloading docopt-0.6.1.tar.gz 08:34:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:34:44 INFO - Installing collected packages: blobuploader, requests, docopt 08:34:44 INFO - Running setup.py install for blobuploader 08:34:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:34:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:34:44 INFO - Running setup.py install for requests 08:34:45 INFO - Running setup.py install for docopt 08:34:45 INFO - Successfully installed blobuploader requests docopt 08:34:45 INFO - Cleaning up... 08:34:45 INFO - Return code: 0 08:34:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:34:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:45 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 0x108795490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10870b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff863d65d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a9d30>, '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 0x1083cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085a1b20>, '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.776cMy3Jwb/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.3FIZgaSBtd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:34:45 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 08:34:45 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 08:34:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.776cMy3Jwb/Render', 08:34:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:45 INFO - 'HOME': '/Users/cltbld', 08:34:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:45 INFO - 'LOGNAME': 'cltbld', 08:34:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:45 INFO - 'MOZ_NO_REMOTE': '1', 08:34:45 INFO - 'NO_EM_RESTART': '1', 08:34:45 INFO - 'PAGER': '/bin/cat', 08:34:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:45 INFO - 'PWD': '/builds/slave/test', 08:34:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:45 INFO - 'SHELL': '/bin/bash', 08:34:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3FIZgaSBtd/Listeners', 08:34:45 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:34:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:45 INFO - 'USER': 'cltbld', 08:34:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:45 INFO - 'XPC_FLAGS': '0x0', 08:34:45 INFO - 'XPC_SERVICE_NAME': '0', 08:34:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:34:45 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-35nPHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:34:45 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-7ATv__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:34:45 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-v_7uhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:34:46 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Bxsi4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:34:46 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-xvEaGn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:34:46 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-UFwKKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:34:46 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-p3tD4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:34:46 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-kO4taA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:34:46 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-iU_B1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:34:46 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-wUks3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:34:46 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-t2Co_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:34:46 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-woLtRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:34:46 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-vkA1Z7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:34:47 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Bt8DJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:34:47 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-da9mi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:34:47 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-QGH0tk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:34:47 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-NaFkoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:34:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:34:47 INFO - Running setup.py install for manifestparser 08:34:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:34:47 INFO - Running setup.py install for mozcrash 08:34:47 INFO - Running setup.py install for mozdebug 08:34:47 INFO - Running setup.py install for mozdevice 08:34:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:34:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:34:48 INFO - Running setup.py install for mozfile 08:34:48 INFO - Running setup.py install for mozhttpd 08:34:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:34:48 INFO - Running setup.py install for mozinfo 08:34:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:34:48 INFO - Running setup.py install for mozInstall 08:34:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:34:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:34:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:34:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:34:48 INFO - Running setup.py install for mozleak 08:34:48 INFO - Running setup.py install for mozlog 08:34:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:34:48 INFO - Running setup.py install for moznetwork 08:34:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:34:49 INFO - Running setup.py install for mozprocess 08:34:49 INFO - Running setup.py install for mozprofile 08:34:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:34:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:34:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:34:49 INFO - Running setup.py install for mozrunner 08:34:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:34:49 INFO - Running setup.py install for mozscreenshot 08:34:49 INFO - Running setup.py install for moztest 08:34:49 INFO - Running setup.py install for mozversion 08:34:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:34:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:34:50 INFO - Cleaning up... 08:34:50 INFO - Return code: 0 08:34:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:50 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 0x108795490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10870b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff863d65d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a9d30>, '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 0x1083cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085a1b20>, '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.776cMy3Jwb/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.3FIZgaSBtd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:34:50 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 08:34:50 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 08:34:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.776cMy3Jwb/Render', 08:34:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:50 INFO - 'HOME': '/Users/cltbld', 08:34:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:50 INFO - 'LOGNAME': 'cltbld', 08:34:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:50 INFO - 'MOZ_NO_REMOTE': '1', 08:34:50 INFO - 'NO_EM_RESTART': '1', 08:34:50 INFO - 'PAGER': '/bin/cat', 08:34:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:50 INFO - 'PWD': '/builds/slave/test', 08:34:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:50 INFO - 'SHELL': '/bin/bash', 08:34:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3FIZgaSBtd/Listeners', 08:34:50 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:34:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:50 INFO - 'USER': 'cltbld', 08:34:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:50 INFO - 'XPC_FLAGS': '0x0', 08:34:50 INFO - 'XPC_SERVICE_NAME': '0', 08:34:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:34:50 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-9_rHAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:34:50 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)) 08:34:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:34:50 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-XJYBO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:34:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:34:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:34:50 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-AchHJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:34:50 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)) 08:34:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:34:50 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Fwxs8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:34:50 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)) 08:34:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:34:50 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-fh4E1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:34:50 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)) 08:34:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:34:50 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Khdm0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:34:50 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)) 08:34:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:34:50 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-iTZHZl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:34:51 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)) 08:34:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:34:51 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-PLvHV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:34:51 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)) 08:34:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:34:51 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-M_JGYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:34:51 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)) 08:34:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:34:51 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-PE6D9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:34:51 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)) 08:34:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:34:51 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-la_W_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:34:51 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)) 08:34:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:34:51 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-CvfTTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:34:51 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)) 08:34:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:34:51 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-UrNnM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:34:51 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)) 08:34:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:34:51 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-o4KAuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:34:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:34:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:34:51 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-_m8cAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:34:51 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)) 08:34:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:34:51 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-YA1smP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:34:51 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)) 08:34:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:34:51 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-fDYUZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:34:52 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)) 08:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:34:52 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)) 08:34:52 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)) 08:34:52 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)) 08:34:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:34:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:55 INFO - Downloading blessings-1.6.tar.gz 08:34:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:34:55 INFO - Installing collected packages: blessings 08:34:55 INFO - Running setup.py install for blessings 08:34:55 INFO - Successfully installed blessings 08:34:55 INFO - Cleaning up... 08:34:55 INFO - Return code: 0 08:34:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:34:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:34:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:34:55 INFO - Reading from file tmpfile_stdout 08:34:55 INFO - Current package versions: 08:34:55 INFO - blessings == 1.6 08:34:55 INFO - blobuploader == 1.2.4 08:34:55 INFO - docopt == 0.6.1 08:34:55 INFO - functools32 == 3.2.3-2 08:34:55 INFO - jsonschema == 2.5.1 08:34:55 INFO - manifestparser == 1.1 08:34:55 INFO - mozInstall == 1.12 08:34:55 INFO - mozcrash == 0.17 08:34:55 INFO - mozdebug == 0.1 08:34:55 INFO - mozdevice == 0.48 08:34:55 INFO - mozfile == 1.2 08:34:55 INFO - mozhttpd == 0.7 08:34:55 INFO - mozinfo == 0.9 08:34:55 INFO - mozleak == 0.1 08:34:55 INFO - mozlog == 3.4 08:34:55 INFO - moznetwork == 0.27 08:34:55 INFO - mozprocess == 0.23 08:34:55 INFO - mozprofile == 0.28 08:34:55 INFO - mozrunner == 6.12 08:34:55 INFO - mozscreenshot == 0.1 08:34:55 INFO - mozsystemmonitor == 0.3 08:34:55 INFO - moztest == 0.7 08:34:55 INFO - mozversion == 1.4 08:34:55 INFO - psutil == 3.1.1 08:34:55 INFO - requests == 1.2.3 08:34:55 INFO - wsgiref == 0.1.2 08:34:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:34:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:56 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 0x108795490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10870b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff863d65d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a9d30>, '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 0x1083cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085a1b20>, '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.776cMy3Jwb/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.3FIZgaSBtd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:34:56 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 08:34:56 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 08:34:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.776cMy3Jwb/Render', 08:34:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:56 INFO - 'HOME': '/Users/cltbld', 08:34:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:56 INFO - 'LOGNAME': 'cltbld', 08:34:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:56 INFO - 'MOZ_NO_REMOTE': '1', 08:34:56 INFO - 'NO_EM_RESTART': '1', 08:34:56 INFO - 'PAGER': '/bin/cat', 08:34:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:56 INFO - 'PWD': '/builds/slave/test', 08:34:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:56 INFO - 'SHELL': '/bin/bash', 08:34:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3FIZgaSBtd/Listeners', 08:34:56 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:34:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:56 INFO - 'USER': 'cltbld', 08:34:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:56 INFO - 'XPC_FLAGS': '0x0', 08:34:56 INFO - 'XPC_SERVICE_NAME': '0', 08:34:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:56 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)) 08:34:56 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)) 08:34:56 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)) 08:34:56 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)) 08:34:56 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)) 08:34:56 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)) 08:34:56 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)) 08:34:56 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)) 08:34:56 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)) 08:34:56 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)) 08:34:56 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)) 08:34:56 INFO - Cleaning up... 08:34:56 INFO - Return code: 0 08:34:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:56 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 0x108795490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10870b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff863d65d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085a9d30>, '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 0x1083cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085a1b20>, '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.776cMy3Jwb/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.3FIZgaSBtd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:34:56 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 08:34:56 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 08:34:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.776cMy3Jwb/Render', 08:34:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:56 INFO - 'HOME': '/Users/cltbld', 08:34:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:56 INFO - 'LOGNAME': 'cltbld', 08:34:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:56 INFO - 'MOZ_NO_REMOTE': '1', 08:34:56 INFO - 'NO_EM_RESTART': '1', 08:34:56 INFO - 'PAGER': '/bin/cat', 08:34:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:56 INFO - 'PWD': '/builds/slave/test', 08:34:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:56 INFO - 'SHELL': '/bin/bash', 08:34:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3FIZgaSBtd/Listeners', 08:34:56 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:34:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:56 INFO - 'USER': 'cltbld', 08:34:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:56 INFO - 'XPC_FLAGS': '0x0', 08:34:56 INFO - 'XPC_SERVICE_NAME': '0', 08:34:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:34:56 INFO - Cleaning up... 08:34:56 INFO - Return code: 0 08:34:56 INFO - Running post-action listener: _resource_record_post_action 08:34:56 INFO - Running post-action listener: _start_resource_monitoring 08:34:56 INFO - Starting resource monitoring. 08:34:56 INFO - [mozharness: 2016-10-29 15:34:56.627060Z] Finished create-virtualenv step (success) 08:34:56 INFO - [mozharness: 2016-10-29 15:34:56.628043Z] Running install step. 08:34:56 INFO - Running pre-action listener: _resource_record_pre_action 08:34:56 INFO - Running main action method: install 08:34:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:34:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:34:56 INFO - Reading from file tmpfile_stdout 08:34:56 INFO - Detecting whether we're running mozinstall >=1.0... 08:34:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:34:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:34:56 INFO - Reading from file tmpfile_stdout 08:34:56 INFO - Output received: 08:34:56 INFO - Usage: mozinstall [options] installer 08:34:56 INFO - Options: 08:34:56 INFO - -h, --help show this help message and exit 08:34:56 INFO - -d DEST, --destination=DEST 08:34:56 INFO - Directory to install application into. [default: 08:34:56 INFO - "/builds/slave/test"] 08:34:56 INFO - --app=APP Application being installed. [default: firefox] 08:34:56 INFO - mkdir: /builds/slave/test/build/application 08:34:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:34:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:35:31 INFO - Reading from file tmpfile_stdout 08:35:31 INFO - Output received: 08:35:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:35:31 INFO - Running post-action listener: _resource_record_post_action 08:35:31 INFO - [mozharness: 2016-10-29 15:35:31.487865Z] Finished install step (success) 08:35:31 INFO - [mozharness: 2016-10-29 15:35:31.487988Z] Running run-tests step. 08:35:31 INFO - Running pre-action listener: _resource_record_pre_action 08:35:31 INFO - Running main action method: run_tests 08:35:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:35:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:35:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:35:31 INFO - 2016-10-29 08:35:31.502 screenresolution[1643:7498] starting screenresolution argv=screenresolution get 08:35:31 INFO - 2016-10-29 08:35:31.504 screenresolution[1643:7498] Display 0: 1600x1200x32@60 08:35:31 INFO - 2016-10-29 08:35:31.510 screenresolution[1644:7500] starting screenresolution argv=screenresolution list 08:35:31 INFO - Available Modes on Display 0 08:35:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:35:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:35:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:35:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:35:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:35:31 INFO - Intel Iris: 08:35:31 INFO - Chipset Model: Intel Iris 08:35:31 INFO - Type: GPU 08:35:31 INFO - Bus: Built-In 08:35:31 INFO - VRAM (Dynamic, Max): 1536 MB 08:35:31 INFO - Vendor: Intel (0x8086) 08:35:31 INFO - Device ID: 0x0a2e 08:35:31 INFO - Revision ID: 0x0009 08:35:31 INFO - Displays: 08:35:31 INFO - Display: 08:35:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:35:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:35:31 INFO - Display Serial Number: 1600x1200 60 08:35:31 INFO - Main Display: Yes 08:35:31 INFO - Mirror: Off 08:35:31 INFO - Online: Yes 08:35:31 INFO - Rotation: Supported 08:35:31 INFO - Return code: 0 08:35:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:35:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:35:31 INFO - Python 2.7.3 08:35:31 INFO - Return code: 0 08:35:31 INFO - grabbing minidump binary from tooltool 08:35:31 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')]} 08:35:31 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 0x1085a9d30>, '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 0x1083cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085a1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:35:31 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 08:35:31 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 08:35:31 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 08:35:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:35:31 INFO - Return code: 0 08:35:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:35:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:35:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:35:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:35:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:35:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--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 08:35:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0164 --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 08:35:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.776cMy3Jwb/Render', 08:35:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:31 INFO - 'HOME': '/Users/cltbld', 08:35:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:31 INFO - 'LOGNAME': 'cltbld', 08:35:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:35:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:35:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:31 INFO - 'MOZ_NO_REMOTE': '1', 08:35:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:35:31 INFO - 'NO_EM_RESTART': '1', 08:35:31 INFO - 'PAGER': '/bin/cat', 08:35:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:31 INFO - 'PWD': '/builds/slave/test', 08:35:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:35:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:31 INFO - 'SHELL': '/bin/bash', 08:35:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3FIZgaSBtd/Listeners', 08:35:31 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:35:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:31 INFO - 'USER': 'cltbld', 08:35:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:31 INFO - 'XPC_FLAGS': '0x0', 08:35:31 INFO - 'XPC_SERVICE_NAME': '0', 08:35:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--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 08:35:32 INFO - mozversion application_buildid: 20161029061822 08:35:32 INFO - mozversion application_changeset: 409d8a10d867013fd454ae4e27a42d5b0e40aa31 08:35:32 INFO - mozversion application_display_name: Nightly 08:35:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:35:32 INFO - mozversion application_name: Firefox 08:35:32 INFO - mozversion application_remotingname: firefox 08:35:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:35:32 INFO - mozversion application_vendor: Mozilla 08:35:32 INFO - mozversion application_version: 52.0a1 08:35:32 INFO - mozversion platform_buildid: 20161029061822 08:35:32 INFO - mozversion platform_changeset: 409d8a10d867013fd454ae4e27a42d5b0e40aa31 08:35:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:35:32 INFO - mozversion platform_version: 52.0a1 08:35:32 INFO - using testdate: 1477755332 08:35:32 INFO - actual date: 1477755332 08:35:32 INFO - starting webserver on 'localhost:49271' 08:35:32 INFO - SUITE-START | Running 2 tests 08:35:32 INFO - TEST-START | dromaeo_css 08:35:32 INFO - operating with platform_type : mac_ 08:35:32 INFO - Initialising browser for dromaeo_css test... 08:35:32 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmprhAsem/profile http://localhost:49271/getInfo.html) 08:35:35 INFO - PROCESS | 1654 | 2016-10-29 08:35:35.128 plugin-container[1656:7641] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 08:35:35 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:35 INFO - PROCESS | 1654 | 2016-10-29 08:35:35.131 plugin-container[1656:7641] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920f, name = 'com.apple.CFPasteboardClient' 08:35:35 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:35 INFO - PROCESS | 1654 | 2016-10-29 08:35:35.131 plugin-container[1656:7641] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:35:35 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 08:35:35 INFO - PROCESS | 1654 | colorDepth:24 08:35:35 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 08:35:35 INFO - PROCESS | 1654 | __metrics 08:35:35 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3131: TypeError: this.tabs is undefined 08:35:35 INFO - TEST-INFO | 1654: exit 0 08:35:35 INFO - Browser initialized. 08:35:35 INFO - Running cycle 1/1 for dromaeo_css test... 08:35:35 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmprhAsem/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:35:38 INFO - PROCESS | 1657 | 2016-10-29 08:35:38.346 plugin-container[1658:7759] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 08:35:38 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:38 INFO - PROCESS | 1657 | 2016-10-29 08:35:38.346 plugin-container[1658:7759] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0f, name = 'com.apple.CFPasteboardClient' 08:35:38 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:38 INFO - PROCESS | 1657 | 2016-10-29 08:35:38.346 plugin-container[1658:7759] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:35:47 INFO - PROCESS | 1657 | RSS: Main: 238018560 08:35:47 INFO - PROCESS | 1657 | 08:36:37 INFO - PROCESS | 1657 | 1477755397600 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] 08:38:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 08:38:53 INFO - PROCESS | 1657 | RSS: Main: 252239872 08:38:53 INFO - PROCESS | 1657 | 08:41:55 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 08:41:56 INFO - PROCESS | 1657 | RSS: Main: 262508544 08:41:56 INFO - PROCESS | 1657 | 08:44:58 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 08:44:58 INFO - PROCESS | 1657 | RSS: Main: 267010048 08:44:58 INFO - PROCESS | 1657 | 08:48:00 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 08:48:01 INFO - PROCESS | 1657 | RSS: Main: 265764864 08:48:01 INFO - PROCESS | 1657 | 08:51:04 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 08:51:04 INFO - PROCESS | 1657 | RSS: Main: 270327808 08:51:04 INFO - PROCESS | 1657 | 08:54:07 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 08:54:07 INFO - PROCESS | 1657 | RSS: Main: 272314368 08:54:07 INFO - PROCESS | 1657 | 08:54:07 INFO - PROCESS | 1657 | __start_tp_report 08:54:07 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 08:54:07 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 08:54:07 INFO - PROCESS | 1657 | |i|pagename|runs| 08:54:07 INFO - PROCESS | 1657 | |0;dojo.html;2779.9305017374563;3272.7872688275274;3353.195233144046;3389.322135572886;3395.966040339596;5461.918071228924;5528.949499595079;5552.7223638818095;5570.080936645454;5636.943630563704;4531.229690952538;4924.433690125627;5503.036968530511;5507.03626865299;5566.248556444879;5078.847634570969;5095.643304968654;5184.248283998818;5229.660072095302;5230.895382092356;5055.292259083732;5205.583553315744;5205.869853253661;5220.530152286294;5229.980153869997;4190.832366705328;4992.40091189056;5047.621428392885;5071.822486213002;5187.714675692835;5114.795408183668;5120.257562653432;5122.92315615266;5133.101707201225;5134.05020071288;4991.401031876162;5053.317802096743;5067.822626208102;5068;5099.59203263738;5465.344158700981;5538.695371754552;5552.666839989613;5579.107342825128;5583.3300003999375;5351.170568561879;6044.727987240584;6073.726682299307;6121.449069583714;6151.015837466029;6153.17434324788;6196.442320191204;6210.223722034746;6213.689315534205;6228.626282423069;5570.944290557083;5640.858978525488;5700.344460387026;5709.286339207599;5723.399043100498;6209.751609935643;6264.68676566179;6284.151639528696;6294.402031806971;6316.368363163647;5985.341612422717;6064.332923378425;6064.575479716377;6086.239220097488;6111.236095488064;4547.295169248771;6000.609960352563;6017.518598512109;6195.721192546346;6211.3478084801345;6069.180660610761;6086.02623580225;6092.39076092396;6121.6054024712375;6125.540694081788;6046.848828779228;6108.311716707923;6109.860177052684;6110.133516259496;6140.539459540552;4743.976280118646;4834.299026641188;4845.1278769821065;4856;4858.198397264482;3621.8189090545893;4274.871753847389;4320;4327.5239723630375;4431.069475410135;5316.973415132977;5406.486383793534;5683.971580142155;5709.914351284734;5716.628419152742;6178.7219575119225;6186.752529898844;6232.127502149703;6233.501319894398;6244.687765611701;5578.442155784389;5587.608867379245;5642.85892852682;5660.320761508563;5710.486056254957;5311.787528498894;5327.413984461706;5351.170568561879;5352.759125839347;5394.406615272317;5199.896002079861;6231.4391704745885;6261.092141639437;6284.183056202664;6297.968510157511;5773.451522105393;5848.766049357978;5959.0763431668165;6229.56393052482;6232.875342493216;6225.097360882737;6275.686215689106;6282.874342513215;6310;6310.936890631218;4955.603551715926;5930.11048342752;6042.607230530001;6090.025595904634;6118.510519158545;6092.055731361646;6102.458955353496;6142.9078563820685;6146.723397447036;6202.751889924533;4745.596624286836;5653.152027195789;5926.970365148147;6059.098349644519;6105.542084343603;4185.644220241192;4674.883127921829;5879.265091863517;5992.760289588367;5998.310194327533;5658.943410565842;5659.943400565941;5813.709314534341;5821.563382746395;5827.70861456917;4963.684623574683;5598.832035038792;5807.680577568274;5898.734556945034;5983.850403739942;4547.52251013652;5736.856578585569;5772.855678608068;5813.534917206529;5880.088586269135 08:54:07 INFO - PROCESS | 1657 | |1;ext.html;462.9699069560479;526.8524813052346;531.750077463592;539.2019810680193;540.5621446628229;40410;41875.790621046894;41936.79031604846;41990.790046049726;42140.73577792663;43193;43312.3503147453;43705;43785;43863.3420498692;9255.44467331959;9591.280653950964;9756.414615123116;9767.120959113678;9784.657536986208;9622.037796220357;9711;9723.805523889518;9748.122668959792;9777.608895644165;8238.670453181865;9693.079157480064;9747.707568772948;9759.02409759022;9765.707028789147;9665.710028699115;9723.076307750753;9749.366291191121;9762.755931101708;9768.658096966608;8036.8748375227515;9464.668736594254;9549.809003819953;9585.568649410794;9586.233101351874;41019.58980410187;41423.378649320286;41526.79236603828;41542;41648;3410.5225268462436;3634.60019397866;3852.7880966546827;3882.7476214045996;4114.518773241661;27304.4982525961;30634.24291813725;31066.048725371053;31458.37083258345;31499.212519686964;3190.7925984811145;3277.7541684373527;3310.7682462227654;3312.7681062325646;3362.7646064775477;468220.65889670677;471008;471701;473207;474583;56842;57120;57384;57396.71301643465;57699.423005770244;60873.39126608766;61046.69476652677;61084.38915610787;61395;61585.69207153936;15858.920705396398;16096.195190240442;16110.677786444205;16121.435749748702;16126.919365403099;11989.580364687194;12143.967762740265;12380.071494637757;12461.501539938483;12462.43919023646;14345.569632911029;15729.764053539206;15738.606534836492;15875.126868022138;15939.203039847962;7058.75294364705;7100.715971361088;7117.181524124792;7180.8922866157045;7209.315115064061;858.8110615664543;888.8089060851947;910.9954450227707;1028.783955369366;1094.1356328500556;26866.597001045;29038.27404314909;29090.98181563635;31264.687353126632;32789.508157377655;1066.1896958311584;1070.2240875365299;1078.2586123410792;1091.4815462655333;1129.6331438958787;12811.487540498276;15110.244487775788;15555;15630.609234768994;15986.840131598768;435447;436216;439362;439952;440174;56915.14627280594;57410;57421.99999999916;57459.71270143623;57606.42393576095;1131.886811318878;1344.4151793969654;1359.4222455456588;1405.8523854995078;1406.585057408067;25105.121320753686;27612.861935690195;28542;28874.855625721735;30312.242193945327;51039.234411483114;51550.226746598084;51701.22448163205;51749.22376164436;51824.70438238923;589644;591582;592620;595806;596700;26027.869860650575;26624.93500259968;26651.600225996237;26922.192334230003;27422;35526.46710299296;48560;48573.27140092831;49178.77053073702;49252.01495970132;39582;39659.801700992466;39877.80061099676;41325.79337103295;41406.79296603498;39354.60645393624;39448.40827387649;40788.79605601953;40859.79570102131;40988.1802363957;38348.80825595854;38798;38805.80597096997;38846.41730374103;39702.60297396989;30784.384312314076;34846;35198.64801351954;35288.82355588206;35736.749213777395;24098.732381704027;24854.378640533407;25490.490190196462;26010.479790404464;26713.866430667724 08:54:07 INFO - PROCESS | 1657 | |2;jquery.html;352.1969908608372;366.48508845072666;373.7570579123569;379.1923203576382;383.9961600383995;19629.411117666474;20442.89778551107;20768.16927322909;21279.936003199855;21505.784942150574;20204.393868183943;20569;20698;20739.481512962182;21225.787742122575;4943.332650092228;5604.215409842625;5821.50517206038;5856.590038697293;5906.940930590704;6008.879822403549;6167.259928808527;6176.197094377724;6181.598196117238;6188.443040126388;5692.089265717486;6045.455908968192;6096.72564734588;6138.877222455548;6157.291911430176;5913.674747888865;5971.641701497924;6085.4827339676185;6149.938500615003;6155.569110162291;4553.453585569753;4780.354652121954;5195.142801437758;5848.473637372657;5899.852503687399;10085.647002354883;11840.638326592465;13169.73660526784;13200.47198112084;13321.666958326023;2129.679598648848;2159.632862413378;2303.5623231585946;2311.1217737259904;2332.2536788227776;9803.803923921552;10170.542325595366;10431.426271555061;13096.607101786962;13904.58286251414;1755.7190849464153;1808.2766893242676;1859.1819599376367;1862.0689655172414;1957.2464601128527;112641.43679281633;124969;125747;125907;126167.10749338767;10249.538770755335;11110.388928609003;11538.480768365444;11545.307281562966;11730.12024098196;14317.35571899267;14380;14381.137131771919;14401.279936003159;14454.85545144556;8240.13478584752;8558.743237702878;8597.18326758957;8624.827503449897;8688.26149777276;8311.667533298722;8378.748637540763;8403.36974726898;8588.012378576419;8606.698765543177;4426.889327766831;4517.706349087299;4530.161920044802;4560.2019646562385;4602.677812553156;1935.8935258560914;1940.3790786948323;1964.9094752412263;1976.4367155360653;2014.949626259326;864.179029921577;905.2622112977901;939.4973689076311;945.5933948402115;998.2662742883974;16365.527102560827;19782.40652780419;20285.290014849408;20707.378778636667;21488.892555537434;857.2713193785357;859.2824991132475;926.9814603707888;954.4368822394821;971.8347880860271;8701.260392866678;9469.14777670013;10545.31455455393;11244.66266012021;11249.606263780728;475140;478316;480467;503607;587284.0635796794;121594;128327.07509387366;134604;138762;139502.60497394897;967.6468089147551;997.4264797741328;1009.258195226508;1009.6718566465928;1015.4415071710678;17298.394556190473;17969.281228751228;18642.067896605386;19566.804331956497;19590.510237243612;54315.72842135764;55613;56005;57376.71311643415;57589.71205143948;81669.18330816616;82261;82329.58835206025;82496.58751706203;83418.87306169207;7745.109312429143;7954.801129971565;8020.318272946864;8085.71699990521;8107.716229931924;9525.857112143462;12231.266124032587;12358.938205308916;12576;12609.180403274126;10688.984546468073;10799.73000674958;11163.944180279046;11201.383923884265;11298.717532061764;10637;10765.30025548337;10782.784344313226;10943.890561094606;11020.944895275472;11041.668749937515;11227.943860280646;11317.264377815416;11348.54605815758;11452.14108941816;6527.738890444329;7706.7687969361;8103.311218546254;8170.18298170202;8199.344052475668;9578.521073946136;10731.624393146203;10834.837477438003;11082.944585277344;11190.440477975906 08:54:07 INFO - PROCESS | 1657 | |3;mootools.html;1541.2062787664352;1560.6254498920257;1799.2982736732677;1865.6455273498043;1907.3324336482233;6449.774257900963;7809.60951952403;8081.878771818428;8502.277306428945;8527.317814574835;7989.36085113191;8269.338452923766;8284.378671599625;8393.664253429855;8410.91589084109;2291.4385975436044;2329.8252631052737;2330.3591512334074;2331.7668233176714;2340.1575432844247;1906.961860762784;2000.8999550022513;2047.4983629010912;2256.5035692147785;2340.4031971847157;1925.2491528303972;2291.346966114658;2310.89600967956;2325.790678838904;2361.9055237790467;1945.727598136255;2272.4091736148557;2279.088364654135;2279.452931296485;2297.6668383084525;1955.2179128348773;1976.2391479280436;2337.7662233776655;2337.789598936087;2349.706286714161;8317.209865062809;8368.456050356708;8475.660973561111;8477.23704866565;8477.982642082929;5064.493550644943;5183.352080989876;5208.791648334062;5226.216067589854;5240.764165612519;2889.9133026009044;3294.8187849668257;3461.2212252243044;3585.2650206707563;3589.0489020409614;1026.471367245869;1069.566825435692;1081.5241293830688;1081.9675409737642;1083.6748975307455;10288.74278143045;10474.895251047468;10642.148628109733;10653.78692426147;10773.407462589554;4289.635380992589;4344.652427805768;4444.555544445594;4457.665675074382;4469.9776501117285;8631.050584435707;8663.870041949376;8664.74005779815;8675.47947123162;8687.6524939003;4076.735012224196;4708.199606066977;5125.1799712045895;5129.64092513528;5131.948680513222;4301.849435269751;4419.160359531678;4675.13510000651;4766.332713420124;4834.540718631724;3562.73522899367;3636.2182130841998;3667.7799332040163;3685.1892583631557;3693.8891833245048;1140.8174692049233;1143.7140714821294;1148.6783700563856;1148.6898537395023;1155.589765633188;905.0858632780808;911.6080085563138;939.5208443693765;940.3229674634252;950;5490.203920431515;5667.914981275284;5759.1937128802;5866.472017518358;5931.40685931412;803.1847674610275;891.6344298837446;957.9281553883486;971.1259866120407;984.1683777208248;3794.506714127201;3805.1818412073944;3851.6533511984057;3983.6215559521797;4016.5983401660183;9738.367006144577;10006.049425304584;10101.646442374482;10119.797604048024;10184.949075254577;9758.17055550272;10052.748681283027;10097.343672661253;10102.242331825142;10112.191024718006;7580.658870350848;7929.801754956057;7952.562609056557;7957.681692732226;7967.322777563972;5543.36251331086;5613.24221230145;5711.171880077449;5735.4551317624755;5800.187973683604;9385.249180065442;9396.95301523488;9429.858552121861;9429.905700942903;9439.764005899908;9240.722778316662;9340.673076442292;9475.384100033476;9501.097395747393;9551.140397363992;6664.83337916556;6733.3939945405145;6733.595984240961;6755.392014718699;6796.76211332601;7759.340456061298;7837.49056311338;7855.60721963888;7861.095973962839;7861.685532578862;6966.268541803241;7175.748848790476;7198.5680859148615;7223.855522889407;7324.267573242633;5883.882322353615;6856.24581296047;6990.2660220678135;7004.404625606677;7185.317394847481;6603.966980165068;6633.701483433354;6713.328667133248;6720.395164435225;6747.224069232101;4832.830850920201;4901.730404827768;4903.289023091703;4905.3623029006;4950.133726597903;4010.318245898146;4426.358178064227;4892.412910450626;4982.4768399317545;5013.521011301747 08:54:07 INFO - PROCESS | 1657 | |4;prototype.html;271.42187141388837;299.7347347597377;308.62964442668795;314.98740050397987;314.98740050397987;4984.526469985355;5334.546563542094;5581.972090139554;5639.772289945474;5659.830205093844;4542.704724192925;4889.946222586514;5136.280920671109;5314.741234975139;5548.86127846804;4234.894127646803;4245.936310955338;4263.360495925605;4265.509466411356;4277.14457108578;3327.434336162858;4162.958370416302;4260.70175087744;4270.37018534245;4280.72175308604;3422.8533441297195;3575.803330816804;4150.83396664133;4159.10760603926;4171.186618609372;4128.360104183841;4160.9375859362135;4170.645495132917;4176.037262486627;4176.352665336878;3595.26297109092;3601.729870259741;3606.6032736398975;3608.4226523756074;3614.3855544557227;4051.4733084699096;4200.600942910419;4821.421429428491;5327.496244762441;5466.890662186734;4126.339785634284;4134.875953721393;4564.470902247751;4744.359511465942;4779.976100119477;5814.592978491507;5815.98220311448;5818.41815818419;5823.67969761663;5832.445917637818;3790.6588407043223;3850.4397659887704;3867.4916782454866;3940.192260586573;3951.9012024699323;4798.808047678089;5132.384113906355;6136.9079463808075;6146.323904370515;6189.6286222826775;3609.314230296235;3864.70532371653;3974.8608798691907;3991.1219531702977;3997.8200980955926;3888.377859542516;3894.2211557688574;3994.7603143810884;3996.2407142642805;3999;3567.4470457079196;4720.7576401209835;4740.881477963078;4740.881477963078;4742.581283545348;5884.58807883444;5906.527477801766;5907.6959985605645;5925.851853703692;5928;1078.0405439159154;1129.8870612446733;1285.569334273029;1330.8536061033278;1353.4653811744306;754.9622518874035;885.8095509465496;899.7615631857562;911.2983003087718;913.8537833946536;4662.813487460464;5425.376081750649;5452.291202143696;5469.085819962981;5513.255710479114;5215.973920130451;5548.389677135512;5850.2102216200265;5903.734331955073;5985.551083668743;4560.543945605413;4726.771039529747;4868.634852386085;5467.781288748405;5472.7263636818;2252.459409741683;2634.565296726057;2644.6512278737596;2650.4831557846037;2652;4665.370175026328;5138.254953031789;5179.05785330501;5185.818496352609;5237.978594174099;3896.980515097406;4816.5068828663225;4879.438864530624;4903.6077113831525;5150.583589512914;4250.638695710837;4263.912702260904;4730.905381892343;4740;5143.5113664201845;4613.884652883705;4772.832950846704;4860.927086093782;5040.319556859924;5044.747762611782;4689.413823272091;4909.484504127158;4964.450417951038;4980.211081794207;5054.721990290569;4612;4919;5035.597152227886;5246.6065045121;5259.579233661374;3126.7811253212058;3204.599425071866;3423.1172854930187;3438.7249020078834;3476.026712520498;4391.780410979502;4406.76950127868;4601.355810186644;5025.96967621644;5041.6470847040355;4780.904381912412;4847.200211964986;4885.9022819544125;4886;4915.311856340044;4438.245498265367;4637.791299391468;4694.460137084279;4903.730294833818;4911.151532111296;4076.695457453586;4431.379606855107;4740.336352910526;4768.141734487686;4794.756640373225;4547.294764463326;4835.637327200404;4884.682495637772;4894.045661096195;4911.803527858846;4023.4769479967417;4117.670586353144;4117.958820411757;4754.028732042473;4817.518248175154 08:54:07 INFO - PROCESS | 1657 | |5;yui.html;680.6222546486698;743.7508434674384;745.9962700186499;750.272235931147;780.812604974806;14721.337539810707;15529.53411397657;15548.533543993699;15560.61098472536;15573.922130389346;11622;14270.001099923009;14367.066140700847;14414.639634009154;14424.639384015405;692.8545005548841;715.2061212054615;865.0398058155442;886.9645214191457;905.4929239625831;829.241244261503;905.8097799462121;906;907.3512438606396;909.7043460875209;758.6093162021564;864.7158968931133;902.6705252582825;955.044955044955;961.1157734883924;753.9396848252127;921.9585118669676;957.1863915671659;957.3442192098365;958.8513780364054;693.4591019005184;707.9256678048833;709.6806437103272;710.083991650775;710.6695386645183;11707.824382634268;14148.858511414857;14290.499832505813;14553;15218.086914785148;13991.86008139926;14030.368633411419;14287.499937502242;14381.640458988504;14507.419703211859;11377.317360958286;11569.942150289195;11600.88399116015;11768.588099416565;11912.485816684099;3763.924721505582;3779.943300850462;3786.4698942147847;3811.4854494643146;3813;75886;90327.54836225777;91382.54308728481;94566.0543394571;94913.05086949111;10786.514606842711;11011.064059554867;11031.503582338813;11073.778524429466;11149.609763658233;19714.901425492782;22039.779602204093;22156.88921555382;22160.778392216194;22217.222397216345;7908.248716371935;7938.801529961682;7960.721374751972;8115.79710507242;8132.349412047022;4304.48346198452;4347.304431290978;4349;4350.521442641307;4351.6518678505645;3406.8807591734667;3484.181217413906;3496.091016335771;3500.7199424046016;3550.591681956608;606.3845197124922;658.4633523678187;660.2209392916404;687.1033301541536;688.9724411023603;615.2770494668836;645.6739346629922;649.1852724830308;668.2548957911894;678.1421501800227;8522.70170544028;9239.815203695889;9359.15767580921;9439.90560094404;9445.386049906734;640.1294239833823;647.8901273885332;655.2845366770423;661.490652197805;678.267471131187;4385.276429389115;4521.163584736834;4542.545745425497;4560.88597785058;4562.931556026662;110964;115898.42050789861;117209;121052;122200.38899805443;89272.5536372327;100397;104033.47983260035;104203.4789826046;104739;58324.12513812296;92309;92516.7224080268;96136;97004.51497742467;9678.467684277432;9865.408075515492;9926.503674816375;9999.450030248116;10021.248406369697;52555.47444525506;53653.731731342654;54678.726606368306;55138.72430637821;55320;58986.705066474395;76187.47625047578;76224.37400333029;77988.8301675464;78495;12539.93730031344;12683.048771342003;12690.492380304684;12735.872641273469;12855.742885142432;14233.256522709018;14315.498957536436;14645.707085858436;14768.556943291718;14888;9820.705378838646;10474.633387831389;11877.52489900429;13106.410211540695;13270.938324934175;10307.742306442398;10725.678229653124;12895.079548211534;13396.665083372995;13738.725225495235;10553.050225479743;12937.85788554623;13237.946255093948;13614.523491677744;13622.523211687543;8364.832703346021;10219.693409197736;10263.768347798312;10971.067459266053;11398.373089480157;10570.2072344576;10644.574217031231;10830.891691082988;11615.477303521193;11617.244879082833 08:54:07 INFO - PROCESS | 1657 | __end_tp_report 08:54:07 INFO - PROCESS | 1657 | __start_cc_report 08:54:07 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,361 08:54:07 INFO - PROCESS | 1657 | __end_cc_report 08:54:07 INFO - PROCESS | 1657 | __startTimestamp1477756447980__endTimestamp 08:54:07 INFO - PROCESS | 1657 | 08:54:07 INFO - PROCESS | 1657 | ------- Summary: start ------- 08:54:07 INFO - PROCESS | 1657 | Number of tests: 6 08:54:07 INFO - PROCESS | 1657 | 08:54:07 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5551.95 Median:5700.34 stddev:690.98 (12.1%) stddev-sans-first:657.99 08:54:07 INFO - PROCESS | 1657 | Values: 2779.9 3272.8 3353.2 3389.3 3396.0 5461.9 5528.9 5552.7 5570.1 5636.9 4531.2 4924.4 5503.0 5507.0 5566.2 5078.8 5095.6 5184.2 5229.7 5230.9 5055.3 5205.6 5205.9 5220.5 5230.0 4190.8 4992.4 5047.6 5071.8 5187.7 5114.8 5120.3 5122.9 5133.1 5134.1 4991.4 5053.3 5067.8 5068.0 5099.6 5465.3 5538.7 5552.7 5579.1 5583.3 5351.2 6044.7 6073.7 6121.4 6151.0 6153.2 6196.4 6210.2 6213.7 6228.6 5570.9 5640.9 5700.3 5709.3 5723.4 6209.8 6264.7 6284.2 6294.4 6316.4 5985.3 6064.3 6064.6 6086.2 6111.2 4547.3 6000.6 6017.5 6195.7 6211.3 6069.2 6086.0 6092.4 6121.6 6125.5 6046.8 6108.3 6109.9 6110.1 6140.5 4744.0 4834.3 4845.1 4856.0 4858.2 3621.8 4274.9 4320.0 4327.5 4431.1 5317.0 5406.5 5684.0 5709.9 5716.6 6178.7 6186.8 6232.1 6233.5 6244.7 5578.4 5587.6 5642.9 5660.3 5710.5 5311.8 5327.4 5351.2 5352.8 5394.4 5199.9 6231.4 6261.1 6284.2 6298.0 5773.5 5848.8 5959.1 6229.6 6232.9 6225.1 6275.7 6282.9 6310.0 6310.9 4955.6 5930.1 6042.6 6090.0 6118.5 6092.1 6102.5 6142.9 6146.7 6202.8 4745.6 5653.2 5927.0 6059.1 6105.5 4185.6 4674.9 5879.3 5992.8 5998.3 5658.9 5659.9 5813.7 5821.6 5827.7 4963.7 5598.8 5807.7 5898.7 5983.9 4547.5 5736.9 5772.9 5813.5 5880.1 08:54:07 INFO - PROCESS | 1657 | 08:54:07 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:64519.49 Median:41475.09 stddev:134595.25 (324.5%) stddev-sans-first:134886.90 08:54:07 INFO - PROCESS | 1657 | Values: 463.0 526.9 531.8 539.2 540.6 40410.0 41875.8 41936.8 41990.8 42140.7 43193.0 43312.4 43705.0 43785.0 43863.3 9255.4 9591.3 9756.4 9767.1 9784.7 9622.0 9711.0 9723.8 9748.1 9777.6 8238.7 9693.1 9747.7 9759.0 9765.7 9665.7 9723.1 9749.4 9762.8 9768.7 8036.9 9464.7 9549.8 9585.6 9586.2 41019.6 41423.4 41526.8 41542.0 41648.0 3410.5 3634.6 3852.8 3882.7 4114.5 27304.5 30634.2 31066.0 31458.4 31499.2 3190.8 3277.8 3310.8 3312.8 3362.8 468220.7 471008.0 471701.0 473207.0 474583.0 56842.0 57120.0 57384.0 57396.7 57699.4 60873.4 61046.7 61084.4 61395.0 61585.7 15858.9 16096.2 16110.7 16121.4 16126.9 11989.6 12144.0 12380.1 12461.5 12462.4 14345.6 15729.8 15738.6 15875.1 15939.2 7058.8 7100.7 7117.2 7180.9 7209.3 858.8 888.8 911.0 1028.8 1094.1 26866.6 29038.3 29091.0 31264.7 32789.5 1066.2 1070.2 1078.3 1091.5 1129.6 12811.5 15110.2 15555.0 15630.6 15986.8 435447.0 436216.0 439362.0 439952.0 440174.0 56915.1 57410.0 57422.0 57459.7 57606.4 1131.9 1344.4 1359.4 1405.9 1406.6 25105.1 27612.9 28542.0 28874.9 30312.2 51039.2 51550.2 51701.2 51749.2 51824.7 589644.0 591582.0 592620.0 595806.0 596700.0 26027.9 26624.9 26651.6 26922.2 27422.0 35526.5 48560.0 48573.3 49178.8 49252.0 39582.0 39659.8 39877.8 41325.8 41406.8 39354.6 39448.4 40788.8 40859.8 40988.2 38348.8 38798.0 38805.8 38846.4 39702.6 30784.4 34846.0 35198.6 35288.8 35736.7 24098.7 24854.4 25490.5 26010.5 26713.9 08:54:07 INFO - PROCESS | 1657 | 08:54:07 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:32498.93 Median:2307.34 stddev:86025.15 (3728.3%) stddev-sans-first:86232.61 08:54:08 INFO - PROCESS | 1657 | Values: 352.2 366.5 373.8 379.2 384.0 19629.4 20442.9 20768.2 21279.9 21505.8 20204.4 20569.0 20698.0 20739.5 21225.8 4943.3 5604.2 5821.5 5856.6 5906.9 6008.9 6167.3 6176.2 6181.6 6188.4 5692.1 6045.5 6096.7 6138.9 6157.3 5913.7 5971.6 6085.5 6149.9 6155.6 4553.5 4780.4 5195.1 5848.5 5899.9 10085.6 11840.6 13169.7 13200.5 13321.7 2129.7 2159.6 2303.6 2311.1 2332.3 9803.8 10170.5 10431.4 13096.6 13904.6 1755.7 1808.3 1859.2 1862.1 1957.2 112641.4 124969.0 125747.0 125907.0 126167.1 10249.5 11110.4 11538.5 11545.3 11730.1 14317.4 14380.0 14381.1 14401.3 14454.9 8240.1 8558.7 8597.2 8624.8 8688.3 8311.7 8378.7 8403.4 8588.0 8606.7 4426.9 4517.7 4530.2 4560.2 4602.7 1935.9 1940.4 1964.9 1976.4 2014.9 864.2 905.3 939.5 945.6 998.3 16365.5 19782.4 20285.3 20707.4 21488.9 857.3 859.3 927.0 954.4 971.8 8701.3 9469.1 10545.3 11244.7 11249.6 475140.0 478316.0 480467.0 503607.0 587284.1 121594.0 128327.1 134604.0 138762.0 139502.6 967.6 997.4 1009.3 1009.7 1015.4 17298.4 17969.3 18642.1 19566.8 19590.5 54315.7 55613.0 56005.0 57376.7 57589.7 81669.2 82261.0 82329.6 82496.6 83418.9 7745.1 7954.8 8020.3 8085.7 8107.7 9525.9 12231.3 12358.9 12576.0 12609.2 10689.0 10799.7 11163.9 11201.4 11298.7 10637.0 10765.3 10782.8 10943.9 11020.9 11041.7 11227.9 11317.3 11348.5 11452.1 6527.7 7706.8 8103.3 8170.2 8199.3 9578.5 10731.6 10834.8 11082.9 11190.4 08:54:08 INFO - PROCESS | 1657 | 08:54:08 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5377.30 Median:5094.84 stddev:2950.21 (57.9%) stddev-sans-first:2944.40 08:54:08 INFO - PROCESS | 1657 | Values: 1541.2 1560.6 1799.3 1865.6 1907.3 6449.8 7809.6 8081.9 8502.3 8527.3 7989.4 8269.3 8284.4 8393.7 8410.9 2291.4 2329.8 2330.4 2331.8 2340.2 1907.0 2000.9 2047.5 2256.5 2340.4 1925.2 2291.3 2310.9 2325.8 2361.9 1945.7 2272.4 2279.1 2279.5 2297.7 1955.2 1976.2 2337.8 2337.8 2349.7 8317.2 8368.5 8475.7 8477.2 8478.0 5064.5 5183.4 5208.8 5226.2 5240.8 2889.9 3294.8 3461.2 3585.3 3589.0 1026.5 1069.6 1081.5 1082.0 1083.7 10288.7 10474.9 10642.1 10653.8 10773.4 4289.6 4344.7 4444.6 4457.7 4470.0 8631.1 8663.9 8664.7 8675.5 8687.7 4076.7 4708.2 5125.2 5129.6 5131.9 4301.8 4419.2 4675.1 4766.3 4834.5 3562.7 3636.2 3667.8 3685.2 3693.9 1140.8 1143.7 1148.7 1148.7 1155.6 905.1 911.6 939.5 940.3 950.0 5490.2 5667.9 5759.2 5866.5 5931.4 803.2 891.6 957.9 971.1 984.2 3794.5 3805.2 3851.7 3983.6 4016.6 9738.4 10006.0 10101.6 10119.8 10184.9 9758.2 10052.7 10097.3 10102.2 10112.2 7580.7 7929.8 7952.6 7957.7 7967.3 5543.4 5613.2 5711.2 5735.5 5800.2 9385.2 9397.0 9429.9 9429.9 9439.8 9240.7 9340.7 9475.4 9501.1 9551.1 6664.8 6733.4 6733.6 6755.4 6796.8 7759.3 7837.5 7855.6 7861.1 7861.7 6966.3 7175.7 7198.6 7223.9 7324.3 5883.9 6856.2 6990.3 7004.4 7185.3 6604.0 6633.7 6713.3 6720.4 6747.2 4832.8 4901.7 4903.3 4905.4 4950.1 4010.3 4426.4 4892.4 4982.5 5013.5 08:54:08 INFO - PROCESS | 1657 | 08:54:08 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4304.97 Median:4735.45 stddev:1299.57 (27.4%) stddev-sans-first:1267.47 08:54:08 INFO - PROCESS | 1657 | Values: 271.4 299.7 308.6 315.0 315.0 4984.5 5334.5 5582.0 5639.8 5659.8 4542.7 4889.9 5136.3 5314.7 5548.9 4234.9 4245.9 4263.4 4265.5 4277.1 3327.4 4163.0 4260.7 4270.4 4280.7 3422.9 3575.8 4150.8 4159.1 4171.2 4128.4 4160.9 4170.6 4176.0 4176.4 3595.3 3601.7 3606.6 3608.4 3614.4 4051.5 4200.6 4821.4 5327.5 5466.9 4126.3 4134.9 4564.5 4744.4 4780.0 5814.6 5816.0 5818.4 5823.7 5832.4 3790.7 3850.4 3867.5 3940.2 3951.9 4798.8 5132.4 6136.9 6146.3 6189.6 3609.3 3864.7 3974.9 3991.1 3997.8 3888.4 3894.2 3994.8 3996.2 3999.0 3567.4 4720.8 4740.9 4740.9 4742.6 5884.6 5906.5 5907.7 5925.9 5928.0 1078.0 1129.9 1285.6 1330.9 1353.5 755.0 885.8 899.8 911.3 913.9 4662.8 5425.4 5452.3 5469.1 5513.3 5216.0 5548.4 5850.2 5903.7 5985.6 4560.5 4726.8 4868.6 5467.8 5472.7 2252.5 2634.6 2644.7 2650.5 2652.0 4665.4 5138.3 5179.1 5185.8 5238.0 3897.0 4816.5 4879.4 4903.6 5150.6 4250.6 4263.9 4730.9 4740.0 5143.5 4613.9 4772.8 4860.9 5040.3 5044.7 4689.4 4909.5 4964.5 4980.2 5054.7 4612.0 4919.0 5035.6 5246.6 5259.6 3126.8 3204.6 3423.1 3438.7 3476.0 4391.8 4406.8 4601.4 5026.0 5041.6 4780.9 4847.2 4885.9 4886.0 4915.3 4438.2 4637.8 4694.5 4903.7 4911.2 4076.7 4431.4 4740.3 4768.1 4794.8 4547.3 4835.6 4884.7 4894.0 4911.8 4023.5 4117.7 4118.0 4754.0 4817.5 08:54:08 INFO - PROCESS | 1657 | 08:54:08 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:21102.94 Median:4349.76 stddev:31328.98 (720.2%) stddev-sans-first:31379.34 08:54:08 INFO - PROCESS | 1657 | Values: 680.6 743.8 746.0 750.3 780.8 14721.3 15529.5 15548.5 15560.6 15573.9 11622.0 14270.0 14367.1 14414.6 14424.6 692.9 715.2 865.0 887.0 905.5 829.2 905.8 906.0 907.4 909.7 758.6 864.7 902.7 955.0 961.1 753.9 922.0 957.2 957.3 958.9 693.5 707.9 709.7 710.1 710.7 11707.8 14148.9 14290.5 14553.0 15218.1 13991.9 14030.4 14287.5 14381.6 14507.4 11377.3 11569.9 11600.9 11768.6 11912.5 3763.9 3779.9 3786.5 3811.5 3813.0 75886.0 90327.5 91382.5 94566.1 94913.1 10786.5 11011.1 11031.5 11073.8 11149.6 19714.9 22039.8 22156.9 22160.8 22217.2 7908.2 7938.8 7960.7 8115.8 8132.3 4304.5 4347.3 4349.0 4350.5 4351.7 3406.9 3484.2 3496.1 3500.7 3550.6 606.4 658.5 660.2 687.1 689.0 615.3 645.7 649.2 668.3 678.1 8522.7 9239.8 9359.2 9439.9 9445.4 640.1 647.9 655.3 661.5 678.3 4385.3 4521.2 4542.5 4560.9 4562.9 110964.0 115898.4 117209.0 121052.0 122200.4 89272.6 100397.0 104033.5 104203.5 104739.0 58324.1 92309.0 92516.7 96136.0 97004.5 9678.5 9865.4 9926.5 9999.5 10021.2 52555.5 53653.7 54678.7 55138.7 55320.0 58986.7 76187.5 76224.4 77988.8 78495.0 12539.9 12683.0 12690.5 12735.9 12855.7 14233.3 14315.5 14645.7 14768.6 14888.0 9820.7 10474.6 11877.5 13106.4 13270.9 10307.7 10725.7 12895.1 13396.7 13738.7 10553.1 12937.9 13237.9 13614.5 13622.5 8364.8 10219.7 10263.8 10971.1 11398.4 10570.2 10644.6 10830.9 11615.5 11617.2 08:54:08 INFO - PROCESS | 1657 | -------- Summary: end -------- 08:54:08 INFO - PROCESS | 1657 | 08:54:08 INFO - TEST-INFO | 1657: exit 0 08:54:08 INFO - TEST-OK | dromaeo_css | took 1116594ms 08:54:08 INFO - TEST-START | kraken 08:54:08 INFO - operating with platform_type : mac_ 08:54:08 INFO - Initialising browser for kraken test... 08:54:08 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpFaJ1if/profile http://localhost:49271/getInfo.html) 08:54:11 INFO - PROCESS | 1666 | 2016-10-29 08:54:11.506 plugin-container[1667:9888] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 08:54:11 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:54:11 INFO - PROCESS | 1666 | 2016-10-29 08:54:11.509 plugin-container[1667:9888] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434f, name = 'com.apple.CFPasteboardClient' 08:54:11 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:54:11 INFO - PROCESS | 1666 | 2016-10-29 08:54:11.509 plugin-container[1667:9888] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:54:11 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 08:54:11 INFO - PROCESS | 1666 | colorDepth:24 08:54:11 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 08:54:11 INFO - PROCESS | 1666 | __metrics 08:54:11 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3131: TypeError: this.tabs is undefined 08:54:12 INFO - TEST-INFO | 1666: exit 0 08:54:12 INFO - Browser initialized. 08:54:12 INFO - Running cycle 1/1 for kraken test... 08:54:12 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpFaJ1if/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:54:14 INFO - PROCESS | 1668 | 2016-10-29 08:54:14.797 plugin-container[1669:10003] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 08:54:14 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:54:14 INFO - PROCESS | 1668 | 2016-10-29 08:54:14.798 plugin-container[1669:10003] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 08:54:14 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:54:14 INFO - PROCESS | 1668 | 2016-10-29 08:54:14.798 plugin-container[1669:10003] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:54:24 INFO - PROCESS | 1668 | RSS: Main: 238198784 08:54:24 INFO - PROCESS | 1668 | 08:54:26 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 08:54:27 INFO - PROCESS | 1668 | RSS: Main: 250859520 08:54:27 INFO - PROCESS | 1668 | 08:54:30 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 08:54:31 INFO - PROCESS | 1668 | RSS: Main: 258334720 08:54:31 INFO - PROCESS | 1668 | 08:54:34 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 08:54:35 INFO - PROCESS | 1668 | RSS: Main: 261033984 08:54:35 INFO - PROCESS | 1668 | 08:54:37 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 08:54:37 INFO - PROCESS | 1668 | RSS: Main: 262430720 08:54:37 INFO - PROCESS | 1668 | 08:54:39 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:54:40 INFO - PROCESS | 1668 | RSS: Main: 263892992 08:54:40 INFO - PROCESS | 1668 | 08:54:43 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 08:54:43 INFO - PROCESS | 1668 | RSS: Main: 264085504 08:54:43 INFO - PROCESS | 1668 | 08:54:46 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 08:54:47 INFO - PROCESS | 1668 | RSS: Main: 263942144 08:54:47 INFO - PROCESS | 1668 | 08:54:50 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 08:54:51 INFO - PROCESS | 1668 | RSS: Main: 264556544 08:54:51 INFO - PROCESS | 1668 | 08:54:52 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:54:53 INFO - PROCESS | 1668 | RSS: Main: 264384512 08:54:53 INFO - PROCESS | 1668 | 08:54:55 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:54:56 INFO - PROCESS | 1668 | RSS: Main: 264376320 08:54:56 INFO - PROCESS | 1668 | 08:54:58 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:54:58 INFO - PROCESS | 1668 | RSS: Main: 264228864 08:54:58 INFO - PROCESS | 1668 | 08:55:02 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:55:02 INFO - PROCESS | 1668 | RSS: Main: 264511488 08:55:02 INFO - PROCESS | 1668 | 08:55:05 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:55:06 INFO - PROCESS | 1668 | RSS: Main: 264806400 08:55:06 INFO - PROCESS | 1668 | 08:55:08 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 08:55:08 INFO - PROCESS | 1668 | RSS: Main: 266113024 08:55:08 INFO - PROCESS | 1668 | 08:55:08 INFO - PROCESS | 1668 | __start_tp_report 08:55:08 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 08:55:08 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 08:55:08 INFO - PROCESS | 1668 | |i|pagename|runs| 08:55:08 INFO - PROCESS | 1668 | |0;ai-astar;113;118;118;117;119;117;118;117;119;118 08:55:08 INFO - PROCESS | 1668 | |1;audio-beat-detection;147;121;119;156;115;119;117;116;148;116 08:55:08 INFO - PROCESS | 1668 | |2;audio-dft;197;197;197;232;281;233;233;221;197;198 08:55:08 INFO - PROCESS | 1668 | |3;audio-fft;82;62;78;77;69;84;85;82;75;62 08:55:08 INFO - PROCESS | 1668 | |4;audio-oscillator;84;71;87;72;70;70;89;70;72;87 08:55:08 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;89;88;89;87;128;99;89;87;90;86 08:55:08 INFO - PROCESS | 1668 | |6;imaging-darkroom;114;91;152;98;95;96;152;96;96;95 08:55:08 INFO - PROCESS | 1668 | |7;imaging-desaturate;114;115;86;94;90;89;90;100;108;135 08:55:08 INFO - PROCESS | 1668 | |8;json-parse-financial;59;58;58;67;82;91;51;69;75;51 08:55:08 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;66;92;67;87;77;73;65;69;71;71 08:55:08 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;88;91;93;86;92;80;106;104;112;110 08:55:08 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;152;158;160;157;136;162;163;160;246;152 08:55:08 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;201;185;244;197;173;176;202;180;174;175 08:55:08 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;70;72;69;68;71;70;67;69;88;91 08:55:08 INFO - PROCESS | 1668 | __end_tp_report 08:55:08 INFO - PROCESS | 1668 | __start_cc_report 08:55:08 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,956 08:55:08 INFO - PROCESS | 1668 | __end_cc_report 08:55:08 INFO - PROCESS | 1668 | __startTimestamp1477756508547__endTimestamp 08:55:08 INFO - PROCESS | 1668 | 08:55:08 INFO - PROCESS | 1668 | ------- Summary: start ------- 08:55:08 INFO - PROCESS | 1668 | Number of tests: 14 08:55:08 INFO - PROCESS | 1668 | 08:55:08 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:117.40 Median:118.00 stddev:1.71 (1.5%) stddev-sans-first:0.78 08:55:08 INFO - PROCESS | 1668 | Values: 113.0 118.0 118.0 117.0 119.0 117.0 118.0 117.0 119.0 118.0 08:55:08 INFO - PROCESS | 1668 | 08:55:08 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:127.40 Median:120.00 stddev:16.09 (13.4%) stddev-sans-first:15.43 08:55:08 INFO - PROCESS | 1668 | Values: 147.0 121.0 119.0 156.0 115.0 119.0 117.0 116.0 148.0 116.0 08:55:08 INFO - PROCESS | 1668 | 08:55:08 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:218.60 Median:226.50 stddev:27.46 (12.1%) stddev-sans-first:27.99 08:55:08 INFO - PROCESS | 1668 | Values: 197.0 197.0 197.0 232.0 281.0 233.0 233.0 221.0 197.0 198.0 08:55:08 INFO - PROCESS | 1668 | 08:55:08 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:75.60 Median:80.00 stddev:8.58 (10.7%) stddev-sans-first:8.78 08:55:08 INFO - PROCESS | 1668 | Values: 82.0 62.0 78.0 77.0 69.0 84.0 85.0 82.0 75.0 62.0 08:55:08 INFO - PROCESS | 1668 | 08:55:08 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:77.20 Median:78.00 stddev:8.34 (10.7%) stddev-sans-first:8.47 08:55:08 INFO - PROCESS | 1668 | Values: 84.0 71.0 87.0 72.0 70.0 70.0 89.0 70.0 72.0 87.0 08:55:08 INFO - PROCESS | 1668 | 08:55:08 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:93.20 Median:89.00 stddev:12.75 (14.3%) stddev-sans-first:13.44 08:55:08 INFO - PROCESS | 1668 | Values: 89.0 88.0 89.0 87.0 128.0 99.0 89.0 87.0 90.0 86.0 08:55:08 INFO - PROCESS | 1668 | 08:55:08 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:108.50 Median:95.50 stddev:23.72 (24.8%) stddev-sans-first:25.08 08:55:08 INFO - PROCESS | 1668 | Values: 114.0 91.0 152.0 98.0 95.0 96.0 152.0 96.0 96.0 95.0 08:55:08 INFO - PROCESS | 1668 | 08:55:08 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:102.10 Median:87.50 stddev:15.70 (17.9%) stddev-sans-first:16.05 08:55:08 INFO - PROCESS | 1668 | Values: 114.0 115.0 86.0 94.0 90.0 89.0 90.0 100.0 108.0 135.0 08:55:08 INFO - PROCESS | 1668 | 08:55:08 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:66.10 Median:68.00 stddev:13.33 (19.6%) stddev-sans-first:13.89 08:55:08 INFO - PROCESS | 1668 | Values: 59.0 58.0 58.0 67.0 82.0 91.0 51.0 69.0 75.0 51.0 08:55:08 INFO - PROCESS | 1668 | 08:55:08 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:73.80 Median:72.00 stddev:9.07 (12.6%) stddev-sans-first:9.17 08:55:08 INFO - PROCESS | 1668 | Values: 66.0 92.0 67.0 87.0 77.0 73.0 65.0 69.0 71.0 71.0 08:55:08 INFO - PROCESS | 1668 | 08:55:08 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:96.20 Median:87.00 stddev:10.98 (12.6%) stddev-sans-first:11.24 08:55:08 INFO - PROCESS | 1668 | Values: 88.0 91.0 93.0 86.0 92.0 80.0 106.0 104.0 112.0 110.0 08:55:08 INFO - PROCESS | 1668 | 08:55:08 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:164.60 Median:160.00 stddev:29.65 (18.5%) stddev-sans-first:31.10 08:55:08 INFO - PROCESS | 1668 | Values: 152.0 158.0 160.0 157.0 136.0 162.0 163.0 160.0 246.0 152.0 08:55:08 INFO - PROCESS | 1668 | 08:55:08 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.70 Median:191.00 stddev:21.90 (11.5%) stddev-sans-first:22.91 08:55:08 INFO - PROCESS | 1668 | Values: 201.0 185.0 244.0 197.0 173.0 176.0 202.0 180.0 174.0 175.0 08:55:08 INFO - PROCESS | 1668 | 08:55:08 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.50 Median:70.50 stddev:8.58 (12.2%) stddev-sans-first:9.01 08:55:08 INFO - PROCESS | 1668 | Values: 70.0 72.0 69.0 68.0 71.0 70.0 67.0 69.0 88.0 91.0 08:55:08 INFO - PROCESS | 1668 | -------- Summary: end -------- 08:55:08 INFO - PROCESS | 1668 | 08:55:09 INFO - TEST-INFO | 1668: exit 0 08:55:09 INFO - TEST-OK | kraken | took 60720ms 08:55:09 INFO - SUITE-END | took 1177s 08:55:09 INFO - Completed test suite (00:19:37) 08:55:09 INFO - javascript benchmark 08:55:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7104.080721274933, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2779.9305017374563, 3272.7872688275274, 3353.195233144046, 3389.322135572886, 3395.966040339596, 5461.918071228924, 5528.949499595079, 5552.7223638818095, 5570.080936645454, 5636.943630563704, 4531.229690952538, 4924.433690125627, 5503.036968530511, 5507.03626865299, 5566.248556444879, 5078.847634570969, 5095.643304968654, 5184.248283998818, 5229.660072095302, 5230.895382092356, 5055.292259083732, 5205.583553315744, 5205.869853253661, 5220.530152286294, 5229.980153869997, 4190.832366705328, 4992.40091189056, 5047.621428392885, 5071.822486213002, 5187.714675692835, 5114.795408183668, 5120.257562653432, 5122.92315615266, 5133.101707201225, 5134.05020071288, 4991.401031876162, 5053.317802096743, 5067.822626208102, 5068.0, 5099.59203263738, 5465.344158700981, 5538.695371754552, 5552.666839989613, 5579.107342825128, 5583.3300003999375, 5351.170568561879, 6044.727987240584, 6073.726682299307, 6121.449069583714, 6151.015837466029, 6153.17434324788, 6196.442320191204, 6210.223722034746, 6213.689315534205, 6228.626282423069, 5570.944290557083, 5640.858978525488, 5700.344460387026, 5709.286339207599, 5723.399043100498, 6209.751609935643, 6264.68676566179, 6284.151639528696, 6294.402031806971, 6316.368363163647, 5985.341612422717, 6064.332923378425, 6064.575479716377, 6086.239220097488, 6111.236095488064, 4547.295169248771, 6000.609960352563, 6017.518598512109, 6195.721192546346, 6211.3478084801345, 6069.180660610761, 6086.02623580225, 6092.39076092396, 6121.6054024712375, 6125.540694081788, 6046.848828779228, 6108.311716707923, 6109.860177052684, 6110.133516259496, 6140.539459540552, 4743.976280118646, 4834.299026641188, 4845.1278769821065, 4856.0, 4858.198397264482, 3621.8189090545893, 4274.871753847389, 4320.0, 4327.5239723630375, 4431.069475410135, 5316.973415132977, 5406.486383793534, 5683.971580142155, 5709.914351284734, 5716.628419152742, 6178.7219575119225, 6186.752529898844, 6232.127502149703, 6233.501319894398, 6244.687765611701, 5578.442155784389, 5587.608867379245, 5642.85892852682, 5660.320761508563, 5710.486056254957, 5311.787528498894, 5327.413984461706, 5351.170568561879, 5352.759125839347, 5394.406615272317, 5199.896002079861, 6231.4391704745885, 6261.092141639437, 6284.183056202664, 6297.968510157511, 5773.451522105393, 5848.766049357978, 5959.0763431668165, 6229.56393052482, 6232.875342493216, 6225.097360882737, 6275.686215689106, 6282.874342513215, 6310.0, 6310.936890631218, 4955.603551715926, 5930.11048342752, 6042.607230530001, 6090.025595904634, 6118.510519158545, 6092.055731361646, 6102.458955353496, 6142.9078563820685, 6146.723397447036, 6202.751889924533, 4745.596624286836, 5653.152027195789, 5926.970365148147, 6059.098349644519, 6105.542084343603, 4185.644220241192, 4674.883127921829, 5879.265091863517, 5992.760289588367, 5998.310194327533, 5658.943410565842, 5659.943400565941, 5813.709314534341, 5821.563382746395, 5827.70861456917, 4963.684623574683, 5598.832035038792, 5807.680577568274, 5898.734556945034, 5983.850403739942, 4547.52251013652, 5736.856578585569, 5772.855678608068, 5813.534917206529, 5880.088586269135], "value": 5509.906997636067, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.9699069560479, 526.8524813052346, 531.750077463592, 539.2019810680193, 540.5621446628229, 40410.0, 41875.790621046894, 41936.79031604846, 41990.790046049726, 42140.73577792663, 43193.0, 43312.3503147453, 43705.0, 43785.0, 43863.3420498692, 9255.44467331959, 9591.280653950964, 9756.414615123116, 9767.120959113678, 9784.657536986208, 9622.037796220357, 9711.0, 9723.805523889518, 9748.122668959792, 9777.608895644165, 8238.670453181865, 9693.079157480064, 9747.707568772948, 9759.02409759022, 9765.707028789147, 9665.710028699115, 9723.076307750753, 9749.366291191121, 9762.755931101708, 9768.658096966608, 8036.8748375227515, 9464.668736594254, 9549.809003819953, 9585.568649410794, 9586.233101351874, 41019.58980410187, 41423.378649320286, 41526.79236603828, 41542.0, 41648.0, 3410.5225268462436, 3634.60019397866, 3852.7880966546827, 3882.7476214045996, 4114.518773241661, 27304.4982525961, 30634.24291813725, 31066.048725371053, 31458.37083258345, 31499.212519686964, 3190.7925984811145, 3277.7541684373527, 3310.7682462227654, 3312.7681062325646, 3362.7646064775477, 468220.65889670677, 471008.0, 471701.0, 473207.0, 474583.0, 56842.0, 57120.0, 57384.0, 57396.71301643465, 57699.423005770244, 60873.39126608766, 61046.69476652677, 61084.38915610787, 61395.0, 61585.69207153936, 15858.920705396398, 16096.195190240442, 16110.677786444205, 16121.435749748702, 16126.919365403099, 11989.580364687194, 12143.967762740265, 12380.071494637757, 12461.501539938483, 12462.43919023646, 14345.569632911029, 15729.764053539206, 15738.606534836492, 15875.126868022138, 15939.203039847962, 7058.75294364705, 7100.715971361088, 7117.181524124792, 7180.8922866157045, 7209.315115064061, 858.8110615664543, 888.8089060851947, 910.9954450227707, 1028.783955369366, 1094.1356328500556, 26866.597001045, 29038.27404314909, 29090.98181563635, 31264.687353126632, 32789.508157377655, 1066.1896958311584, 1070.2240875365299, 1078.2586123410792, 1091.4815462655333, 1129.6331438958787, 12811.487540498276, 15110.244487775788, 15555.0, 15630.609234768994, 15986.840131598768, 435447.0, 436216.0, 439362.0, 439952.0, 440174.0, 56915.14627280594, 57410.0, 57421.99999999916, 57459.71270143623, 57606.42393576095, 1131.886811318878, 1344.4151793969654, 1359.4222455456588, 1405.8523854995078, 1406.585057408067, 25105.121320753686, 27612.861935690195, 28542.0, 28874.855625721735, 30312.242193945327, 51039.234411483114, 51550.226746598084, 51701.22448163205, 51749.22376164436, 51824.70438238923, 589644.0, 591582.0, 592620.0, 595806.0, 596700.0, 26027.869860650575, 26624.93500259968, 26651.600225996237, 26922.192334230003, 27422.0, 35526.46710299296, 48560.0, 48573.27140092831, 49178.77053073702, 49252.01495970132, 39582.0, 39659.801700992466, 39877.80061099676, 41325.79337103295, 41406.79296603498, 39354.60645393624, 39448.40827387649, 40788.79605601953, 40859.79570102131, 40988.1802363957, 38348.80825595854, 38798.0, 38805.80597096997, 38846.41730374103, 39702.60297396989, 30784.384312314076, 34846.0, 35198.64801351954, 35288.82355588206, 35736.749213777395, 24098.732381704027, 24854.378640533407, 25490.490190196462, 26010.479790404464, 26713.866430667724], "value": 19688.190787814692, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.1969908608372, 366.48508845072666, 373.7570579123569, 379.1923203576382, 383.9961600383995, 19629.411117666474, 20442.89778551107, 20768.16927322909, 21279.936003199855, 21505.784942150574, 20204.393868183943, 20569.0, 20698.0, 20739.481512962182, 21225.787742122575, 4943.332650092228, 5604.215409842625, 5821.50517206038, 5856.590038697293, 5906.940930590704, 6008.879822403549, 6167.259928808527, 6176.197094377724, 6181.598196117238, 6188.443040126388, 5692.089265717486, 6045.455908968192, 6096.72564734588, 6138.877222455548, 6157.291911430176, 5913.674747888865, 5971.641701497924, 6085.4827339676185, 6149.938500615003, 6155.569110162291, 4553.453585569753, 4780.354652121954, 5195.142801437758, 5848.473637372657, 5899.852503687399, 10085.647002354883, 11840.638326592465, 13169.73660526784, 13200.47198112084, 13321.666958326023, 2129.679598648848, 2159.632862413378, 2303.5623231585946, 2311.1217737259904, 2332.2536788227776, 9803.803923921552, 10170.542325595366, 10431.426271555061, 13096.607101786962, 13904.58286251414, 1755.7190849464153, 1808.2766893242676, 1859.1819599376367, 1862.0689655172414, 1957.2464601128527, 112641.43679281633, 124969.0, 125747.0, 125907.0, 126167.10749338767, 10249.538770755335, 11110.388928609003, 11538.480768365444, 11545.307281562966, 11730.12024098196, 14317.35571899267, 14380.0, 14381.137131771919, 14401.279936003159, 14454.85545144556, 8240.13478584752, 8558.743237702878, 8597.18326758957, 8624.827503449897, 8688.26149777276, 8311.667533298722, 8378.748637540763, 8403.36974726898, 8588.012378576419, 8606.698765543177, 4426.889327766831, 4517.706349087299, 4530.161920044802, 4560.2019646562385, 4602.677812553156, 1935.8935258560914, 1940.3790786948323, 1964.9094752412263, 1976.4367155360653, 2014.949626259326, 864.179029921577, 905.2622112977901, 939.4973689076311, 945.5933948402115, 998.2662742883974, 16365.527102560827, 19782.40652780419, 20285.290014849408, 20707.378778636667, 21488.892555537434, 857.2713193785357, 859.2824991132475, 926.9814603707888, 954.4368822394821, 971.8347880860271, 8701.260392866678, 9469.14777670013, 10545.31455455393, 11244.66266012021, 11249.606263780728, 475140.0, 478316.0, 480467.0, 503607.0, 587284.0635796794, 121594.0, 128327.07509387366, 134604.0, 138762.0, 139502.60497394897, 967.6468089147551, 997.4264797741328, 1009.258195226508, 1009.6718566465928, 1015.4415071710678, 17298.394556190473, 17969.281228751228, 18642.067896605386, 19566.804331956497, 19590.510237243612, 54315.72842135764, 55613.0, 56005.0, 57376.71311643415, 57589.71205143948, 81669.18330816616, 82261.0, 82329.58835206025, 82496.58751706203, 83418.87306169207, 7745.109312429143, 7954.801129971565, 8020.318272946864, 8085.71699990521, 8107.716229931924, 9525.857112143462, 12231.266124032587, 12358.938205308916, 12576.0, 12609.180403274126, 10688.984546468073, 10799.73000674958, 11163.944180279046, 11201.383923884265, 11298.717532061764, 10637.0, 10765.30025548337, 10782.784344313226, 10943.890561094606, 11020.944895275472, 11041.668749937515, 11227.943860280646, 11317.264377815416, 11348.54605815758, 11452.14108941816, 6527.738890444329, 7706.7687969361, 8103.311218546254, 8170.18298170202, 8199.344052475668, 9578.521073946136, 10731.624393146203, 10834.837477438003, 11082.944585277344, 11190.440477975906], "value": 9333.546706265004, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1541.2062787664352, 1560.6254498920257, 1799.2982736732677, 1865.6455273498043, 1907.3324336482233, 6449.774257900963, 7809.60951952403, 8081.878771818428, 8502.277306428945, 8527.317814574835, 7989.36085113191, 8269.338452923766, 8284.378671599625, 8393.664253429855, 8410.91589084109, 2291.4385975436044, 2329.8252631052737, 2330.3591512334074, 2331.7668233176714, 2340.1575432844247, 1906.961860762784, 2000.8999550022513, 2047.4983629010912, 2256.5035692147785, 2340.4031971847157, 1925.2491528303972, 2291.346966114658, 2310.89600967956, 2325.790678838904, 2361.9055237790467, 1945.727598136255, 2272.4091736148557, 2279.088364654135, 2279.452931296485, 2297.6668383084525, 1955.2179128348773, 1976.2391479280436, 2337.7662233776655, 2337.789598936087, 2349.706286714161, 8317.209865062809, 8368.456050356708, 8475.660973561111, 8477.23704866565, 8477.982642082929, 5064.493550644943, 5183.352080989876, 5208.791648334062, 5226.216067589854, 5240.764165612519, 2889.9133026009044, 3294.8187849668257, 3461.2212252243044, 3585.2650206707563, 3589.0489020409614, 1026.471367245869, 1069.566825435692, 1081.5241293830688, 1081.9675409737642, 1083.6748975307455, 10288.74278143045, 10474.895251047468, 10642.148628109733, 10653.78692426147, 10773.407462589554, 4289.635380992589, 4344.652427805768, 4444.555544445594, 4457.665675074382, 4469.9776501117285, 8631.050584435707, 8663.870041949376, 8664.74005779815, 8675.47947123162, 8687.6524939003, 4076.735012224196, 4708.199606066977, 5125.1799712045895, 5129.64092513528, 5131.948680513222, 4301.849435269751, 4419.160359531678, 4675.13510000651, 4766.332713420124, 4834.540718631724, 3562.73522899367, 3636.2182130841998, 3667.7799332040163, 3685.1892583631557, 3693.8891833245048, 1140.8174692049233, 1143.7140714821294, 1148.6783700563856, 1148.6898537395023, 1155.589765633188, 905.0858632780808, 911.6080085563138, 939.5208443693765, 940.3229674634252, 950.0, 5490.203920431515, 5667.914981275284, 5759.1937128802, 5866.472017518358, 5931.40685931412, 803.1847674610275, 891.6344298837446, 957.9281553883486, 971.1259866120407, 984.1683777208248, 3794.506714127201, 3805.1818412073944, 3851.6533511984057, 3983.6215559521797, 4016.5983401660183, 9738.367006144577, 10006.049425304584, 10101.646442374482, 10119.797604048024, 10184.949075254577, 9758.17055550272, 10052.748681283027, 10097.343672661253, 10102.242331825142, 10112.191024718006, 7580.658870350848, 7929.801754956057, 7952.562609056557, 7957.681692732226, 7967.322777563972, 5543.36251331086, 5613.24221230145, 5711.171880077449, 5735.4551317624755, 5800.187973683604, 9385.249180065442, 9396.95301523488, 9429.858552121861, 9429.905700942903, 9439.764005899908, 9240.722778316662, 9340.673076442292, 9475.384100033476, 9501.097395747393, 9551.140397363992, 6664.83337916556, 6733.3939945405145, 6733.595984240961, 6755.392014718699, 6796.76211332601, 7759.340456061298, 7837.49056311338, 7855.60721963888, 7861.095973962839, 7861.685532578862, 6966.268541803241, 7175.748848790476, 7198.5680859148615, 7223.855522889407, 7324.267573242633, 5883.882322353615, 6856.24581296047, 6990.2660220678135, 7004.404625606677, 7185.317394847481, 6603.966980165068, 6633.701483433354, 6713.328667133248, 6720.395164435225, 6747.224069232101, 4832.830850920201, 4901.730404827768, 4903.289023091703, 4905.3623029006, 4950.133726597903, 4010.318245898146, 4426.358178064227, 4892.412910450626, 4982.4768399317545, 5013.521011301747], "value": 4362.057001373493, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [271.42187141388837, 299.7347347597377, 308.62964442668795, 314.98740050397987, 314.98740050397987, 4984.526469985355, 5334.546563542094, 5581.972090139554, 5639.772289945474, 5659.830205093844, 4542.704724192925, 4889.946222586514, 5136.280920671109, 5314.741234975139, 5548.86127846804, 4234.894127646803, 4245.936310955338, 4263.360495925605, 4265.509466411356, 4277.14457108578, 3327.434336162858, 4162.958370416302, 4260.70175087744, 4270.37018534245, 4280.72175308604, 3422.8533441297195, 3575.803330816804, 4150.83396664133, 4159.10760603926, 4171.186618609372, 4128.360104183841, 4160.9375859362135, 4170.645495132917, 4176.037262486627, 4176.352665336878, 3595.26297109092, 3601.729870259741, 3606.6032736398975, 3608.4226523756074, 3614.3855544557227, 4051.4733084699096, 4200.600942910419, 4821.421429428491, 5327.496244762441, 5466.890662186734, 4126.339785634284, 4134.875953721393, 4564.470902247751, 4744.359511465942, 4779.976100119477, 5814.592978491507, 5815.98220311448, 5818.41815818419, 5823.67969761663, 5832.445917637818, 3790.6588407043223, 3850.4397659887704, 3867.4916782454866, 3940.192260586573, 3951.9012024699323, 4798.808047678089, 5132.384113906355, 6136.9079463808075, 6146.323904370515, 6189.6286222826775, 3609.314230296235, 3864.70532371653, 3974.8608798691907, 3991.1219531702977, 3997.8200980955926, 3888.377859542516, 3894.2211557688574, 3994.7603143810884, 3996.2407142642805, 3999.0, 3567.4470457079196, 4720.7576401209835, 4740.881477963078, 4740.881477963078, 4742.581283545348, 5884.58807883444, 5906.527477801766, 5907.6959985605645, 5925.851853703692, 5928.0, 1078.0405439159154, 1129.8870612446733, 1285.569334273029, 1330.8536061033278, 1353.4653811744306, 754.9622518874035, 885.8095509465496, 899.7615631857562, 911.2983003087718, 913.8537833946536, 4662.813487460464, 5425.376081750649, 5452.291202143696, 5469.085819962981, 5513.255710479114, 5215.973920130451, 5548.389677135512, 5850.2102216200265, 5903.734331955073, 5985.551083668743, 4560.543945605413, 4726.771039529747, 4868.634852386085, 5467.781288748405, 5472.7263636818, 2252.459409741683, 2634.565296726057, 2644.6512278737596, 2650.4831557846037, 2652.0, 4665.370175026328, 5138.254953031789, 5179.05785330501, 5185.818496352609, 5237.978594174099, 3896.980515097406, 4816.5068828663225, 4879.438864530624, 4903.6077113831525, 5150.583589512914, 4250.638695710837, 4263.912702260904, 4730.905381892343, 4740.0, 5143.5113664201845, 4613.884652883705, 4772.832950846704, 4860.927086093782, 5040.319556859924, 5044.747762611782, 4689.413823272091, 4909.484504127158, 4964.450417951038, 4980.211081794207, 5054.721990290569, 4612.0, 4919.0, 5035.597152227886, 5246.6065045121, 5259.579233661374, 3126.7811253212058, 3204.599425071866, 3423.1172854930187, 3438.7249020078834, 3476.026712520498, 4391.780410979502, 4406.76950127868, 4601.355810186644, 5025.96967621644, 5041.6470847040355, 4780.904381912412, 4847.200211964986, 4885.9022819544125, 4886.0, 4915.311856340044, 4438.245498265367, 4637.791299391468, 4694.460137084279, 4903.730294833818, 4911.151532111296, 4076.695457453586, 4431.379606855107, 4740.336352910526, 4768.141734487686, 4794.756640373225, 4547.294764463326, 4835.637327200404, 4884.682495637772, 4894.045661096195, 4911.803527858846, 4023.4769479967417, 4117.670586353144, 4117.958820411757, 4754.028732042473, 4817.518248175154], "value": 3896.2058910973806, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [680.6222546486698, 743.7508434674384, 745.9962700186499, 750.272235931147, 780.812604974806, 14721.337539810707, 15529.53411397657, 15548.533543993699, 15560.61098472536, 15573.922130389346, 11622.0, 14270.001099923009, 14367.066140700847, 14414.639634009154, 14424.639384015405, 692.8545005548841, 715.2061212054615, 865.0398058155442, 886.9645214191457, 905.4929239625831, 829.241244261503, 905.8097799462121, 906.0, 907.3512438606396, 909.7043460875209, 758.6093162021564, 864.7158968931133, 902.6705252582825, 955.044955044955, 961.1157734883924, 753.9396848252127, 921.9585118669676, 957.1863915671659, 957.3442192098365, 958.8513780364054, 693.4591019005184, 707.9256678048833, 709.6806437103272, 710.083991650775, 710.6695386645183, 11707.824382634268, 14148.858511414857, 14290.499832505813, 14553.0, 15218.086914785148, 13991.86008139926, 14030.368633411419, 14287.499937502242, 14381.640458988504, 14507.419703211859, 11377.317360958286, 11569.942150289195, 11600.88399116015, 11768.588099416565, 11912.485816684099, 3763.924721505582, 3779.943300850462, 3786.4698942147847, 3811.4854494643146, 3813.0, 75886.0, 90327.54836225777, 91382.54308728481, 94566.0543394571, 94913.05086949111, 10786.514606842711, 11011.064059554867, 11031.503582338813, 11073.778524429466, 11149.609763658233, 19714.901425492782, 22039.779602204093, 22156.88921555382, 22160.778392216194, 22217.222397216345, 7908.248716371935, 7938.801529961682, 7960.721374751972, 8115.79710507242, 8132.349412047022, 4304.48346198452, 4347.304431290978, 4349.0, 4350.521442641307, 4351.6518678505645, 3406.8807591734667, 3484.181217413906, 3496.091016335771, 3500.7199424046016, 3550.591681956608, 606.3845197124922, 658.4633523678187, 660.2209392916404, 687.1033301541536, 688.9724411023603, 615.2770494668836, 645.6739346629922, 649.1852724830308, 668.2548957911894, 678.1421501800227, 8522.70170544028, 9239.815203695889, 9359.15767580921, 9439.90560094404, 9445.386049906734, 640.1294239833823, 647.8901273885332, 655.2845366770423, 661.490652197805, 678.267471131187, 4385.276429389115, 4521.163584736834, 4542.545745425497, 4560.88597785058, 4562.931556026662, 110964.0, 115898.42050789861, 117209.0, 121052.0, 122200.38899805443, 89272.5536372327, 100397.0, 104033.47983260035, 104203.4789826046, 104739.0, 58324.12513812296, 92309.0, 92516.7224080268, 96136.0, 97004.51497742467, 9678.467684277432, 9865.408075515492, 9926.503674816375, 9999.450030248116, 10021.248406369697, 52555.47444525506, 53653.731731342654, 54678.726606368306, 55138.72430637821, 55320.0, 58986.705066474395, 76187.47625047578, 76224.37400333029, 77988.8301675464, 78495.0, 12539.93730031344, 12683.048771342003, 12690.492380304684, 12735.872641273469, 12855.742885142432, 14233.256522709018, 14315.498957536436, 14645.707085858436, 14768.556943291718, 14888.0, 9820.705378838646, 10474.633387831389, 11877.52489900429, 13106.410211540695, 13270.938324934175, 10307.742306442398, 10725.678229653124, 12895.079548211534, 13396.665083372995, 13738.725225495235, 10553.050225479743, 12937.85788554623, 13237.946255093948, 13614.523491677744, 13622.523211687543, 8364.832703346021, 10219.693409197736, 10263.768347798312, 10971.067459266053, 11398.373089480157, 10570.2072344576, 10644.574217031231, 10830.891691082988, 11615.477303521193, 11617.244879082833], "value": 7469.0873209156125, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1584.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 118.0, 118.0, 117.0, 119.0, 117.0, 118.0, 117.0, 119.0, 118.0], "value": 117.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 121.0, 119.0, 156.0, 115.0, 119.0, 117.0, 116.0, 148.0, 116.0], "value": 127.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 197.0, 197.0, 232.0, 281.0, 233.0, 233.0, 221.0, 197.0, 198.0], "value": 218.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 62.0, 78.0, 77.0, 69.0, 84.0, 85.0, 82.0, 75.0, 62.0], "value": 75.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 87.0, 72.0, 70.0, 70.0, 89.0, 70.0, 72.0, 87.0], "value": 77.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 89.0, 87.0, 128.0, 99.0, 89.0, 87.0, 90.0, 86.0], "value": 93.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 91.0, 152.0, 98.0, 95.0, 96.0, 152.0, 96.0, 96.0, 95.0], "value": 108.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 86.0, 94.0, 90.0, 89.0, 90.0, 100.0, 108.0, 135.0], "value": 102.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 58.0, 67.0, 82.0, 91.0, 51.0, 69.0, 75.0, 51.0], "value": 66.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 92.0, 67.0, 87.0, 77.0, 73.0, 65.0, 69.0, 71.0, 71.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 91.0, 93.0, 86.0, 92.0, 80.0, 106.0, 104.0, 112.0, 110.0], "value": 96.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 158.0, 160.0, 157.0, 136.0, 162.0, 163.0, 160.0, 246.0, 152.0], "value": 164.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 185.0, 244.0, 197.0, 173.0, 176.0, 202.0, 180.0, 174.0, 175.0], "value": 190.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 69.0, 68.0, 71.0, 70.0, 67.0, 69.0, 88.0, 91.0], "value": 73.5, "unit": "score"}]}]} 08:55:09 INFO - Return code: 0 08:55:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:55:09 ERROR - # TBPL SUCCESS # 08:55:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:55:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:55:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:55:09 INFO - 2016-10-29 08:55:09.851 screenresolution[1673:10383] starting screenresolution argv=screenresolution get 08:55:09 INFO - 2016-10-29 08:55:09.853 screenresolution[1673:10383] Display 0: 1600x1200x32@60 08:55:09 INFO - 2016-10-29 08:55:09.859 screenresolution[1674:10386] starting screenresolution argv=screenresolution list 08:55:09 INFO - Available Modes on Display 0 08:55:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:55:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:55:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:55:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:55:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:55:10 INFO - Intel Iris: 08:55:10 INFO - Chipset Model: Intel Iris 08:55:10 INFO - Type: GPU 08:55:10 INFO - Bus: Built-In 08:55:10 INFO - VRAM (Dynamic, Max): 1536 MB 08:55:10 INFO - Vendor: Intel (0x8086) 08:55:10 INFO - Device ID: 0x0a2e 08:55:10 INFO - Revision ID: 0x0009 08:55:10 INFO - Displays: 08:55:10 INFO - Display: 08:55:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:55:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:55:10 INFO - Display Serial Number: 1600x1200 60 08:55:10 INFO - Main Display: Yes 08:55:10 INFO - Mirror: Off 08:55:10 INFO - Online: Yes 08:55:10 INFO - Rotation: Supported 08:55:10 INFO - Return code: 0 08:55:10 INFO - Running post-action listener: _resource_record_post_action 08:55:10 INFO - [mozharness: 2016-10-29 15:55:10.093276Z] Finished run-tests step (success) 08:55:10 INFO - Running post-run listener: _resource_record_post_run 08:55:10 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 39164416; Write bytes: 711564800; Read time: 388; Write time: 2136 08:55:10 INFO - TinderboxPrint: CPU usage
54.7% 08:55:10 INFO - TinderboxPrint: I/O read bytes / time
39,164,416 / 388 08:55:10 INFO - TinderboxPrint: I/O write bytes / time
711,564,800 / 2,136 08:55:10 INFO - TinderboxPrint: CPU idle
2,197.3 (45.3%) 08:55:10 INFO - TinderboxPrint: CPU system
987.0 (20.3%) 08:55:10 INFO - TinderboxPrint: CPU user
1,669.3 (34.4%) 08:55:10 INFO - TinderboxPrint: Swap in / out
445,853,696 / 0 08:55:10 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392909312; Write bytes: 381183488; Read time: 28914; Write time: 849 08:55:10 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 32879104; Write bytes: 302381056; Read time: 305; Write time: 1230 08:55:10 INFO - Running post-run listener: _upload_blobber_files 08:55:10 INFO - Blob upload gear active. 08:55:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:55:10 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 08:55:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:55:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:55:10 INFO - (blobuploader) - INFO - Open directory for files ... 08:55:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:55:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:55:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:55:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:55:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:55:12 INFO - (blobuploader) - INFO - Done attempting. 08:55:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:55:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:55:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:55:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:55:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:55:13 INFO - (blobuploader) - INFO - Done attempting. 08:55:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:55:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:55:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:55:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:55:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:55:16 INFO - (blobuploader) - INFO - Done attempting. 08:55:16 INFO - (blobuploader) - INFO - Iteration through files over. 08:55:16 INFO - Return code: 0 08:55:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:55:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:55:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85168025359ee1b1d6194016a9d91afb6086be900bf5bc701bb54497c6c955c8d05cde2b1a4186eef0e8db7f956db9f98b89912811f2030cebc53409f2cb323f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d492a21d9cd5b5286d0ece1c374f622f99a91259189115407fdf4edd38469feec16c05f37c101a5027f583a940d1d7129f0f611f02c9151e561413e8c8b7406f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b93efce9a9890fd2e31472c7ac154fee83879befb34165b83dbd2b1383401a26e00bcfeb0cb24993fc360fdc895e9e90add94e3e2b13880d2ed36c8b89f128a1"} 08:55:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:55:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:55:16 INFO - Contents: 08:55:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85168025359ee1b1d6194016a9d91afb6086be900bf5bc701bb54497c6c955c8d05cde2b1a4186eef0e8db7f956db9f98b89912811f2030cebc53409f2cb323f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d492a21d9cd5b5286d0ece1c374f622f99a91259189115407fdf4edd38469feec16c05f37c101a5027f583a940d1d7129f0f611f02c9151e561413e8c8b7406f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b93efce9a9890fd2e31472c7ac154fee83879befb34165b83dbd2b1383401a26e00bcfeb0cb24993fc360fdc895e9e90add94e3e2b13880d2ed36c8b89f128a1"} 08:55:16 INFO - Running post-run listener: copy_logs_to_upload_dir 08:55:16 INFO - Copying logs to upload dir... 08:55:16 INFO - mkdir: /builds/slave/test/build/upload/logs 08:55:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.069716 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-29 08:55:16.598768) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-29 08:55:16.602709) ========= 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.776cMy3Jwb/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.3FIZgaSBtd/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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/Firefox/sha512/85168025359ee1b1d6194016a9d91afb6086be900bf5bc701bb54497c6c955c8d05cde2b1a4186eef0e8db7f956db9f98b89912811f2030cebc53409f2cb323f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d492a21d9cd5b5286d0ece1c374f622f99a91259189115407fdf4edd38469feec16c05f37c101a5027f583a940d1d7129f0f611f02c9151e561413e8c8b7406f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b93efce9a9890fd2e31472c7ac154fee83879befb34165b83dbd2b1383401a26e00bcfeb0cb24993fc360fdc895e9e90add94e3e2b13880d2ed36c8b89f128a1"} build_url:https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011105 build_url: 'https://queue.taskcluster.net/v1/task/YcIQeruPT9OiwmS44y3hIQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85168025359ee1b1d6194016a9d91afb6086be900bf5bc701bb54497c6c955c8d05cde2b1a4186eef0e8db7f956db9f98b89912811f2030cebc53409f2cb323f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d492a21d9cd5b5286d0ece1c374f622f99a91259189115407fdf4edd38469feec16c05f37c101a5027f583a940d1d7129f0f611f02c9151e561413e8c8b7406f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b93efce9a9890fd2e31472c7ac154fee83879befb34165b83dbd2b1383401a26e00bcfeb0cb24993fc360fdc895e9e90add94e3e2b13880d2ed36c8b89f128a1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-29 08:55:16.636340) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 08:55:16.636680) ========= 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.776cMy3Jwb/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.3FIZgaSBtd/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.005227 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 08:55:16.685271) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-29 08:55:16.685627) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-29 08:55:16.685941) ========= ========= Total master_lag: 0.18 =========