builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0353 starttime: 1477541539.42 results: success (0) buildid: 20161026184134 builduid: d72ff4b54eb8421bbf5a93d6034a0cf4 revision: 3f4c3a3cabaf94958834d3a8935adfb4a887942d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 21:12:19.425021) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 21:12:19.425495) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 21:12:19.425786) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 21:12:19.455387) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 21:12:19.455765) ========= 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.gzOLxgRO1a/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.FsfAdjeyMt/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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-26 21:12:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 143M=0s 2016-10-26 21:12:19 (143 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.087745 ========= master_lag: 0.19 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 21:12:19.729091) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 21:12:19.729409) ========= 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.gzOLxgRO1a/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.FsfAdjeyMt/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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.020959 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 21:12:19.770887) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-26 21:12:19.771274) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3f4c3a3cabaf94958834d3a8935adfb4a887942d --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 3f4c3a3cabaf94958834d3a8935adfb4a887942d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gzOLxgRO1a/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.FsfAdjeyMt/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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-26 21:12:19,853 truncating revision to first 12 chars 2016-10-26 21:12:19,853 Setting DEBUG logging. 2016-10-26 21:12:19,853 attempt 1/10 2016-10-26 21:12:19,853 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3f4c3a3cabaf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-26 21:12:21,177 unpacking tar archive at: mozilla-central-3f4c3a3cabaf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.589546 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-26 21:12:21.382529) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 21:12:21.382850) ========= script_repo_revision: 3f4c3a3cabaf94958834d3a8935adfb4a887942d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 21:12:21.383237) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 21:12:21.383502) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 21:12:21.401474) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-10-26 21:12:21.401869) ========= /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.gzOLxgRO1a/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.FsfAdjeyMt/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 21:12:21 INFO - MultiFileLogger online at 20161026 21:12:21 in /builds/slave/test 21:12:21 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 21:12:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:12:21 INFO - {'append_to_log': False, 21:12:21 INFO - 'base_work_dir': '/builds/slave/test', 21:12:21 INFO - 'blob_upload_branch': 'Firefox', 21:12:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:12:21 INFO - 'branch': 'Firefox', 21:12:21 INFO - 'buildbot_json_path': 'buildprops.json', 21:12:21 INFO - 'config_files': ('talos/mac_config.py',), 21:12:21 INFO - 'default_actions': ('clobber', 21:12:21 INFO - 'read-buildbot-config', 21:12:21 INFO - 'download-and-extract', 21:12:21 INFO - 'populate-webroot', 21:12:21 INFO - 'create-virtualenv', 21:12:21 INFO - 'install', 21:12:21 INFO - 'run-tests'), 21:12:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:12:21 INFO - 'download_minidump_stackwalk': True, 21:12:21 INFO - 'download_symbols': 'ondemand', 21:12:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:12:21 INFO - 'tooltool.py': '/tools/tooltool.py', 21:12:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:12:21 INFO - '/tools/misc-python/virtualenv.py')}, 21:12:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:12:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:12:21 INFO - 'installer_path': 'installer.exe', 21:12:21 INFO - 'log_level': 'info', 21:12:21 INFO - 'log_name': 'talos', 21:12:21 INFO - 'log_to_console': True, 21:12:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:12:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:12:21 INFO - 'opt_config_files': (), 21:12:21 INFO - 'pip_index': False, 21:12:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:12:21 INFO - 'cmd': ('bash', 21:12:21 INFO - '-c', 21:12:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:12:21 INFO - 'enabled': True, 21:12:21 INFO - 'halt_on_failure': False, 21:12:21 INFO - 'name': 'check_screen_resolution'},), 21:12:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:12:21 INFO - 'cmd': ('bash', 21:12:21 INFO - '-c', 21:12:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:12:21 INFO - 'enabled': True, 21:12:21 INFO - 'halt_on_failure': False, 21:12:21 INFO - 'name': 'check_screen_resolution'},), 21:12:21 INFO - 'run_cmd_checks_enabled': True, 21:12:21 INFO - 'sps_profile': False, 21:12:21 INFO - 'sps_profile_interval': 0, 21:12:21 INFO - 'suite': 'dromaeojs-e10s', 21:12:21 INFO - 'system_bits': '32', 21:12:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:12:21 INFO - 'title': 't-yosemite-r7-0353', 21:12:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:12:21 INFO - 'use_talos_json': True, 21:12:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:12:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:12:21 INFO - 'work_dir': 'build'} 21:12:21 INFO - [mozharness: 2016-10-27 04:12:21.608051Z] Running clobber step. 21:12:21 INFO - Running pre-action listener: _resource_record_pre_action 21:12:21 INFO - Running main action method: clobber 21:12:21 INFO - rmtree: /builds/slave/test/build 21:12:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:12:23 INFO - Running post-action listener: _resource_record_post_action 21:12:23 INFO - [mozharness: 2016-10-27 04:12:23.268765Z] Finished clobber step (success) 21:12:23 INFO - [mozharness: 2016-10-27 04:12:23.268903Z] Running read-buildbot-config step. 21:12:23 INFO - Running pre-action listener: _resource_record_pre_action 21:12:23 INFO - Running main action method: read_buildbot_config 21:12:23 INFO - Using buildbot properties: 21:12:23 INFO - { 21:12:23 INFO - "project": "", 21:12:23 INFO - "product": "firefox", 21:12:23 INFO - "script_repo_revision": "production", 21:12:23 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 21:12:23 INFO - "repository": "", 21:12:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 21:12:23 INFO - "buildid": "20161026184134", 21:12:23 INFO - "pgo_build": "False", 21:12:23 INFO - "basedir": "/builds/slave/test", 21:12:23 INFO - "buildnumber": 53, 21:12:23 INFO - "slavename": "t-yosemite-r7-0353", 21:12:23 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 21:12:23 INFO - "platform": "yosemite_r7", 21:12:23 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 21:12:23 INFO - "branch": "mozilla-central", 21:12:23 INFO - "repo_path": "mozilla-central", 21:12:23 INFO - "revision": "3f4c3a3cabaf94958834d3a8935adfb4a887942d", 21:12:23 INFO - "stage_platform": "macosx64", 21:12:23 INFO - "builduid": "d72ff4b54eb8421bbf5a93d6034a0cf4", 21:12:23 INFO - "slavebuilddir": "test" 21:12:23 INFO - } 21:12:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 21:12:23 INFO - Running post-action listener: _resource_record_post_action 21:12:23 INFO - [mozharness: 2016-10-27 04:12:23.272196Z] Finished read-buildbot-config step (success) 21:12:23 INFO - [mozharness: 2016-10-27 04:12:23.272332Z] Running download-and-extract step. 21:12:23 INFO - Running pre-action listener: _resource_record_pre_action 21:12:23 INFO - Running main action method: download_and_extract 21:12:23 INFO - mkdir: /builds/slave/test/build/tests 21:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:12:23 INFO - trying https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 21:12:23 INFO - Downloading https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/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 21:12:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/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 21:12:24 INFO - Downloaded 1199 bytes. 21:12:24 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 21:12:24 INFO - Using the following test package requirements: 21:12:24 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 21:12:24 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:12:24 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 21:12:24 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:12:24 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 21:12:24 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:12:24 INFO - u'jsshell-mac.zip'], 21:12:24 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:12:24 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 21:12:24 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 21:12:24 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:12:24 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 21:12:24 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:12:24 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 21:12:24 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:12:24 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 21:12:24 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:12:24 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 21:12:24 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:12:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 21:12:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:12:24 INFO - Fetch https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 21:12:24 INFO - Http code: 200 21:12:24 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 21:12:24 INFO - x-amz-cf-id: XU9F7rpWk8g4ms-NTnlUpRuCbCtg4QRj0l8A0FLIYaM4IQXTiWD9uQ== 21:12:24 INFO - x-amz-version-id: h95TTrysPLqedXSVZquwq4iidBP9._J. 21:12:25 INFO - Expected file size: 16376915 21:12:25 INFO - Obtained file size: 16376915 21:12:32 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 21:12:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 21:12:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:12:32 INFO - Fetch https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 21:12:32 INFO - Http code: 200 21:12:32 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 21:12:32 INFO - x-amz-cf-id: bJvj812sRIN6rFRxKg88L6eHR2xJh2GJsy4dPAU63B-9UrVWS3nkyQ== 21:12:32 INFO - x-amz-version-id: h95TTrysPLqedXSVZquwq4iidBP9._J. 21:12:33 INFO - Expected file size: 16376915 21:12:33 INFO - Obtained file size: 16376915 21:12:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 21:12:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:12:39 INFO - Fetch https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 21:12:40 INFO - Http code: 200 21:12:40 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 21:12:40 INFO - x-amz-cf-id: bgHhbihhkMRYJGyvXq9E1ZMX9c0Z4exgf2C5Oc_sU_VSW8DTDk9_vA== 21:12:40 INFO - x-amz-version-id: VKwGDcwEytcFgK1cZo7j0c1DMYLUPbrf 21:12:40 INFO - Expected file size: 14409028 21:12:40 INFO - Obtained file size: 14409028 21:12: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')]} 21:12:40 INFO - trying https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 21:12:40 INFO - Downloading https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 21:12:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/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 21:12:43 INFO - Downloaded 111550831 bytes. 21:12:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 21:12:43 INFO - mkdir: /builds/slave/test/properties 21:12:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:12:43 INFO - Writing to file /builds/slave/test/properties/build_url 21:12:43 INFO - Contents: 21:12:43 INFO - build_url:https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 21:12:44 INFO - Running post-action listener: _resource_record_post_action 21:12:44 INFO - Running post-action listener: set_extra_try_arguments 21:12:44 INFO - [mozharness: 2016-10-27 04:12:44.377319Z] Finished download-and-extract step (success) 21:12:44 INFO - [mozharness: 2016-10-27 04:12:44.377453Z] Running populate-webroot step. 21:12:44 INFO - Running pre-action listener: _resource_record_pre_action 21:12:44 INFO - Running main action method: populate_webroot 21:12:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:12:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:12:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:12:44 INFO - '--tppagecycles', 21:12:44 INFO - '7'], 21:12:44 INFO - 'tests': ['tsvgm']}}, 21:12:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:12:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:12:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:12:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:12:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:12:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:12:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:12:44 INFO - 'talos_options': ['--disable-e10s'], 21:12:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:12:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:12:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:12:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:12:44 INFO - 'talos_options': ['--disable-e10s'], 21:12:44 INFO - 'tests': ['damp', 'tps']}, 21:12:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:12:44 INFO - 'tests': ['damp', 'tps']}, 21:12:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:12:44 INFO - 'tests': ['dromaeo_dom']}, 21:12:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:12:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:12:44 INFO - 'tests': ['basic_compositor_video']}, 21:12:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:12:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:12:44 INFO - 'tests': ['a11yr', 21:12:44 INFO - 'ts_paint', 21:12:44 INFO - 'tpaint', 21:12:44 INFO - 'sessionrestore', 21:12:44 INFO - 'sessionrestore_no_auto_restore', 21:12:44 INFO - 'tabpaint']}, 21:12:44 INFO - 'other-e10s': {'tests': ['a11yr', 21:12:44 INFO - 'ts_paint', 21:12:44 INFO - 'tpaint', 21:12:44 INFO - 'sessionrestore', 21:12:44 INFO - 'sessionrestore_no_auto_restore', 21:12:44 INFO - 'tabpaint']}, 21:12:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:12:44 INFO - 'ts_paint', 21:12:44 INFO - 'tpaint', 21:12:44 INFO - 'sessionrestore', 21:12:44 INFO - 'sessionrestore_no_auto_restore', 21:12:44 INFO - 'tabpaint']}, 21:12:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:12:44 INFO - 'ts_paint', 21:12:44 INFO - 'tpaint', 21:12:44 INFO - 'sessionrestore', 21:12:44 INFO - 'sessionrestore_no_auto_restore', 21:12:44 INFO - 'tabpaint']}, 21:12:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:12:44 INFO - 'tests': ['a11yr', 21:12:44 INFO - 'ts_paint', 21:12:44 INFO - 'tpaint', 21:12:44 INFO - 'sessionrestore', 21:12:44 INFO - 'sessionrestore_no_auto_restore', 21:12:44 INFO - 'tabpaint']}, 21:12:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:12:44 INFO - 'tests': ['a11yr', 21:12:44 INFO - 'ts_paint', 21:12:44 INFO - 'tpaint', 21:12:44 INFO - 'sessionrestore', 21:12:44 INFO - 'sessionrestore_no_auto_restore', 21:12:44 INFO - 'tabpaint']}, 21:12:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:12:44 INFO - 'tests': ['tsvgx', 21:12:44 INFO - 'tsvgr_opacity', 21:12:44 INFO - 'tart', 21:12:44 INFO - 'tscrollx', 21:12:44 INFO - 'cart']}, 21:12:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:12:44 INFO - 'tsvgr_opacity', 21:12:44 INFO - 'tart', 21:12:44 INFO - 'tscrollx', 21:12:44 INFO - 'cart']}, 21:12:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:12:44 INFO - 'talos_options': ['--disable-e10s'], 21:12:44 INFO - 'tests': ['tp5o']}, 21:12:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:12:44 INFO - 'tests': ['tp5o']}, 21:12:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:12:44 INFO - 'talos_options': ['--disable-e10s', 21:12:44 INFO - '--xperf_path', 21:12:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:12:44 INFO - 'tests': ['tp5n']}, 21:12:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:12:44 INFO - 'talos_options': ['--xperf_path', 21:12:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:12:44 INFO - 'tests': ['tp5n']}}, 21:12:44 INFO - 'talos.zip': {'path': '', 21:12:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:12:44 INFO - Running post-action listener: _resource_record_post_action 21:12:44 INFO - [mozharness: 2016-10-27 04:12:44.396504Z] Finished populate-webroot step (success) 21:12:44 INFO - [mozharness: 2016-10-27 04:12:44.396635Z] Running create-virtualenv step. 21:12:44 INFO - Running pre-action listener: _resource_record_pre_action 21:12:44 INFO - Running main action method: create_virtualenv 21:12:44 INFO - Creating virtualenv /builds/slave/test/build/venv 21:12:44 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 21:12:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:12:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:12:44 INFO - Using real prefix '/tools/python27' 21:12:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:12:45 INFO - Installing distribute.............................................................................................................................................................................................done. 21:12:48 INFO - Installing pip.................done. 21:12:48 INFO - Return code: 0 21:12:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:12:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:12:48 INFO - Reading from file tmpfile_stdout 21:12:48 INFO - Output received: 21:12:48 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) 21:12:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:12:48 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')]} 21:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:48 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')]} 21:12:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:48 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 0x106a2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8aca566cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10683fd30>, '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 0x106665db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106837b20>, '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.gzOLxgRO1a/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.FsfAdjeyMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 21:12:48 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 21:12:48 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 21:12:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzOLxgRO1a/Render', 21:12:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:12:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:12:48 INFO - 'HOME': '/Users/cltbld', 21:12:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:12:48 INFO - 'LOGNAME': 'cltbld', 21:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:48 INFO - 'MOZ_NO_REMOTE': '1', 21:12:48 INFO - 'NO_EM_RESTART': '1', 21:12:48 INFO - 'PAGER': '/bin/cat', 21:12:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:48 INFO - 'PWD': '/builds/slave/test', 21:12:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:12:48 INFO - 'SHELL': '/bin/bash', 21:12:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FsfAdjeyMt/Listeners', 21:12:48 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 21:12:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:12:48 INFO - 'USER': 'cltbld', 21:12:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:12:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:48 INFO - 'XPC_FLAGS': '0x0', 21:12:48 INFO - 'XPC_SERVICE_NAME': '0', 21:12:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:12:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:48 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 21:12:48 INFO - Cleaning up... 21:12:48 INFO - Return code: 0 21:12:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:12:48 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')]} 21:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:48 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')]} 21:12:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:48 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 0x106a2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8aca566cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10683fd30>, '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 0x106665db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106837b20>, '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.gzOLxgRO1a/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.FsfAdjeyMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 21:12:48 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 21:12:48 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 21:12:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzOLxgRO1a/Render', 21:12:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:12:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:12:48 INFO - 'HOME': '/Users/cltbld', 21:12:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:12:48 INFO - 'LOGNAME': 'cltbld', 21:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:48 INFO - 'MOZ_NO_REMOTE': '1', 21:12:48 INFO - 'NO_EM_RESTART': '1', 21:12:48 INFO - 'PAGER': '/bin/cat', 21:12:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:48 INFO - 'PWD': '/builds/slave/test', 21:12:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:12:48 INFO - 'SHELL': '/bin/bash', 21:12:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FsfAdjeyMt/Listeners', 21:12:48 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 21:12:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:12:48 INFO - 'USER': 'cltbld', 21:12:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:12:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:48 INFO - 'XPC_FLAGS': '0x0', 21:12:48 INFO - 'XPC_SERVICE_NAME': '0', 21:12:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:12:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:48 INFO - Downloading/unpacking psutil>=3.1.1 21:12:48 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 21:12:48 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 21:12:48 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 21:12:48 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 21:12:48 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 21:12:48 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 21:12:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:12:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:12:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:12:52 INFO - Installing collected packages: psutil 21:12:52 INFO - Running setup.py install for psutil 21:12:52 INFO - building 'psutil._psutil_osx' extension 21:12:52 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 21:12:52 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 21:12:52 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 21:12:53 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 21:12:53 INFO - building 'psutil._psutil_posix' extension 21:12:53 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 21:12:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:12:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:12:53 INFO - ^ 21:12:53 INFO - 1 warning generated. 21:12:53 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 21:12:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:12:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:12:53 INFO - Successfully installed psutil 21:12:53 INFO - Cleaning up... 21:12:53 INFO - Return code: 0 21:12:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:12:53 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')]} 21:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:53 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')]} 21:12:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:53 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 0x106a2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8aca566cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10683fd30>, '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 0x106665db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106837b20>, '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.gzOLxgRO1a/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.FsfAdjeyMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 21:12:53 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 21:12:53 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 21:12:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzOLxgRO1a/Render', 21:12:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:12:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:12:53 INFO - 'HOME': '/Users/cltbld', 21:12:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:12:53 INFO - 'LOGNAME': 'cltbld', 21:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:53 INFO - 'MOZ_NO_REMOTE': '1', 21:12:53 INFO - 'NO_EM_RESTART': '1', 21:12:53 INFO - 'PAGER': '/bin/cat', 21:12:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:53 INFO - 'PWD': '/builds/slave/test', 21:12:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:12:53 INFO - 'SHELL': '/bin/bash', 21:12:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FsfAdjeyMt/Listeners', 21:12:53 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 21:12:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:12:53 INFO - 'USER': 'cltbld', 21:12:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:12:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:53 INFO - 'XPC_FLAGS': '0x0', 21:12:53 INFO - 'XPC_SERVICE_NAME': '0', 21:12:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:12:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:12:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:12:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:12:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:12:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:12:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:12:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:12:56 INFO - Installing collected packages: mozsystemmonitor 21:12:56 INFO - Running setup.py install for mozsystemmonitor 21:12:56 INFO - Successfully installed mozsystemmonitor 21:12:56 INFO - Cleaning up... 21:12:56 INFO - Return code: 0 21:12:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:12: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')]} 21:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12: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')]} 21:12:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106a2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8aca566cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10683fd30>, '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 0x106665db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106837b20>, '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.gzOLxgRO1a/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.FsfAdjeyMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 21:12: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==2.5.1'] in /builds/slave/test/build 21:12: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==2.5.1 21:12:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzOLxgRO1a/Render', 21:12:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:12:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:12:56 INFO - 'HOME': '/Users/cltbld', 21:12:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:12:56 INFO - 'LOGNAME': 'cltbld', 21:12:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:56 INFO - 'MOZ_NO_REMOTE': '1', 21:12:56 INFO - 'NO_EM_RESTART': '1', 21:12:56 INFO - 'PAGER': '/bin/cat', 21:12:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:12:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:56 INFO - 'PWD': '/builds/slave/test', 21:12:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:12:56 INFO - 'SHELL': '/bin/bash', 21:12:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FsfAdjeyMt/Listeners', 21:12:56 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 21:12:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:12:56 INFO - 'USER': 'cltbld', 21:12:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:12:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:12:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:56 INFO - 'XPC_FLAGS': '0x0', 21:12:56 INFO - 'XPC_SERVICE_NAME': '0', 21:12:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:12:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:56 INFO - Downloading/unpacking jsonschema==2.5.1 21:12:56 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 21:12:56 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 21:12:56 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 21:12:56 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 21:12:56 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 21:12:56 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 21:12:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:12:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:12:59 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 21:12:59 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 21:12:59 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 21:12:59 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 21:12:59 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 21:12:59 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 21:12:59 INFO - Downloading functools32-3.2.3-2.tar.gz 21:12:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:12:59 INFO - warning: no files found matching '*.txt' 21:12:59 INFO - no previously-included directories found matching 'build' 21:12:59 INFO - no previously-included directories found matching 'dist' 21:12:59 INFO - no previously-included directories found matching '.git*' 21:12:59 INFO - Installing collected packages: jsonschema, functools32 21:12:59 INFO - Running setup.py install for functools32 21:12:59 INFO - warning: no files found matching '*.txt' 21:12:59 INFO - no previously-included directories found matching 'build' 21:12:59 INFO - no previously-included directories found matching 'dist' 21:12:59 INFO - no previously-included directories found matching '.git*' 21:13:00 INFO - Successfully installed jsonschema functools32 21:13:00 INFO - Cleaning up... 21:13:00 INFO - Return code: 0 21:13:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:13: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')]} 21:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:13:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13: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')]} 21:13:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:13:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:00 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 0x106a2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8aca566cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10683fd30>, '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 0x106665db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106837b20>, '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.gzOLxgRO1a/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.FsfAdjeyMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 21:13:00 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 21:13:00 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 21:13:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzOLxgRO1a/Render', 21:13:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:13:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:13:00 INFO - 'HOME': '/Users/cltbld', 21:13:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:13:00 INFO - 'LOGNAME': 'cltbld', 21:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:13:00 INFO - 'MOZ_NO_REMOTE': '1', 21:13:00 INFO - 'NO_EM_RESTART': '1', 21:13:00 INFO - 'PAGER': '/bin/cat', 21:13:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:13:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:13:00 INFO - 'PWD': '/builds/slave/test', 21:13:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:13:00 INFO - 'SHELL': '/bin/bash', 21:13:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FsfAdjeyMt/Listeners', 21:13:00 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 21:13:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:13:00 INFO - 'USER': 'cltbld', 21:13:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:13:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:13:00 INFO - 'XPC_FLAGS': '0x0', 21:13:00 INFO - 'XPC_SERVICE_NAME': '0', 21:13:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:13:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:13:00 INFO - Cleaning up... 21:13:00 INFO - Return code: 0 21:13:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:13: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')]} 21:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:13:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13: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')]} 21:13:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:13:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:00 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 0x106a2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8aca566cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10683fd30>, '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 0x106665db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106837b20>, '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.gzOLxgRO1a/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.FsfAdjeyMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 21:13:00 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 21:13:00 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 21:13:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzOLxgRO1a/Render', 21:13:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:13:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:13:00 INFO - 'HOME': '/Users/cltbld', 21:13:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:13:00 INFO - 'LOGNAME': 'cltbld', 21:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:13:00 INFO - 'MOZ_NO_REMOTE': '1', 21:13:00 INFO - 'NO_EM_RESTART': '1', 21:13:00 INFO - 'PAGER': '/bin/cat', 21:13:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:13:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:13:00 INFO - 'PWD': '/builds/slave/test', 21:13:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:13:00 INFO - 'SHELL': '/bin/bash', 21:13:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FsfAdjeyMt/Listeners', 21:13:00 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 21:13:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:13:00 INFO - 'USER': 'cltbld', 21:13:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:13:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:13:00 INFO - 'XPC_FLAGS': '0x0', 21:13:00 INFO - 'XPC_SERVICE_NAME': '0', 21:13:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:13:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:13:00 INFO - Downloading/unpacking blobuploader==1.2.4 21:13:00 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 21:13:00 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 21:13:00 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 21:13:00 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 21:13:00 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 21:13:00 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 21:13:03 INFO - Downloading blobuploader-1.2.4.tar.gz 21:13:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:13:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:13:03 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 21:13:03 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 21:13:03 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 21:13:03 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 21:13:03 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 21:13:03 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 21:13:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:13:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:13:03 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 21:13:03 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 21:13:03 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 21:13:03 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 21:13:03 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 21:13:03 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 21:13:04 INFO - Downloading docopt-0.6.1.tar.gz 21:13:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:13:04 INFO - Installing collected packages: blobuploader, requests, docopt 21:13:04 INFO - Running setup.py install for blobuploader 21:13:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:13:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:13:04 INFO - Running setup.py install for requests 21:13:05 INFO - Running setup.py install for docopt 21:13:05 INFO - Successfully installed blobuploader requests docopt 21:13:05 INFO - Cleaning up... 21:13:05 INFO - Return code: 0 21:13:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:13:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:13:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:05 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 0x106a2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8aca566cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10683fd30>, '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 0x106665db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106837b20>, '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.gzOLxgRO1a/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.FsfAdjeyMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 21:13:05 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 21:13:05 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 21:13:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzOLxgRO1a/Render', 21:13:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:13:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:13:05 INFO - 'HOME': '/Users/cltbld', 21:13:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:13:05 INFO - 'LOGNAME': 'cltbld', 21:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:13:05 INFO - 'MOZ_NO_REMOTE': '1', 21:13:05 INFO - 'NO_EM_RESTART': '1', 21:13:05 INFO - 'PAGER': '/bin/cat', 21:13:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:13:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:13:05 INFO - 'PWD': '/builds/slave/test', 21:13:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:13:05 INFO - 'SHELL': '/bin/bash', 21:13:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FsfAdjeyMt/Listeners', 21:13:05 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 21:13:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:13:05 INFO - 'USER': 'cltbld', 21:13:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:13:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:13:05 INFO - 'XPC_FLAGS': '0x0', 21:13:05 INFO - 'XPC_SERVICE_NAME': '0', 21:13:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:13:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:13:05 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Dupo5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:13:05 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-D59MR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:13:05 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-VEwkZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:13:05 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-PjZcWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:13:05 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-917YvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:13:06 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-tnbW7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:13:06 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-6ki3YR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:13:06 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-a86Ff9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:13:06 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-bkMeeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:13:06 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-zhCpyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:13:06 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-JUqxnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:13:06 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-LRtPx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:13:06 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-RHnFvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:13:06 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-LOVhDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:13:07 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-nEF1st-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:13:07 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-kKw1Lg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:13:07 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-vqkoVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:13:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:13:07 INFO - Running setup.py install for manifestparser 21:13:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:13:07 INFO - Running setup.py install for mozcrash 21:13:07 INFO - Running setup.py install for mozdebug 21:13:07 INFO - Running setup.py install for mozdevice 21:13:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:13:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:13:07 INFO - Running setup.py install for mozfile 21:13:08 INFO - Running setup.py install for mozhttpd 21:13:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:13:08 INFO - Running setup.py install for mozinfo 21:13:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:13:08 INFO - Running setup.py install for mozInstall 21:13:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:13:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:13:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:13:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:13:08 INFO - Running setup.py install for mozleak 21:13:08 INFO - Running setup.py install for mozlog 21:13:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:13:08 INFO - Running setup.py install for moznetwork 21:13:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:13:09 INFO - Running setup.py install for mozprocess 21:13:09 INFO - Running setup.py install for mozprofile 21:13:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:13:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:13:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:13:09 INFO - Running setup.py install for mozrunner 21:13:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:13:09 INFO - Running setup.py install for mozscreenshot 21:13:09 INFO - Running setup.py install for moztest 21:13:09 INFO - Running setup.py install for mozversion 21:13:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:13:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:13:09 INFO - Cleaning up... 21:13:09 INFO - Return code: 0 21:13:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:13:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:13:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:13:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:13:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:13:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:09 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 0x106a2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8aca566cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10683fd30>, '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 0x106665db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106837b20>, '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.gzOLxgRO1a/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.FsfAdjeyMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 21:13:09 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 21:13:09 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 21:13:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzOLxgRO1a/Render', 21:13:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:13:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:13:09 INFO - 'HOME': '/Users/cltbld', 21:13:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:13:09 INFO - 'LOGNAME': 'cltbld', 21:13:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:13:09 INFO - 'MOZ_NO_REMOTE': '1', 21:13:09 INFO - 'NO_EM_RESTART': '1', 21:13:09 INFO - 'PAGER': '/bin/cat', 21:13:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:13:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:13:09 INFO - 'PWD': '/builds/slave/test', 21:13:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:13:09 INFO - 'SHELL': '/bin/bash', 21:13:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FsfAdjeyMt/Listeners', 21:13:09 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 21:13:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:13:09 INFO - 'USER': 'cltbld', 21:13:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:13:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:13:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:13:09 INFO - 'XPC_FLAGS': '0x0', 21:13:09 INFO - 'XPC_SERVICE_NAME': '0', 21:13:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:13:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:13:10 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-omNBwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:13:10 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)) 21:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:13:10 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Tg5EVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:13:10 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)) 21:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:13:10 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-s4VdNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:13:10 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)) 21:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:13:10 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-3OE4Nd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:13:10 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)) 21:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:13:10 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-WktNvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:13:10 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)) 21:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:13:10 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-1DCYZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:13:10 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)) 21:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:13:10 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-06lhUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:13:11 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)) 21:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:13:11 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-GEgR_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:13:11 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)) 21:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:13:11 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-a719F1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:13:11 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)) 21:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:13:11 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-0f5drF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:13:11 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)) 21:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:13:11 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-e0J8VG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:13:11 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)) 21:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:13:11 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-d2_pEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:13:11 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)) 21:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:13:11 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-giBrEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:13:11 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)) 21:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:13:11 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-GdEwzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:13:11 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)) 21:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:13:11 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-AraObr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:13:11 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)) 21:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:13:11 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-MCiupN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:13:11 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)) 21:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:13:11 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-jqzDHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:13:12 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)) 21:13:12 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)) 21:13:12 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)) 21:13:12 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)) 21:13:12 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)) 21:13:12 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)) 21:13:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:13:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:13:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:13:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:13:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:13:14 INFO - Downloading blessings-1.6.tar.gz 21:13:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:13:14 INFO - Installing collected packages: blessings 21:13:14 INFO - Running setup.py install for blessings 21:13:14 INFO - Successfully installed blessings 21:13:14 INFO - Cleaning up... 21:13:15 INFO - Return code: 0 21:13:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:13:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:13:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:13:15 INFO - Reading from file tmpfile_stdout 21:13:15 INFO - Current package versions: 21:13:15 INFO - blessings == 1.6 21:13:15 INFO - blobuploader == 1.2.4 21:13:15 INFO - docopt == 0.6.1 21:13:15 INFO - functools32 == 3.2.3-2 21:13:15 INFO - jsonschema == 2.5.1 21:13:15 INFO - manifestparser == 1.1 21:13:15 INFO - mozInstall == 1.12 21:13:15 INFO - mozcrash == 0.17 21:13:15 INFO - mozdebug == 0.1 21:13:15 INFO - mozdevice == 0.48 21:13:15 INFO - mozfile == 1.2 21:13:15 INFO - mozhttpd == 0.7 21:13:15 INFO - mozinfo == 0.9 21:13:15 INFO - mozleak == 0.1 21:13:15 INFO - mozlog == 3.4 21:13:15 INFO - moznetwork == 0.27 21:13:15 INFO - mozprocess == 0.23 21:13:15 INFO - mozprofile == 0.28 21:13:15 INFO - mozrunner == 6.12 21:13:15 INFO - mozscreenshot == 0.1 21:13:15 INFO - mozsystemmonitor == 0.3 21:13:15 INFO - moztest == 0.7 21:13:15 INFO - mozversion == 1.4 21:13:15 INFO - psutil == 3.1.1 21:13:15 INFO - requests == 1.2.3 21:13:15 INFO - wsgiref == 0.1.2 21:13:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:13:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:13:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:13:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:13:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:13:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:15 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 0x106a2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8aca566cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10683fd30>, '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 0x106665db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106837b20>, '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.gzOLxgRO1a/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.FsfAdjeyMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 21:13:15 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 21:13:15 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 21:13:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzOLxgRO1a/Render', 21:13:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:13:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:13:15 INFO - 'HOME': '/Users/cltbld', 21:13:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:13:15 INFO - 'LOGNAME': 'cltbld', 21:13:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:13:15 INFO - 'MOZ_NO_REMOTE': '1', 21:13:15 INFO - 'NO_EM_RESTART': '1', 21:13:15 INFO - 'PAGER': '/bin/cat', 21:13:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:13:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:13:15 INFO - 'PWD': '/builds/slave/test', 21:13:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:13:15 INFO - 'SHELL': '/bin/bash', 21:13:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FsfAdjeyMt/Listeners', 21:13:15 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 21:13:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:13:15 INFO - 'USER': 'cltbld', 21:13:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:13:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:13:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:13:15 INFO - 'XPC_FLAGS': '0x0', 21:13:15 INFO - 'XPC_SERVICE_NAME': '0', 21:13:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:13:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:13:15 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)) 21:13:15 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)) 21:13:15 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)) 21:13:15 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)) 21:13:15 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)) 21:13:15 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)) 21:13:15 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)) 21:13:15 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)) 21:13:15 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)) 21:13:15 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)) 21:13:15 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)) 21:13:15 INFO - Cleaning up... 21:13:15 INFO - Return code: 0 21:13:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:13:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:13:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:13:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:13:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:13:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:15 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 0x106a2b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8aca566cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10683fd30>, '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 0x106665db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106837b20>, '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.gzOLxgRO1a/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.FsfAdjeyMt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 21:13:15 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 21:13:15 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 21:13:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzOLxgRO1a/Render', 21:13:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:13:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:13:15 INFO - 'HOME': '/Users/cltbld', 21:13:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:13:15 INFO - 'LOGNAME': 'cltbld', 21:13:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:13:15 INFO - 'MOZ_NO_REMOTE': '1', 21:13:15 INFO - 'NO_EM_RESTART': '1', 21:13:15 INFO - 'PAGER': '/bin/cat', 21:13:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:13:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:13:15 INFO - 'PWD': '/builds/slave/test', 21:13:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:13:15 INFO - 'SHELL': '/bin/bash', 21:13:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FsfAdjeyMt/Listeners', 21:13:15 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 21:13:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:13:15 INFO - 'USER': 'cltbld', 21:13:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:13:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:13:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:13:15 INFO - 'XPC_FLAGS': '0x0', 21:13:15 INFO - 'XPC_SERVICE_NAME': '0', 21:13:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:13:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:13:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:13:16 INFO - Cleaning up... 21:13:16 INFO - Return code: 0 21:13:16 INFO - Running post-action listener: _resource_record_post_action 21:13:16 INFO - Running post-action listener: _start_resource_monitoring 21:13:16 INFO - Starting resource monitoring. 21:13:16 INFO - [mozharness: 2016-10-27 04:13:16.244529Z] Finished create-virtualenv step (success) 21:13:16 INFO - [mozharness: 2016-10-27 04:13:16.245513Z] Running install step. 21:13:16 INFO - Running pre-action listener: _resource_record_pre_action 21:13:16 INFO - Running main action method: install 21:13:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:13:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:13:16 INFO - Reading from file tmpfile_stdout 21:13:16 INFO - Detecting whether we're running mozinstall >=1.0... 21:13:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:13:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:13:16 INFO - Reading from file tmpfile_stdout 21:13:16 INFO - Output received: 21:13:16 INFO - Usage: mozinstall [options] installer 21:13:16 INFO - Options: 21:13:16 INFO - -h, --help show this help message and exit 21:13:16 INFO - -d DEST, --destination=DEST 21:13:16 INFO - Directory to install application into. [default: 21:13:16 INFO - "/builds/slave/test"] 21:13:16 INFO - --app=APP Application being installed. [default: firefox] 21:13:16 INFO - mkdir: /builds/slave/test/build/application 21:13:16 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'] 21:13:16 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 21:13:50 INFO - Reading from file tmpfile_stdout 21:13:50 INFO - Output received: 21:13:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:13:50 INFO - Running post-action listener: _resource_record_post_action 21:13:50 INFO - [mozharness: 2016-10-27 04:13:50.658068Z] Finished install step (success) 21:13:50 INFO - [mozharness: 2016-10-27 04:13:50.658214Z] Running run-tests step. 21:13:50 INFO - Running pre-action listener: _resource_record_pre_action 21:13:50 INFO - Running main action method: run_tests 21:13:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:13:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:13:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:13:50 INFO - 2016-10-26 21:13:50.672 screenresolution[1933:16450] starting screenresolution argv=screenresolution get 21:13:50 INFO - 2016-10-26 21:13:50.675 screenresolution[1933:16450] Display 0: 1600x1200x32@60 21:13:50 INFO - 2016-10-26 21:13:50.681 screenresolution[1934:16452] starting screenresolution argv=screenresolution list 21:13:50 INFO - Available Modes on Display 0 21:13:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:13:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:13:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:13:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:13:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:13:50 INFO - Intel Iris: 21:13:50 INFO - Chipset Model: Intel Iris 21:13:50 INFO - Type: GPU 21:13:50 INFO - Bus: Built-In 21:13:50 INFO - VRAM (Dynamic, Max): 1536 MB 21:13:50 INFO - Vendor: Intel (0x8086) 21:13:50 INFO - Device ID: 0x0a2e 21:13:50 INFO - Revision ID: 0x0009 21:13:50 INFO - Displays: 21:13:50 INFO - Display: 21:13:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:13:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:13:50 INFO - Display Serial Number: 1600x1200 60 21:13:50 INFO - Main Display: Yes 21:13:50 INFO - Mirror: Off 21:13:50 INFO - Online: Yes 21:13:50 INFO - Rotation: Supported 21:13:50 INFO - Return code: 0 21:13:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:13:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:13:50 INFO - Python 2.7.3 21:13:50 INFO - Return code: 0 21:13:50 INFO - grabbing minidump binary from tooltool 21:13: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')]} 21:13:50 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 0x10683fd30>, '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 0x106665db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106837b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:13:50 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 21:13:50 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 21:13:50 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 21:13:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:13:51 INFO - Return code: 0 21:13:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:13:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:13:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:13:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:13:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:13:51 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/aqLaAy6BRYGskUgB8za_eQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0353', '--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 21:13:51 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/aqLaAy6BRYGskUgB8za_eQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0353 --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 21:13:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzOLxgRO1a/Render', 21:13:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:13:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:13:51 INFO - 'HOME': '/Users/cltbld', 21:13:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:13:51 INFO - 'LOGNAME': 'cltbld', 21:13:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:13:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:13:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:13:51 INFO - 'MOZ_NO_REMOTE': '1', 21:13:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:13:51 INFO - 'NO_EM_RESTART': '1', 21:13:51 INFO - 'PAGER': '/bin/cat', 21:13:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:13:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:13:51 INFO - 'PWD': '/builds/slave/test', 21:13:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:13:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:13:51 INFO - 'SHELL': '/bin/bash', 21:13:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FsfAdjeyMt/Listeners', 21:13:51 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 21:13:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:13:51 INFO - 'USER': 'cltbld', 21:13:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:13:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:13:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:13:51 INFO - 'XPC_FLAGS': '0x0', 21:13:51 INFO - 'XPC_SERVICE_NAME': '0', 21:13:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:13:51 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/aqLaAy6BRYGskUgB8za_eQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0353', '--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 21:13:51 INFO - mozversion application_buildid: 20161026184134 21:13:51 INFO - mozversion application_changeset: 3f4c3a3cabaf94958834d3a8935adfb4a887942d 21:13:51 INFO - mozversion application_display_name: Nightly 21:13:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:13:51 INFO - mozversion application_name: Firefox 21:13:51 INFO - mozversion application_remotingname: firefox 21:13:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:13:51 INFO - mozversion application_vendor: Mozilla 21:13:51 INFO - mozversion application_version: 52.0a1 21:13:51 INFO - mozversion platform_buildid: 20161026184134 21:13:51 INFO - mozversion platform_changeset: 3f4c3a3cabaf94958834d3a8935adfb4a887942d 21:13:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:13:51 INFO - mozversion platform_version: 52.0a1 21:13:51 INFO - using testdate: 1477541631 21:13:51 INFO - actual date: 1477541631 21:13:51 INFO - starting webserver on 'localhost:54514' 21:13:51 INFO - SUITE-START | Running 2 tests 21:13:51 INFO - TEST-START | dromaeo_css 21:13:51 INFO - operating with platform_type : mac_ 21:13:51 INFO - Initialising browser for dromaeo_css test... 21:13:51 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpxVLOgz/profile http://localhost:54514/getInfo.html) 21:13:54 INFO - PROCESS | 1944 | 2016-10-26 21:13:54.448 plugin-container[1946:16615] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4b, name = 'com.apple.tsm.portname' 21:13:54 INFO - PROCESS | 1944 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:13:54 INFO - PROCESS | 1944 | 2016-10-26 21:13:54.451 plugin-container[1946:16615] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 21:13:54 INFO - PROCESS | 1944 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:13:54 INFO - PROCESS | 1944 | 2016-10-26 21:13:54.451 plugin-container[1946:16615] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:13:54 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 21:13:54 INFO - PROCESS | 1944 | colorDepth:24 21:13:54 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 21:13:54 INFO - PROCESS | 1944 | __metrics 21:13:54 ERROR - PROCESS | 1944 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 21:13:55 INFO - TEST-INFO | 1944: exit 0 21:13:55 INFO - Browser initialized. 21:13:55 INFO - Running cycle 1/1 for dromaeo_css test... 21:13:55 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpxVLOgz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:13:57 INFO - PROCESS | 1947 | 2016-10-26 21:13:57.628 plugin-container[1948:16736] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 21:13:57 INFO - PROCESS | 1947 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:13:57 INFO - PROCESS | 1947 | 2016-10-26 21:13:57.629 plugin-container[1948:16736] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 21:13:57 INFO - PROCESS | 1947 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:13:57 INFO - PROCESS | 1947 | 2016-10-26 21:13:57.629 plugin-container[1948:16736] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:14:07 INFO - PROCESS | 1947 | RSS: Main: 236638208 21:14:07 INFO - PROCESS | 1947 | 21:14:56 INFO - PROCESS | 1947 | 1477541696984 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] 21:17:10 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54514/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54514/tests/dromaeo/cssquery-ext.html) 21:17:12 INFO - PROCESS | 1947 | RSS: Main: 249253888 21:17:12 INFO - PROCESS | 1947 | 21:20:14 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54514/tests/dromaeo/cssquery-ext.html (next: http://localhost:54514/tests/dromaeo/cssquery-jquery.html) 21:20:15 INFO - PROCESS | 1947 | RSS: Main: 261672960 21:20:15 INFO - PROCESS | 1947 | 21:23:16 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54514/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54514/tests/dromaeo/cssquery-mootools.html) 21:23:17 INFO - PROCESS | 1947 | RSS: Main: 265588736 21:23:17 INFO - PROCESS | 1947 | 21:26:19 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54514/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54514/tests/dromaeo/cssquery-prototype.html) 21:26:20 INFO - PROCESS | 1947 | RSS: Main: 264536064 21:26:20 INFO - PROCESS | 1947 | 21:29:22 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54514/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54514/tests/dromaeo/cssquery-yui.html) 21:29:23 INFO - PROCESS | 1947 | RSS: Main: 269053952 21:29:23 INFO - PROCESS | 1947 | 21:32:26 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54514/tests/dromaeo/cssquery-yui.html (next: http://localhost:54514/tests/dromaeo/cssquery-dojo.html) 21:32:26 INFO - PROCESS | 1947 | RSS: Main: 270995456 21:32:26 INFO - PROCESS | 1947 | 21:32:26 INFO - PROCESS | 1947 | __start_tp_report 21:32:26 INFO - PROCESS | 1947 | _x_x_mozilla_page_load 21:32:26 INFO - PROCESS | 1947 | _x_x_mozilla_page_load_details 21:32:26 INFO - PROCESS | 1947 | |i|pagename|runs| 21:32:26 INFO - PROCESS | 1947 | |0;dojo.html;3368.9663103368975;3373.9203454894437;3389.898303050908;3397.7451691123174;3399.983000085;5759.654420734753;5765.711714414283;5776.9711151444235;5812.186293918855;5825.417458254173;5648.4633959773855;5787.421257874211;5795.217645617851;5842.591018628697;5874.236349274599;5161.380634323887;5200.297959775438;5245.160774276116;5252.8686782830355;5261.579073674116;4525.592696657301;5189.714565698885;5277.8944421111555;5278.788848446057;5282.894342113155;5310.070737620901;5324.1215199492035;5328.1474964005765;5356.250124982505;5356.250124982505;5134.460881607415;5211.557017653504;5213.32226810516;5258.92111618326;5267.710275934822;3742.1018955450495;4027.8590249341423;4609.778408721691;5206.76569554371;5214.97392513035;3792.9758965079272;5626.987142314422;5722.713864306809;5730.885382292373;5733.140028995643;6149.739303442783;6156.78451254204;6169.907451388232;6172;6229.813105606839;6245.718942647592;6274.968625156891;6277.497800175975;6282.937170628281;6293.622382657009;5835.976258413481;5904.350521442638;5988.610740301867;5990.820275391745;6033.0347144457555;5800.1299805029585;5837.883242335214;6216.875662486725;6243.250809902841;6246.812595622138;6218.098375735493;6222.12890195373;6236.875262494725;6246.187995560548;6257;6249;6272.1219029335925;6302.086197501429;6322.209723784527;6337.65142917144;6137.386261373917;6162.168107305456;6178.783742568989;6196.349383314687;6204.844878878064;5340.732963351817;6179.6910154493;6185.288691800411;6205.875882482325;6256.781012664626;4903.313536104948;4963.156263435225;4983.750812459362;4996.375453068366;5027.648064635514;3832.4251362295877;4484.529124441952;4507.3689683444345;4523.072770082125;4524.434445694288;4502.684812063124;5401.567874570025;5423.430539793344;5699.772009119672;5729;5206.635535512478;6232.127502149703;6245.500359971192;6249.0626406039455;6283.717232724538;5813.79651712188;5821.767129314865;5825.766969321265;5838.9124163137585;5840.094785308284;4514.210013247669;5379.78480860769;5404.8108316208745;5407.540359069523;5422.240886275925;6245.656488893154;6272.466840318532;6285.96857015712;6315;6318.08387783778;6267.655278959701;6270.0908368287255;6277.780277690259;6287.497000240061;6289.716962736779;6176.258848937977;6202.875942481215;6240.937590624218;6268.153799237045;6303.432691057827;6131.315499378873;6184.6598437086395;6209.441150296495;6217.595856269328;6224.035274532454;6035.668038257938;6251.499880009679;6259.155014073224;6264.49884009269;6273.749050037947;6014.879702406015;6105.459747277989;6164.7842325518395;6178.814635560941;6181.660008699565;6104.664243466652;6123.099607468126;6125.785597504244;6147.846303842261;6243.344448832807;5229.823289759774;5898.4101589840675;6063.090536419572;6066.331758766374;6084.39156084388;5055.797768089235;5995.850103747442;6044.184035155197;6124.6325220486915;6205.565610407409;5983.640981541122;6067.302260240129;6087.360827113266;6098.878022439438;6121.326654068138 21:32:26 INFO - PROCESS | 1947 | |1;ext.html;447.46080972428246;458.26219786144304;509.8699831542956;517.9715115668638;535.4966331648253;37925.43111853321;39925;40104.79947600261;42394.788026059825;42480.51318203864;30836.53745193824;41983.79008104955;42049.57950420495;42063;42092;9270.721878343644;9465.337426380156;9472.857907131398;9477.336586438953;9486.956434792168;9466.81066378672;9517.23862091031;9518.762030949247;9520.047995200459;9540.952295238514;9491.572879220417;9496.192823610001;9518.619255229818;9528.666496672618;9554.140127388533;9415.576299066559;9486.9525652372;9495.52522373882;9523.809523809485;9530.809383812355;9077.954610226972;9089;9145.405548639385;9152.039035901267;9172.312076594215;39321.60678393208;39361;39772.80113599414;39900.40149397762;39909.60090399088;3290.3912776136253;3335.666433356669;3514.139035936212;3812.485314482537;3967.9484936491854;30503.20074518899;30925;31121.377572448426;31271.530927036114;31865.203369915707;3196.1210667066744;3248.6101667800017;3248.805071695706;3317.883874064396;3363.226457914669;493560;498130;501026;501045;501566.46083848196;56671.716641416526;57067.14399284014;57090.001849935055;57185;57259;55915.16127258095;56671;57699;57715.42284577184;57944.71027644835;15306.38774449032;15508.612284692746;15618.219089045502;15629.531114066596;15640.530784076496;11974.940125299492;12126.333051682243;12136.45385957634;12185.147039707135;12234.694132646755;14523.419063237563;14695.77956330656;14744.778828317583;14814.555563333117;15016;6655.567388119756;6729.831754206086;6741.22475915276;6766.458683305422;6819.454443644497;861.6208868098017;916.6745805239177;1059.0309866472246;1102.2284400919389;1134.9388321919032;22886.542269154525;24751;26430.471390572464;28724;29741.553876691865;822.7984143884667;1014.4623349624711;1033.4161198922584;1067.5196161727254;1142.4915912418894;14845.480408185662;15286.783416404782;15295.388184472718;15636.530904072895;15668.529944101696;628034;635216;635613;639397;640998;57461.13808292795;57550;57618.8476230473;57754.7112264436;57760;1175.306569124221;1191.79628575138;1233.6114124050896;1237.270010693695;1399.8530154333648;29319.706802931698;30926.845365773028;31237.68762312348;32316.86890958805;32606.184845379055;55760.60598485071;55916.16125758198;55950;56086.158707620234;56258.87482250414;588714;589333;591740;592376;592574;25638.846251918334;25720.97116115409;25804;26052.088176913716;26070;46663;47391.289130663754;47795.04409911852;48142.22293324823;49044.50955490399;33230.16924576905;38905.249263783764;39273;39777.00557486086;40730.59269407268;38629.806850965564;38944.61055389523;39097;40223.597764021986;40445;37905;39056.609433905294;39123;39213.60786392214;39335.803320983214;30954.845225773726;34083.14792130118;34772.130696732784;34819.82590087135;34876.476852847736;23273.883630582415;25533.3616659585;25821.612675810255;25939;26033.349166270993 21:32:26 INFO - PROCESS | 1947 | |2;jquery.html;363.09226932668344;371.79922841665496;376.17242067451616;379.71900793412874;392.6995848176144;19331.42005739828;20560.280390186348;20665;21099.99999999998;21280.57438851222;20450.284240051602;20569.485762855937;20575.794242057575;20606.896965515152;20956.26653067143;4577.08458308338;5004;5589.608727389094;5893.086571581412;5905;5741.110127930177;5879.52963762898;5883.382244864292;5888.793892213774;5890.087036509327;5456.290682211307;5721.141828725683;5734.397888221739;5746.913796293059;5818.8254352369495;5493.478119578674;5665.263515742948;5731.1116776899435;5734.340550836624;5740.368559458477;4938.9753051234875;4956.479569645207;4995.725235112068;5026.321446604698;5033.219850923113;25045.87477062621;25199.87400063006;25301.114460993776;25443.491130177477;25751;2190.244365693849;2309.5265470578493;2409.6987876515436;2663.5072511585417;2674.5185866544016;15316.693666126726;15339.693206135926;15464;15902.363905443768;16236.51290461288;1621.9107949062798;1754.1317048061292;1845.4371416717895;1872.2604571194438;1883.660941030614;125273.37363313216;126156;128378;129130;130660.34669826685;9579.329446938787;10182.23633227511;10547.419891906018;10561.524731386953;10703.78592428147;12993.545225917236;13008.739825203444;13016.219026858418;13016.284104374161;13031.869681303066;8072.434929554992;8395.70615028471;8437.620307086196;8528.488290702579;8564.3148548116;6337.96831015842;7641.2740789624895;8381.119982401882;8511.233988941025;8642.660387639926;6450.967745161244;6496.610203387811;6504;6515.784633224726;6526.314736952551;1547.257316488092;1851.453821122772;1865.5242913057084;1868.9439316820517;1877.5306173456636;714.4213187318185;881.7090360181124;886.4061079077034;892.4734406700078;965.8361674182593;20966.685499717518;21015;21353.14587416517;21689.240876569245;22025.898705064683;884.4648987362682;967.6806653804227;982.0866594067586;1003.2024540490328;1035.9067683908484;10892.509837057352;11382.219273345878;11505.309681419141;11549.76900461986;11602.94198529002;565109;570855;572921;576783;590794;69458.65270673615;117815.41092294484;123444;126522;128802;915.555955361649;1003.7841863999129;1014.2697257974247;1058.074185088048;1059.89930956559;12795.680107997374;16616.50150495487;19120;19323;19381.449484041525;47550.04899902052;50733;51479.74260128675;52009.7399513;54968.72515637396;80357.59821200857;81837;82446;82637.3472530558;82830.17169828466;7784.260495252942;8020.679172833022;8087.716929907425;8093.367367287652;8162.428629995843;10385.844212336971;11095.223334366518;12485.812712809133;12852.935735321264;12947.741045179233;9582.760430989281;10729.195310351912;10748;10781.9460902695;11078.279911805706;9238.907610923805;10661.413622250851;10830.945845271039;10882.94558527234;11155.16336274799;11014.39420831862;11266.60566880188;11407.543698251977;11499.885001150216;11540.480678369662;6957.443404527727;7484.962575187307;7805.687772489264;7926.960365198137;8010.639521221676;9878.75303117399;10601.985622731656;10904.291221070605;10961.903809618974;11093.22347435704 21:32:26 INFO - PROCESS | 1947 | |3;mootools.html;1504.1276059885267;1511.9319630616621;1538.0848395204853;1761.7667632657144;1891.540996704285;6455.419012288899;7132.14414270287;7600.96199519003;8792.34057445691;8802.647894084237;8248.711295104673;8555.743327700164;8580.871286930702;8656.8701469478;8692.826143477127;1906.5805522784965;2285.6685780561806;2313.1325752842686;2321.628539433691;2326.8836558172106;2302.067662596651;2337.602607556711;2366.668666386707;2367.6566897799808;2371.3834403055243;2020.8686435381726;2199.59307528107;2315.235972129193;2323.8024767894663;2343.7187537495524;2295.139322753959;2298.471351589144;2305.0318866076286;2324.0819876148994;2331.172433786007;1837.926482940694;2348.483333666608;2349.682792822964;2367.621180611111;2367.6803631509697;8801.515916624583;8810.339224558184;8841.38110332277;8841.867371989425;8874.378793484459;5158.4325724170685;5321.840344789663;5321.893562128775;5328.254044433782;5347.812826551052;2785.554311310201;3276.459384201604;3596.5684117906017;3628.4738712886747;3645.8541658333634;957.1242313283337;970.1656575345197;977.2768151567859;1062.3360399750156;1071.405370019639;9394.389364691273;10858.294210876267;11124.555017799279;11244.831327530093;11315.490802913806;4340.891477713019;4414.4702635683925;4513.052259025641;4525.705829121097;4537.069900670354;8123.3095186908595;8958.731238062868;9053.275737940949;9068.591913363915;9082.909170908339;5223.582113430916;5225.947740522623;5227.738613069331;5231.843044708665;5232.189010703271;4283.60782745609;4739.810407583727;4841.346418233564;4878.536539028786;4924.162892308316;3625.691816195653;3647.379945409287;3704.481372607837;3724.962750372516;3768.6042965488227;1145.9942700286445;1154.3824054131;1159.640511441439;1159.8840115988503;1160.6246597781656;806.9354451643854;828.4987582512614;915.9908400916039;925.0379605210505;959.9760005999906;5503.926734286776;5580.386157522669;5909.803979789306;5925.496332811673;6033.9698301508215;358.75425333646535;409.63951722484023;423.31423094586967;435.5818414322286;436.49802726863726;3493.2489514754448;3686.8340924658455;3928.2339943710704;3963.92072158561;4032.213718324957;7697.4226932980255;10453.581856725797;10564.102051325723;10640.308379955279;10688.94655526733;9371.156595906401;10594.205434592437;10594.841077383846;10598.258121931545;10606.681799546026;7860.646270917822;8076;8111.756647300473;8227.917720822836;8250.793730156674;4918.729469879191;5667.659940403589;5690.601657883908;5764.913526297025;5778.797742079007;7938.24586664266;8069.435139540175;9772.511374431107;9789.412635241908;9814.55834487464;9210.125038121369;9666.130048295687;9682.95158524231;9767.26745494105;9796.51017449139;6720.932790672031;6943.132108486439;7077.150741911004;7285.344319011315;7453.813654658677;8029.1167971524255;8146.67413303485;8261.876071859047;8273.172682731678;8278.254957053894;7282.271772822675;7363.116426028911;7390.7413240536325;7490.962545187239;7517.812054698677;7133.429325654039;7225.783226503213;7237.421006319587;7290.270972902667;7390.7413240536325;6774.220964588938;6937.7571784987285;6950.513464057467;6952.339527744856;6961.6171110587375;4764.190087685171;4876.195427754381;5048.469910659328;5049.267856160763;5090.312807770903;4451.3323001550025;4470.619997300266;4583.633309335179;4675.251959686434;4692.906141877212 21:32:26 INFO - PROCESS | 1947 | |4;prototype.html;290.8981856350277;308.72957778740476;312.5405653689077;314.41832609672093;314.8897885739991;4150.667946564279;4463.575960283772;5338.731039308288;5389.245505629215;5425.782968681257;4661;5339.184677209748;5356.062689029414;5358.410574836775;5366.221897824813;3862.2854771867155;3911.393733971239;3999.70002249831;4007.3187558115046;4090.550039495653;4045.8786236412807;4056.2293164298835;4068.125353049094;4074.22589707956;4078.3882417637296;4070.3894415837713;4076.960375104359;4083.5712250213746;4083.7141400102005;4127.947373419789;3771.773693578394;4008.198360327917;4068.877933661995;4110.938335924963;4111.403846442264;3454.3091381723757;3466.2547552276383;3472.5588880614514;3473.645278341449;3487.720982321408;5215.3741551013745;5233.005728911495;5236.586121747145;5243.082460569423;5262.631615786897;4389.517153113155;4515.887102822456;4629.745364004978;4650.95353545453;4668.976655116737;4858.46556878743;5158.845234642967;5233.848553318264;5668.8866222675315;5708.229389032469;3283.7208837248863;3322.1063993523703;3699.112213068871;3847.7883716395586;3864.3044252034624;4638.373819534387;5425.267588875491;5682.232898558683;5949.416983080796;5980.3686377993545;3823.2162406706557;3846.519185101862;3878.534575850916;3891.8248678808955;3893.805309734502;3938.258654278447;3960.32674445345;3964.949288438566;3966.2067586482817;3970.940435893464;4596.908061838812;4617.445906491175;4646.814127434933;4665.95334046662;4668.906621867543;5308.814191503279;5578.24693666358;5620.662760234399;5645.979401020434;5788.739506722123;1131.8585176852894;1189.2567145534042;1201.2492192379764;1338.7415829120596;1338.9129706569042;667.7462564225564;802.3581135091903;820.5774026376421;823.4153750836853;824.2458150791958;4737.976310118427;4945.727984960789;5035.974820125876;5081.822136225288;5211.60912931524;5200;5591.728838629366;5803.680797556175;5822.184894114827;5832.996007056369;4603.976980115078;4966.51004698589;5256.868578285497;5281.498257665516;5302.019126461617;2623.9533164131626;2767.972320276812;2832.2723138885262;3021.818690878554;3044.5737596736476;5216.426193118754;5440.564754819605;5626.240457538262;5879.559033072536;5883.794067207713;5553.916691249635;5880.617759845596;5925.377835327228;5926.733297001645;5951.5238780897425;5614.466625670555;5849.590528663038;5863;5881.382784660785;5909.2908850938165;4962.337616898235;5440.905251478131;5779.335376431596;5786.566007549366;5800.767969281182;5290.470952904679;5623.240862483676;5698.715064246689;5705.657660540381;5736.139579063174;5662.433756624305;5884.499817515388;5913.556483263858;5925.170476133431;5925.792597259076;3213.9715291106622;3559.822008899493;3755.723054161598;3793.981030094832;3873.322168620424;5793.188953546591;5797.956367853827;5801.3038435388025;5801.9709901451915;5832.096025116114;5618.662880227036;5637.098064309691;5652.293463317085;5693.28833895763;5718.714064296685;5150.40770311419;5285.312909321764;5533.474319939599;5610.943890561205;5613.719314034363;5008;5456.754446050017;5464.43171709296;5467.835964921059;5528.811305569322;5324.653897496651;5356.616305744063;5440.863978400572;5572.60991730575;5584.46947539983;4901.803927842846;5558.499735023867;5561.555075593862;5580;5586.944130558642 21:32:26 INFO - PROCESS | 1947 | |5;yui.html;673.7641825361123;717.598145038778;743.9844612104478;766.9233076692328;800.8278220182667;14701.852981470181;15450;15495.457658981937;15597.532074037768;15603.141827199499;13737.931310343434;14415.63960900978;14513.419463221458;14518.564443066698;14526.491572794954;876.0538618292245;891.4829398948626;891.5319457284912;893.5264309915755;894.8031433084743;702.5679207287507;721.4841388407287;864.1833467373335;881.2113158722939;888.1340692824509;944.759086432962;947.8625599288099;949.4682977532569;953.7567920180379;956.1538038835611;795.8607243732382;865.3769286114051;942.6323733743811;945.3902233059672;946.5220063867772;623.8845813524513;673.6497021548817;701.4108149154735;709.6061685764398;710.7299226293975;14284.428622855074;14703.411863525445;14792.334344954503;14856.18290993995;15206.08763474195;12607.429893751949;13948.930255348658;14076.366563504667;14129.858701412957;14274.64313392163;10559.04346782902;11090;11639.6508104757;11760.529578816837;12235.449404776806;3408.84660190292;3644.2893635741043;3764.4353346997896;3844.884653460401;3895.7662540247675;90320.54839725824;91928.08071919261;93869;96090.03909960881;96235.51882240614;10429.843552346721;10432.73918152037;10502.369857808557;10553.47232638365;10808.027277544901;19831.008449577464;19996.90001549983;20000.999950002442;20056.899715501622;20079.196832126552;7292.927070729331;7465.58939258346;7633.198514156044;7663;7701.537907725555;3764.9247015059545;4346.348047792856;4361.214981303332;4428.33574963758;4530.252508336088;3569.910752231215;3672.137047793766;3689.4465830125696;3698.556173259226;3703.8518459261327;665.1884700665181;673.4040374268709;675.7634827810325;680.5236334565824;699.7795694356262;615.3047056825848;658.1411258307954;661.4774328280604;666.1373521289844;678.5182520410465;6909.175922840091;8166.346692264605;8805.735827925173;8933.865992010125;9050.049744776703;585.2158108135035;599.4964230046781;618.0914056337177;620.6927570852457;624.073251221935;4297.333913243452;4404.845830395985;4428.269335559661;4433.179861725591;4479.708818926824;72936.90594641084;89028.5548572253;113426;114883;119790.20314695286;80958;84580.15419845846;86786.28385167921;104576;107948.14233359517;40344.39483407751;82561;87832;88913.55543222371;91060.08939910648;7161.534500257675;9311.627534898527;9705.17506011997;9763.560639771085;9799.265055120752;47052.7647361761;47442.52557474519;47842.04315913592;49819.25271121009;53824.46175538351;75911.8613220791;76174;76756.61621691856;76770;76820.46359072899;12199.939000304941;12469.750604987668;12481.251124932533;12552.246865188117;12830;14010.859891400956;14026.579202623938;14461.710765784836;14569.781453277998;14622.488212912496;12844.293563854078;13298.601041968757;13345.999050071478;13627.182369057888;13737.175769453865;12644.810327845275;13055.151415157985;13331.3334333281;13462.990275729164;13488.865111348761;12511.775583545832;12826.422810973716;12965.870341296466;13162.341882906008;13212.603621891358;10946.42520091818;11106;11216.383098929637;11360.375179365214;11362.829557556808;9969.700908972742;11181;11309.377984210947;11329.773404532029;11342.659720208407 21:32:26 INFO - PROCESS | 1947 | __end_tp_report 21:32:26 INFO - PROCESS | 1947 | __start_cc_report 21:32:26 INFO - PROCESS | 1947 | _x_x_mozilla_cycle_collect,356 21:32:26 INFO - PROCESS | 1947 | __end_cc_report 21:32:26 INFO - PROCESS | 1947 | __startTimestamp1477542746802__endTimestamp 21:32:26 INFO - PROCESS | 1947 | 21:32:26 INFO - PROCESS | 1947 | ------- Summary: start ------- 21:32:26 INFO - PROCESS | 1947 | Number of tests: 6 21:32:26 INFO - PROCESS | 1947 | 21:32:26 INFO - PROCESS | 1947 | [#0] dojo.html Cycles:165 Average:5698.81 Median:5995.85 stddev:707.41 (11.8%) stddev-sans-first:685.56 21:32:26 INFO - PROCESS | 1947 | Values: 3369.0 3373.9 3389.9 3397.7 3400.0 5759.7 5765.7 5777.0 5812.2 5825.4 5648.5 5787.4 5795.2 5842.6 5874.2 5161.4 5200.3 5245.2 5252.9 5261.6 4525.6 5189.7 5277.9 5278.8 5282.9 5310.1 5324.1 5328.1 5356.3 5356.3 5134.5 5211.6 5213.3 5258.9 5267.7 3742.1 4027.9 4609.8 5206.8 5215.0 3793.0 5627.0 5722.7 5730.9 5733.1 6149.7 6156.8 6169.9 6172.0 6229.8 6245.7 6275.0 6277.5 6282.9 6293.6 5836.0 5904.4 5988.6 5990.8 6033.0 5800.1 5837.9 6216.9 6243.3 6246.8 6218.1 6222.1 6236.9 6246.2 6257.0 6249.0 6272.1 6302.1 6322.2 6337.7 6137.4 6162.2 6178.8 6196.3 6204.8 5340.7 6179.7 6185.3 6205.9 6256.8 4903.3 4963.2 4983.8 4996.4 5027.6 3832.4 4484.5 4507.4 4523.1 4524.4 4502.7 5401.6 5423.4 5699.8 5729.0 5206.6 6232.1 6245.5 6249.1 6283.7 5813.8 5821.8 5825.8 5838.9 5840.1 4514.2 5379.8 5404.8 5407.5 5422.2 6245.7 6272.5 6286.0 6315.0 6318.1 6267.7 6270.1 6277.8 6287.5 6289.7 6176.3 6202.9 6240.9 6268.2 6303.4 6131.3 6184.7 6209.4 6217.6 6224.0 6035.7 6251.5 6259.2 6264.5 6273.7 6014.9 6105.5 6164.8 6178.8 6181.7 6104.7 6123.1 6125.8 6147.8 6243.3 5229.8 5898.4 6063.1 6066.3 6084.4 5055.8 5995.9 6044.2 6124.6 6205.6 5983.6 6067.3 6087.4 6098.9 6121.3 21:32:26 INFO - PROCESS | 1947 | 21:32:26 INFO - PROCESS | 1947 | [#1] ext.html Cycles:180 Average:70404.99 Median:39917.30 stddev:154567.83 (387.2%) stddev-sans-first:154912.18 21:32:26 INFO - PROCESS | 1947 | Values: 447.5 458.3 509.9 518.0 535.5 37925.4 39925.0 40104.8 42394.8 42480.5 30836.5 41983.8 42049.6 42063.0 42092.0 9270.7 9465.3 9472.9 9477.3 9487.0 9466.8 9517.2 9518.8 9520.0 9541.0 9491.6 9496.2 9518.6 9528.7 9554.1 9415.6 9487.0 9495.5 9523.8 9530.8 9078.0 9089.0 9145.4 9152.0 9172.3 39321.6 39361.0 39772.8 39900.4 39909.6 3290.4 3335.7 3514.1 3812.5 3967.9 30503.2 30925.0 31121.4 31271.5 31865.2 3196.1 3248.6 3248.8 3317.9 3363.2 493560.0 498130.0 501026.0 501045.0 501566.5 56671.7 57067.1 57090.0 57185.0 57259.0 55915.2 56671.0 57699.0 57715.4 57944.7 15306.4 15508.6 15618.2 15629.5 15640.5 11974.9 12126.3 12136.5 12185.1 12234.7 14523.4 14695.8 14744.8 14814.6 15016.0 6655.6 6729.8 6741.2 6766.5 6819.5 861.6 916.7 1059.0 1102.2 1134.9 22886.5 24751.0 26430.5 28724.0 29741.6 822.8 1014.5 1033.4 1067.5 1142.5 14845.5 15286.8 15295.4 15636.5 15668.5 628034.0 635216.0 635613.0 639397.0 640998.0 57461.1 57550.0 57618.8 57754.7 57760.0 1175.3 1191.8 1233.6 1237.3 1399.9 29319.7 30926.8 31237.7 32316.9 32606.2 55760.6 55916.2 55950.0 56086.2 56258.9 588714.0 589333.0 591740.0 592376.0 592574.0 25638.8 25721.0 25804.0 26052.1 26070.0 46663.0 47391.3 47795.0 48142.2 49044.5 33230.2 38905.2 39273.0 39777.0 40730.6 38629.8 38944.6 39097.0 40223.6 40445.0 37905.0 39056.6 39123.0 39213.6 39335.8 30954.8 34083.1 34772.1 34819.8 34876.5 23273.9 25533.4 25821.6 25939.0 26033.3 21:32:26 INFO - PROCESS | 1947 | 21:32:26 INFO - PROCESS | 1947 | [#2] jquery.html Cycles:180 Average:34335.02 Median:25250.49 stddev:96247.08 (381.2%) stddev-sans-first:96483.27 21:32:26 INFO - PROCESS | 1947 | Values: 363.1 371.8 376.2 379.7 392.7 19331.4 20560.3 20665.0 21100.0 21280.6 20450.3 20569.5 20575.8 20606.9 20956.3 4577.1 5004.0 5589.6 5893.1 5905.0 5741.1 5879.5 5883.4 5888.8 5890.1 5456.3 5721.1 5734.4 5746.9 5818.8 5493.5 5665.3 5731.1 5734.3 5740.4 4939.0 4956.5 4995.7 5026.3 5033.2 25045.9 25199.9 25301.1 25443.5 25751.0 2190.2 2309.5 2409.7 2663.5 2674.5 15316.7 15339.7 15464.0 15902.4 16236.5 1621.9 1754.1 1845.4 1872.3 1883.7 125273.4 126156.0 128378.0 129130.0 130660.3 9579.3 10182.2 10547.4 10561.5 10703.8 12993.5 13008.7 13016.2 13016.3 13031.9 8072.4 8395.7 8437.6 8528.5 8564.3 6338.0 7641.3 8381.1 8511.2 8642.7 6451.0 6496.6 6504.0 6515.8 6526.3 1547.3 1851.5 1865.5 1868.9 1877.5 714.4 881.7 886.4 892.5 965.8 20966.7 21015.0 21353.1 21689.2 22025.9 884.5 967.7 982.1 1003.2 1035.9 10892.5 11382.2 11505.3 11549.8 11602.9 565109.0 570855.0 572921.0 576783.0 590794.0 69458.7 117815.4 123444.0 126522.0 128802.0 915.6 1003.8 1014.3 1058.1 1059.9 12795.7 16616.5 19120.0 19323.0 19381.4 47550.0 50733.0 51479.7 52009.7 54968.7 80357.6 81837.0 82446.0 82637.3 82830.2 7784.3 8020.7 8087.7 8093.4 8162.4 10385.8 11095.2 12485.8 12852.9 12947.7 9582.8 10729.2 10748.0 10781.9 11078.3 9238.9 10661.4 10830.9 10882.9 11155.2 11014.4 11266.6 11407.5 11499.9 11540.5 6957.4 7485.0 7805.7 7927.0 8010.6 9878.8 10602.0 10904.3 10961.9 11093.2 21:32:26 INFO - PROCESS | 1947 | 21:32:26 INFO - PROCESS | 1947 | [#3] mootools.html Cycles:180 Average:5467.68 Median:5191.01 stddev:3058.33 (58.9%) stddev-sans-first:3052.41 21:32:26 INFO - PROCESS | 1947 | Values: 1504.1 1511.9 1538.1 1761.8 1891.5 6455.4 7132.1 7601.0 8792.3 8802.6 8248.7 8555.7 8580.9 8656.9 8692.8 1906.6 2285.7 2313.1 2321.6 2326.9 2302.1 2337.6 2366.7 2367.7 2371.4 2020.9 2199.6 2315.2 2323.8 2343.7 2295.1 2298.5 2305.0 2324.1 2331.2 1837.9 2348.5 2349.7 2367.6 2367.7 8801.5 8810.3 8841.4 8841.9 8874.4 5158.4 5321.8 5321.9 5328.3 5347.8 2785.6 3276.5 3596.6 3628.5 3645.9 957.1 970.2 977.3 1062.3 1071.4 9394.4 10858.3 11124.6 11244.8 11315.5 4340.9 4414.5 4513.1 4525.7 4537.1 8123.3 8958.7 9053.3 9068.6 9082.9 5223.6 5225.9 5227.7 5231.8 5232.2 4283.6 4739.8 4841.3 4878.5 4924.2 3625.7 3647.4 3704.5 3725.0 3768.6 1146.0 1154.4 1159.6 1159.9 1160.6 806.9 828.5 916.0 925.0 960.0 5503.9 5580.4 5909.8 5925.5 6034.0 358.8 409.6 423.3 435.6 436.5 3493.2 3686.8 3928.2 3963.9 4032.2 7697.4 10453.6 10564.1 10640.3 10688.9 9371.2 10594.2 10594.8 10598.3 10606.7 7860.6 8076.0 8111.8 8227.9 8250.8 4918.7 5667.7 5690.6 5764.9 5778.8 7938.2 8069.4 9772.5 9789.4 9814.6 9210.1 9666.1 9683.0 9767.3 9796.5 6720.9 6943.1 7077.2 7285.3 7453.8 8029.1 8146.7 8261.9 8273.2 8278.3 7282.3 7363.1 7390.7 7491.0 7517.8 7133.4 7225.8 7237.4 7290.3 7390.7 6774.2 6937.8 6950.5 6952.3 6961.6 4764.2 4876.2 5048.5 5049.3 5090.3 4451.3 4470.6 4583.6 4675.3 4692.9 21:32:26 INFO - PROCESS | 1947 | 21:32:26 INFO - PROCESS | 1947 | [#4] prototype.html Cycles:180 Average:4560.36 Median:5233.43 stddev:1416.68 (27.1%) stddev-sans-first:1383.93 21:32:26 INFO - PROCESS | 1947 | Values: 290.9 308.7 312.5 314.4 314.9 4150.7 4463.6 5338.7 5389.2 5425.8 4661.0 5339.2 5356.1 5358.4 5366.2 3862.3 3911.4 3999.7 4007.3 4090.6 4045.9 4056.2 4068.1 4074.2 4078.4 4070.4 4077.0 4083.6 4083.7 4127.9 3771.8 4008.2 4068.9 4110.9 4111.4 3454.3 3466.3 3472.6 3473.6 3487.7 5215.4 5233.0 5236.6 5243.1 5262.6 4389.5 4515.9 4629.7 4651.0 4669.0 4858.5 5158.8 5233.8 5668.9 5708.2 3283.7 3322.1 3699.1 3847.8 3864.3 4638.4 5425.3 5682.2 5949.4 5980.4 3823.2 3846.5 3878.5 3891.8 3893.8 3938.3 3960.3 3964.9 3966.2 3970.9 4596.9 4617.4 4646.8 4666.0 4668.9 5308.8 5578.2 5620.7 5646.0 5788.7 1131.9 1189.3 1201.2 1338.7 1338.9 667.7 802.4 820.6 823.4 824.2 4738.0 4945.7 5036.0 5081.8 5211.6 5200.0 5591.7 5803.7 5822.2 5833.0 4604.0 4966.5 5256.9 5281.5 5302.0 2624.0 2768.0 2832.3 3021.8 3044.6 5216.4 5440.6 5626.2 5879.6 5883.8 5553.9 5880.6 5925.4 5926.7 5951.5 5614.5 5849.6 5863.0 5881.4 5909.3 4962.3 5440.9 5779.3 5786.6 5800.8 5290.5 5623.2 5698.7 5705.7 5736.1 5662.4 5884.5 5913.6 5925.2 5925.8 3214.0 3559.8 3755.7 3794.0 3873.3 5793.2 5798.0 5801.3 5802.0 5832.1 5618.7 5637.1 5652.3 5693.3 5718.7 5150.4 5285.3 5533.5 5610.9 5613.7 5008.0 5456.8 5464.4 5467.8 5528.8 5324.7 5356.6 5440.9 5572.6 5584.5 4901.8 5558.5 5561.6 5580.0 5586.9 21:32:26 INFO - PROCESS | 1947 | 21:32:26 INFO - PROCESS | 1947 | [#5] yui.html Cycles:180 Average:20261.60 Median:4428.30 stddev:29500.64 (666.2%) stddev-sans-first:29546.73 21:32:26 INFO - PROCESS | 1947 | Values: 673.8 717.6 744.0 766.9 800.8 14701.9 15450.0 15495.5 15597.5 15603.1 13737.9 14415.6 14513.4 14518.6 14526.5 876.1 891.5 891.5 893.5 894.8 702.6 721.5 864.2 881.2 888.1 944.8 947.9 949.5 953.8 956.2 795.9 865.4 942.6 945.4 946.5 623.9 673.6 701.4 709.6 710.7 14284.4 14703.4 14792.3 14856.2 15206.1 12607.4 13948.9 14076.4 14129.9 14274.6 10559.0 11090.0 11639.7 11760.5 12235.4 3408.8 3644.3 3764.4 3844.9 3895.8 90320.5 91928.1 93869.0 96090.0 96235.5 10429.8 10432.7 10502.4 10553.5 10808.0 19831.0 19996.9 20001.0 20056.9 20079.2 7292.9 7465.6 7633.2 7663.0 7701.5 3764.9 4346.3 4361.2 4428.3 4530.3 3569.9 3672.1 3689.4 3698.6 3703.9 665.2 673.4 675.8 680.5 699.8 615.3 658.1 661.5 666.1 678.5 6909.2 8166.3 8805.7 8933.9 9050.0 585.2 599.5 618.1 620.7 624.1 4297.3 4404.8 4428.3 4433.2 4479.7 72936.9 89028.6 113426.0 114883.0 119790.2 80958.0 84580.2 86786.3 104576.0 107948.1 40344.4 82561.0 87832.0 88913.6 91060.1 7161.5 9311.6 9705.2 9763.6 9799.3 47052.8 47442.5 47842.0 49819.3 53824.5 75911.9 76174.0 76756.6 76770.0 76820.5 12199.9 12469.8 12481.3 12552.2 12830.0 14010.9 14026.6 14461.7 14569.8 14622.5 12844.3 13298.6 13346.0 13627.2 13737.2 12644.8 13055.2 13331.3 13463.0 13488.9 12511.8 12826.4 12965.9 13162.3 13212.6 10946.4 11106.0 11216.4 11360.4 11362.8 9969.7 11181.0 11309.4 11329.8 11342.7 21:32:26 INFO - PROCESS | 1947 | -------- Summary: end -------- 21:32:26 INFO - PROCESS | 1947 | 21:32:27 INFO - TEST-INFO | 1947: exit 0 21:32:27 INFO - TEST-OK | dromaeo_css | took 1116237ms 21:32:27 INFO - TEST-START | kraken 21:32:27 INFO - operating with platform_type : mac_ 21:32:27 INFO - Initialising browser for kraken test... 21:32:27 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpQp41aZ/profile http://localhost:54514/getInfo.html) 21:32:30 INFO - PROCESS | 1954 | 2016-10-26 21:32:30.134 plugin-container[1955:18654] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 21:32:30 INFO - PROCESS | 1954 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:32:30 INFO - PROCESS | 1954 | 2016-10-26 21:32:30.138 plugin-container[1955:18654] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 21:32:30 INFO - PROCESS | 1954 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:32:30 INFO - PROCESS | 1954 | 2016-10-26 21:32:30.138 plugin-container[1955:18654] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:32:30 INFO - PROCESS | 1954 | __metrics Screen width/height:1600/1200 21:32:30 INFO - PROCESS | 1954 | colorDepth:24 21:32:30 INFO - PROCESS | 1954 | Browser inner width/height: 1024/689 21:32:30 INFO - PROCESS | 1954 | __metrics 21:32:30 ERROR - PROCESS | 1954 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 21:32:31 INFO - TEST-INFO | 1954: exit 0 21:32:31 INFO - Browser initialized. 21:32:31 INFO - Running cycle 1/1 for kraken test... 21:32:31 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpQp41aZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:32:33 INFO - PROCESS | 1956 | 2016-10-26 21:32:33.587 plugin-container[1957:18763] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 21:32:33 INFO - PROCESS | 1956 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:32:33 INFO - PROCESS | 1956 | 2016-10-26 21:32:33.589 plugin-container[1957:18763] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 21:32:33 INFO - PROCESS | 1956 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:32:33 INFO - PROCESS | 1956 | 2016-10-26 21:32:33.589 plugin-container[1957:18763] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:32:43 INFO - PROCESS | 1956 | RSS: Main: 236552192 21:32:43 INFO - PROCESS | 1956 | 21:32:46 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54514/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54514/tests/kraken/driver.html?testName=audio-beat-detection) 21:32:47 INFO - PROCESS | 1956 | RSS: Main: 248758272 21:32:47 INFO - PROCESS | 1956 | 21:32:49 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54514/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54514/tests/kraken/driver.html?testName=audio-dft) 21:32:50 INFO - PROCESS | 1956 | RSS: Main: 256307200 21:32:50 INFO - PROCESS | 1956 | 21:32:53 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54514/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54514/tests/kraken/driver.html?testName=audio-fft) 21:32:54 INFO - PROCESS | 1956 | RSS: Main: 259379200 21:32:54 INFO - PROCESS | 1956 | 21:32:56 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54514/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54514/tests/kraken/driver.html?testName=audio-oscillator) 21:32:57 INFO - PROCESS | 1956 | RSS: Main: 260489216 21:32:57 INFO - PROCESS | 1956 | 21:32:59 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54514/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54514/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:32:59 INFO - PROCESS | 1956 | RSS: Main: 262127616 21:32:59 INFO - PROCESS | 1956 | 21:33:02 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54514/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54514/tests/kraken/driver.html?testName=imaging-darkroom) 21:33:03 INFO - PROCESS | 1956 | RSS: Main: 262844416 21:33:03 INFO - PROCESS | 1956 | 21:33:06 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54514/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54514/tests/kraken/driver.html?testName=imaging-desaturate) 21:33:07 INFO - PROCESS | 1956 | RSS: Main: 263069696 21:33:07 INFO - PROCESS | 1956 | 21:33:10 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54514/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54514/tests/kraken/driver.html?testName=json-parse-financial) 21:33:10 INFO - PROCESS | 1956 | RSS: Main: 263311360 21:33:10 INFO - PROCESS | 1956 | 21:33:12 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54514/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54514/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:33:12 INFO - PROCESS | 1956 | RSS: Main: 263163904 21:33:12 INFO - PROCESS | 1956 | 21:33:14 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54514/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54514/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:33:15 INFO - PROCESS | 1956 | RSS: Main: 263454720 21:33:15 INFO - PROCESS | 1956 | 21:33:17 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54514/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54514/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:33:18 INFO - PROCESS | 1956 | RSS: Main: 263225344 21:33:18 INFO - PROCESS | 1956 | 21:33:21 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54514/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54514/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:33:22 INFO - PROCESS | 1956 | RSS: Main: 263413760 21:33:22 INFO - PROCESS | 1956 | 21:33:25 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54514/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54514/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:33:25 INFO - PROCESS | 1956 | RSS: Main: 263385088 21:33:25 INFO - PROCESS | 1956 | 21:33:27 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54514/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54514/tests/kraken/driver.html?testName=ai-astar) 21:33:27 INFO - PROCESS | 1956 | RSS: Main: 264859648 21:33:27 INFO - PROCESS | 1956 | 21:33:27 INFO - PROCESS | 1956 | __start_tp_report 21:33:27 INFO - PROCESS | 1956 | _x_x_mozilla_page_load 21:33:27 INFO - PROCESS | 1956 | _x_x_mozilla_page_load_details 21:33:27 INFO - PROCESS | 1956 | |i|pagename|runs| 21:33:27 INFO - PROCESS | 1956 | |0;ai-astar;104;119;117;117;117;117;117;117;116;120 21:33:27 INFO - PROCESS | 1956 | |1;audio-beat-detection;143;116;129;183;144;113;117;115;145;120 21:33:27 INFO - PROCESS | 1956 | |2;audio-dft;201;216;270;277;205;203;195;206;196;196 21:33:27 INFO - PROCESS | 1956 | |3;audio-fft;80;58;57;87;60;61;62;58;80;58 21:33:27 INFO - PROCESS | 1956 | |4;audio-oscillator;91;71;86;72;68;69;89;69;72;88 21:33:27 INFO - PROCESS | 1956 | |5;imaging-gaussian-blur;89;87;89;74;134;130;121;131;135;86 21:33:27 INFO - PROCESS | 1956 | |6;imaging-darkroom;151;95;149;153;95;121;151;147;94;104 21:33:27 INFO - PROCESS | 1956 | |7;imaging-desaturate;88;87;89;86;90;89;123;144;139;101 21:33:27 INFO - PROCESS | 1956 | |8;json-parse-financial;59;58;56;56;57;58;56;59;59;58 21:33:27 INFO - PROCESS | 1956 | |9;json-stringify-tinderbox;67;76;72;76;75;68;63;66;72;72 21:33:27 INFO - PROCESS | 1956 | |10;stanford-crypto-aes;85;88;89;91;87;92;92;89;88;88 21:33:27 INFO - PROCESS | 1956 | |11;stanford-crypto-ccm;178;166;163;143;147;146;163;168;214;150 21:33:27 INFO - PROCESS | 1956 | |12;stanford-crypto-pbkdf2;187;179;154;196;188;206;243;166;160;226 21:33:27 INFO - PROCESS | 1956 | |13;stanford-crypto-sha256-iterative;70;71;69;72;65;81;84;90;79;62 21:33:27 INFO - PROCESS | 1956 | __end_tp_report 21:33:27 INFO - PROCESS | 1956 | __start_cc_report 21:33:27 INFO - PROCESS | 1956 | _x_x_mozilla_cycle_collect,957 21:33:27 INFO - PROCESS | 1956 | __end_cc_report 21:33:27 INFO - PROCESS | 1956 | __startTimestamp1477542807811__endTimestamp 21:33:27 INFO - PROCESS | 1956 | 21:33:27 INFO - PROCESS | 1956 | ------- Summary: start ------- 21:33:27 INFO - PROCESS | 1956 | Number of tests: 14 21:33:27 INFO - PROCESS | 1956 | 21:33:27 INFO - PROCESS | 1956 | [#0] ai-astar Cycles:10 Average:116.10 Median:117.00 stddev:4.41 (3.8%) stddev-sans-first:1.24 21:33:27 INFO - PROCESS | 1956 | Values: 104.0 119.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 120.0 21:33:27 INFO - PROCESS | 1956 | 21:33:27 INFO - PROCESS | 1956 | [#1] audio-beat-detection Cycles:10 Average:132.50 Median:136.00 stddev:21.90 (16.1%) stddev-sans-first:22.90 21:33:27 INFO - PROCESS | 1956 | Values: 143.0 116.0 129.0 183.0 144.0 113.0 117.0 115.0 145.0 120.0 21:33:27 INFO - PROCESS | 1956 | 21:33:27 INFO - PROCESS | 1956 | [#2] audio-dft Cycles:10 Average:216.50 Median:205.50 stddev:30.71 (14.9%) stddev-sans-first:32.06 21:33:27 INFO - PROCESS | 1956 | Values: 201.0 216.0 270.0 277.0 205.0 203.0 195.0 206.0 196.0 196.0 21:33:27 INFO - PROCESS | 1956 | 21:33:27 INFO - PROCESS | 1956 | [#3] audio-fft Cycles:10 Average:66.10 Median:61.50 stddev:11.46 (18.6%) stddev-sans-first:11.00 21:33:27 INFO - PROCESS | 1956 | Values: 80.0 58.0 57.0 87.0 60.0 61.0 62.0 58.0 80.0 58.0 21:33:27 INFO - PROCESS | 1956 | 21:33:27 INFO - PROCESS | 1956 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:79.00 stddev:9.63 (12.2%) stddev-sans-first:8.89 21:33:27 INFO - PROCESS | 1956 | Values: 91.0 71.0 86.0 72.0 68.0 69.0 89.0 69.0 72.0 88.0 21:33:27 INFO - PROCESS | 1956 | 21:33:27 INFO - PROCESS | 1956 | [#5] imaging-gaussian-blur Cycles:10 Average:107.60 Median:80.00 stddev:24.47 (30.6%) stddev-sans-first:25.01 21:33:27 INFO - PROCESS | 1956 | Values: 89.0 87.0 89.0 74.0 134.0 130.0 121.0 131.0 135.0 86.0 21:33:27 INFO - PROCESS | 1956 | 21:33:27 INFO - PROCESS | 1956 | [#6] imaging-darkroom Cycles:10 Average:126.00 Median:152.00 stddev:26.67 (17.5%) stddev-sans-first:26.72 21:33:27 INFO - PROCESS | 1956 | Values: 151.0 95.0 149.0 153.0 95.0 121.0 151.0 147.0 94.0 104.0 21:33:27 INFO - PROCESS | 1956 | 21:33:27 INFO - PROCESS | 1956 | [#7] imaging-desaturate Cycles:10 Average:103.60 Median:87.50 stddev:22.87 (26.1%) stddev-sans-first:23.55 21:33:27 INFO - PROCESS | 1956 | Values: 88.0 87.0 89.0 86.0 90.0 89.0 123.0 144.0 139.0 101.0 21:33:27 INFO - PROCESS | 1956 | 21:33:27 INFO - PROCESS | 1956 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:58.00 stddev:1.26 (2.2%) stddev-sans-first:1.24 21:33:27 INFO - PROCESS | 1956 | Values: 59.0 58.0 56.0 56.0 57.0 58.0 56.0 59.0 59.0 58.0 21:33:27 INFO - PROCESS | 1956 | 21:33:27 INFO - PROCESS | 1956 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:72.00 stddev:4.50 (6.2%) stddev-sans-first:4.57 21:33:27 INFO - PROCESS | 1956 | Values: 67.0 76.0 72.0 76.0 75.0 68.0 63.0 66.0 72.0 72.0 21:33:27 INFO - PROCESS | 1956 | 21:33:27 INFO - PROCESS | 1956 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:89.00 stddev:2.23 (2.5%) stddev-sans-first:1.87 21:33:27 INFO - PROCESS | 1956 | Values: 85.0 88.0 89.0 91.0 87.0 92.0 92.0 89.0 88.0 88.0 21:33:27 INFO - PROCESS | 1956 | 21:33:27 INFO - PROCESS | 1956 | [#11] stanford-crypto-ccm Cycles:10 Average:163.80 Median:164.50 stddev:21.00 (12.8%) stddev-sans-first:21.63 21:33:27 INFO - PROCESS | 1956 | Values: 178.0 166.0 163.0 143.0 147.0 146.0 163.0 168.0 214.0 150.0 21:33:27 INFO - PROCESS | 1956 | 21:33:27 INFO - PROCESS | 1956 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.50 Median:192.00 stddev:28.48 (14.8%) stddev-sans-first:30.18 21:33:27 INFO - PROCESS | 1956 | Values: 187.0 179.0 154.0 196.0 188.0 206.0 243.0 166.0 160.0 226.0 21:33:27 INFO - PROCESS | 1956 | 21:33:27 INFO - PROCESS | 1956 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.30 Median:75.50 stddev:8.87 (11.7%) stddev-sans-first:9.27 21:33:27 INFO - PROCESS | 1956 | Values: 70.0 71.0 69.0 72.0 65.0 81.0 84.0 90.0 79.0 62.0 21:33:27 INFO - PROCESS | 1956 | -------- Summary: end -------- 21:33:27 INFO - PROCESS | 1956 | 21:33:28 INFO - TEST-INFO | 1956: exit 0 21:33:28 INFO - TEST-OK | kraken | took 61186ms 21:33:28 INFO - SUITE-END | took 1177s 21:33:28 INFO - Completed test suite (00:19:37) 21:33:28 INFO - javascript benchmark 21:33:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7190.929656661828, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3368.9663103368975, 3373.9203454894437, 3389.898303050908, 3397.7451691123174, 3399.983000085, 5759.654420734753, 5765.711714414283, 5776.9711151444235, 5812.186293918855, 5825.417458254173, 5648.4633959773855, 5787.421257874211, 5795.217645617851, 5842.591018628697, 5874.236349274599, 5161.380634323887, 5200.297959775438, 5245.160774276116, 5252.8686782830355, 5261.579073674116, 4525.592696657301, 5189.714565698885, 5277.8944421111555, 5278.788848446057, 5282.894342113155, 5310.070737620901, 5324.1215199492035, 5328.1474964005765, 5356.250124982505, 5356.250124982505, 5134.460881607415, 5211.557017653504, 5213.32226810516, 5258.92111618326, 5267.710275934822, 3742.1018955450495, 4027.8590249341423, 4609.778408721691, 5206.76569554371, 5214.97392513035, 3792.9758965079272, 5626.987142314422, 5722.713864306809, 5730.885382292373, 5733.140028995643, 6149.739303442783, 6156.78451254204, 6169.907451388232, 6172.0, 6229.813105606839, 6245.718942647592, 6274.968625156891, 6277.497800175975, 6282.937170628281, 6293.622382657009, 5835.976258413481, 5904.350521442638, 5988.610740301867, 5990.820275391745, 6033.0347144457555, 5800.1299805029585, 5837.883242335214, 6216.875662486725, 6243.250809902841, 6246.812595622138, 6218.098375735493, 6222.12890195373, 6236.875262494725, 6246.187995560548, 6257.0, 6249.0, 6272.1219029335925, 6302.086197501429, 6322.209723784527, 6337.65142917144, 6137.386261373917, 6162.168107305456, 6178.783742568989, 6196.349383314687, 6204.844878878064, 5340.732963351817, 6179.6910154493, 6185.288691800411, 6205.875882482325, 6256.781012664626, 4903.313536104948, 4963.156263435225, 4983.750812459362, 4996.375453068366, 5027.648064635514, 3832.4251362295877, 4484.529124441952, 4507.3689683444345, 4523.072770082125, 4524.434445694288, 4502.684812063124, 5401.567874570025, 5423.430539793344, 5699.772009119672, 5729.0, 5206.635535512478, 6232.127502149703, 6245.500359971192, 6249.0626406039455, 6283.717232724538, 5813.79651712188, 5821.767129314865, 5825.766969321265, 5838.9124163137585, 5840.094785308284, 4514.210013247669, 5379.78480860769, 5404.8108316208745, 5407.540359069523, 5422.240886275925, 6245.656488893154, 6272.466840318532, 6285.96857015712, 6315.0, 6318.08387783778, 6267.655278959701, 6270.0908368287255, 6277.780277690259, 6287.497000240061, 6289.716962736779, 6176.258848937977, 6202.875942481215, 6240.937590624218, 6268.153799237045, 6303.432691057827, 6131.315499378873, 6184.6598437086395, 6209.441150296495, 6217.595856269328, 6224.035274532454, 6035.668038257938, 6251.499880009679, 6259.155014073224, 6264.49884009269, 6273.749050037947, 6014.879702406015, 6105.459747277989, 6164.7842325518395, 6178.814635560941, 6181.660008699565, 6104.664243466652, 6123.099607468126, 6125.785597504244, 6147.846303842261, 6243.344448832807, 5229.823289759774, 5898.4101589840675, 6063.090536419572, 6066.331758766374, 6084.39156084388, 5055.797768089235, 5995.850103747442, 6044.184035155197, 6124.6325220486915, 6205.565610407409, 5983.640981541122, 6067.302260240129, 6087.360827113266, 6098.878022439438, 6121.326654068138], "value": 5654.133831854748, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [447.46080972428246, 458.26219786144304, 509.8699831542956, 517.9715115668638, 535.4966331648253, 37925.43111853321, 39925.0, 40104.79947600261, 42394.788026059825, 42480.51318203864, 30836.53745193824, 41983.79008104955, 42049.57950420495, 42063.0, 42092.0, 9270.721878343644, 9465.337426380156, 9472.857907131398, 9477.336586438953, 9486.956434792168, 9466.81066378672, 9517.23862091031, 9518.762030949247, 9520.047995200459, 9540.952295238514, 9491.572879220417, 9496.192823610001, 9518.619255229818, 9528.666496672618, 9554.140127388533, 9415.576299066559, 9486.9525652372, 9495.52522373882, 9523.809523809485, 9530.809383812355, 9077.954610226972, 9089.0, 9145.405548639385, 9152.039035901267, 9172.312076594215, 39321.60678393208, 39361.0, 39772.80113599414, 39900.40149397762, 39909.60090399088, 3290.3912776136253, 3335.666433356669, 3514.139035936212, 3812.485314482537, 3967.9484936491854, 30503.20074518899, 30925.0, 31121.377572448426, 31271.530927036114, 31865.203369915707, 3196.1210667066744, 3248.6101667800017, 3248.805071695706, 3317.883874064396, 3363.226457914669, 493560.0, 498130.0, 501026.0, 501045.0, 501566.46083848196, 56671.716641416526, 57067.14399284014, 57090.001849935055, 57185.0, 57259.0, 55915.16127258095, 56671.0, 57699.0, 57715.42284577184, 57944.71027644835, 15306.38774449032, 15508.612284692746, 15618.219089045502, 15629.531114066596, 15640.530784076496, 11974.940125299492, 12126.333051682243, 12136.45385957634, 12185.147039707135, 12234.694132646755, 14523.419063237563, 14695.77956330656, 14744.778828317583, 14814.555563333117, 15016.0, 6655.567388119756, 6729.831754206086, 6741.22475915276, 6766.458683305422, 6819.454443644497, 861.6208868098017, 916.6745805239177, 1059.0309866472246, 1102.2284400919389, 1134.9388321919032, 22886.542269154525, 24751.0, 26430.471390572464, 28724.0, 29741.553876691865, 822.7984143884667, 1014.4623349624711, 1033.4161198922584, 1067.5196161727254, 1142.4915912418894, 14845.480408185662, 15286.783416404782, 15295.388184472718, 15636.530904072895, 15668.529944101696, 628034.0, 635216.0, 635613.0, 639397.0, 640998.0, 57461.13808292795, 57550.0, 57618.8476230473, 57754.7112264436, 57760.0, 1175.306569124221, 1191.79628575138, 1233.6114124050896, 1237.270010693695, 1399.8530154333648, 29319.706802931698, 30926.845365773028, 31237.68762312348, 32316.86890958805, 32606.184845379055, 55760.60598485071, 55916.16125758198, 55950.0, 56086.158707620234, 56258.87482250414, 588714.0, 589333.0, 591740.0, 592376.0, 592574.0, 25638.846251918334, 25720.97116115409, 25804.0, 26052.088176913716, 26070.0, 46663.0, 47391.289130663754, 47795.04409911852, 48142.22293324823, 49044.50955490399, 33230.16924576905, 38905.249263783764, 39273.0, 39777.00557486086, 40730.59269407268, 38629.806850965564, 38944.61055389523, 39097.0, 40223.597764021986, 40445.0, 37905.0, 39056.609433905294, 39123.0, 39213.60786392214, 39335.803320983214, 30954.845225773726, 34083.14792130118, 34772.130696732784, 34819.82590087135, 34876.476852847736, 23273.883630582415, 25533.3616659585, 25821.612675810255, 25939.0, 26033.349166270993], "value": 19590.15072347655, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.09226932668344, 371.79922841665496, 376.17242067451616, 379.71900793412874, 392.6995848176144, 19331.42005739828, 20560.280390186348, 20665.0, 21099.99999999998, 21280.57438851222, 20450.284240051602, 20569.485762855937, 20575.794242057575, 20606.896965515152, 20956.26653067143, 4577.08458308338, 5004.0, 5589.608727389094, 5893.086571581412, 5905.0, 5741.110127930177, 5879.52963762898, 5883.382244864292, 5888.793892213774, 5890.087036509327, 5456.290682211307, 5721.141828725683, 5734.397888221739, 5746.913796293059, 5818.8254352369495, 5493.478119578674, 5665.263515742948, 5731.1116776899435, 5734.340550836624, 5740.368559458477, 4938.9753051234875, 4956.479569645207, 4995.725235112068, 5026.321446604698, 5033.219850923113, 25045.87477062621, 25199.87400063006, 25301.114460993776, 25443.491130177477, 25751.0, 2190.244365693849, 2309.5265470578493, 2409.6987876515436, 2663.5072511585417, 2674.5185866544016, 15316.693666126726, 15339.693206135926, 15464.0, 15902.363905443768, 16236.51290461288, 1621.9107949062798, 1754.1317048061292, 1845.4371416717895, 1872.2604571194438, 1883.660941030614, 125273.37363313216, 126156.0, 128378.0, 129130.0, 130660.34669826685, 9579.329446938787, 10182.23633227511, 10547.419891906018, 10561.524731386953, 10703.78592428147, 12993.545225917236, 13008.739825203444, 13016.219026858418, 13016.284104374161, 13031.869681303066, 8072.434929554992, 8395.70615028471, 8437.620307086196, 8528.488290702579, 8564.3148548116, 6337.96831015842, 7641.2740789624895, 8381.119982401882, 8511.233988941025, 8642.660387639926, 6450.967745161244, 6496.610203387811, 6504.0, 6515.784633224726, 6526.314736952551, 1547.257316488092, 1851.453821122772, 1865.5242913057084, 1868.9439316820517, 1877.5306173456636, 714.4213187318185, 881.7090360181124, 886.4061079077034, 892.4734406700078, 965.8361674182593, 20966.685499717518, 21015.0, 21353.14587416517, 21689.240876569245, 22025.898705064683, 884.4648987362682, 967.6806653804227, 982.0866594067586, 1003.2024540490328, 1035.9067683908484, 10892.509837057352, 11382.219273345878, 11505.309681419141, 11549.76900461986, 11602.94198529002, 565109.0, 570855.0, 572921.0, 576783.0, 590794.0, 69458.65270673615, 117815.41092294484, 123444.0, 126522.0, 128802.0, 915.555955361649, 1003.7841863999129, 1014.2697257974247, 1058.074185088048, 1059.89930956559, 12795.680107997374, 16616.50150495487, 19120.0, 19323.0, 19381.449484041525, 47550.04899902052, 50733.0, 51479.74260128675, 52009.7399513, 54968.72515637396, 80357.59821200857, 81837.0, 82446.0, 82637.3472530558, 82830.17169828466, 7784.260495252942, 8020.679172833022, 8087.716929907425, 8093.367367287652, 8162.428629995843, 10385.844212336971, 11095.223334366518, 12485.812712809133, 12852.935735321264, 12947.741045179233, 9582.760430989281, 10729.195310351912, 10748.0, 10781.9460902695, 11078.279911805706, 9238.907610923805, 10661.413622250851, 10830.945845271039, 10882.94558527234, 11155.16336274799, 11014.39420831862, 11266.60566880188, 11407.543698251977, 11499.885001150216, 11540.480678369662, 6957.443404527727, 7484.962575187307, 7805.687772489264, 7926.960365198137, 8010.639521221676, 9878.75303117399, 10601.985622731656, 10904.291221070605, 10961.903809618974, 11093.22347435704], "value": 9547.993098320752, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1504.1276059885267, 1511.9319630616621, 1538.0848395204853, 1761.7667632657144, 1891.540996704285, 6455.419012288899, 7132.14414270287, 7600.96199519003, 8792.34057445691, 8802.647894084237, 8248.711295104673, 8555.743327700164, 8580.871286930702, 8656.8701469478, 8692.826143477127, 1906.5805522784965, 2285.6685780561806, 2313.1325752842686, 2321.628539433691, 2326.8836558172106, 2302.067662596651, 2337.602607556711, 2366.668666386707, 2367.6566897799808, 2371.3834403055243, 2020.8686435381726, 2199.59307528107, 2315.235972129193, 2323.8024767894663, 2343.7187537495524, 2295.139322753959, 2298.471351589144, 2305.0318866076286, 2324.0819876148994, 2331.172433786007, 1837.926482940694, 2348.483333666608, 2349.682792822964, 2367.621180611111, 2367.6803631509697, 8801.515916624583, 8810.339224558184, 8841.38110332277, 8841.867371989425, 8874.378793484459, 5158.4325724170685, 5321.840344789663, 5321.893562128775, 5328.254044433782, 5347.812826551052, 2785.554311310201, 3276.459384201604, 3596.5684117906017, 3628.4738712886747, 3645.8541658333634, 957.1242313283337, 970.1656575345197, 977.2768151567859, 1062.3360399750156, 1071.405370019639, 9394.389364691273, 10858.294210876267, 11124.555017799279, 11244.831327530093, 11315.490802913806, 4340.891477713019, 4414.4702635683925, 4513.052259025641, 4525.705829121097, 4537.069900670354, 8123.3095186908595, 8958.731238062868, 9053.275737940949, 9068.591913363915, 9082.909170908339, 5223.582113430916, 5225.947740522623, 5227.738613069331, 5231.843044708665, 5232.189010703271, 4283.60782745609, 4739.810407583727, 4841.346418233564, 4878.536539028786, 4924.162892308316, 3625.691816195653, 3647.379945409287, 3704.481372607837, 3724.962750372516, 3768.6042965488227, 1145.9942700286445, 1154.3824054131, 1159.640511441439, 1159.8840115988503, 1160.6246597781656, 806.9354451643854, 828.4987582512614, 915.9908400916039, 925.0379605210505, 959.9760005999906, 5503.926734286776, 5580.386157522669, 5909.803979789306, 5925.496332811673, 6033.9698301508215, 358.75425333646535, 409.63951722484023, 423.31423094586967, 435.5818414322286, 436.49802726863726, 3493.2489514754448, 3686.8340924658455, 3928.2339943710704, 3963.92072158561, 4032.213718324957, 7697.4226932980255, 10453.581856725797, 10564.102051325723, 10640.308379955279, 10688.94655526733, 9371.156595906401, 10594.205434592437, 10594.841077383846, 10598.258121931545, 10606.681799546026, 7860.646270917822, 8076.0, 8111.756647300473, 8227.917720822836, 8250.793730156674, 4918.729469879191, 5667.659940403589, 5690.601657883908, 5764.913526297025, 5778.797742079007, 7938.24586664266, 8069.435139540175, 9772.511374431107, 9789.412635241908, 9814.55834487464, 9210.125038121369, 9666.130048295687, 9682.95158524231, 9767.26745494105, 9796.51017449139, 6720.932790672031, 6943.132108486439, 7077.150741911004, 7285.344319011315, 7453.813654658677, 8029.1167971524255, 8146.67413303485, 8261.876071859047, 8273.172682731678, 8278.254957053894, 7282.271772822675, 7363.116426028911, 7390.7413240536325, 7490.962545187239, 7517.812054698677, 7133.429325654039, 7225.783226503213, 7237.421006319587, 7290.270972902667, 7390.7413240536325, 6774.220964588938, 6937.7571784987285, 6950.513464057467, 6952.339527744856, 6961.6171110587375, 4764.190087685171, 4876.195427754381, 5048.469910659328, 5049.267856160763, 5090.312807770903, 4451.3323001550025, 4470.619997300266, 4583.633309335179, 4675.251959686434, 4692.906141877212], "value": 4324.382690583554, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.8981856350277, 308.72957778740476, 312.5405653689077, 314.41832609672093, 314.8897885739991, 4150.667946564279, 4463.575960283772, 5338.731039308288, 5389.245505629215, 5425.782968681257, 4661.0, 5339.184677209748, 5356.062689029414, 5358.410574836775, 5366.221897824813, 3862.2854771867155, 3911.393733971239, 3999.70002249831, 4007.3187558115046, 4090.550039495653, 4045.8786236412807, 4056.2293164298835, 4068.125353049094, 4074.22589707956, 4078.3882417637296, 4070.3894415837713, 4076.960375104359, 4083.5712250213746, 4083.7141400102005, 4127.947373419789, 3771.773693578394, 4008.198360327917, 4068.877933661995, 4110.938335924963, 4111.403846442264, 3454.3091381723757, 3466.2547552276383, 3472.5588880614514, 3473.645278341449, 3487.720982321408, 5215.3741551013745, 5233.005728911495, 5236.586121747145, 5243.082460569423, 5262.631615786897, 4389.517153113155, 4515.887102822456, 4629.745364004978, 4650.95353545453, 4668.976655116737, 4858.46556878743, 5158.845234642967, 5233.848553318264, 5668.8866222675315, 5708.229389032469, 3283.7208837248863, 3322.1063993523703, 3699.112213068871, 3847.7883716395586, 3864.3044252034624, 4638.373819534387, 5425.267588875491, 5682.232898558683, 5949.416983080796, 5980.3686377993545, 3823.2162406706557, 3846.519185101862, 3878.534575850916, 3891.8248678808955, 3893.805309734502, 3938.258654278447, 3960.32674445345, 3964.949288438566, 3966.2067586482817, 3970.940435893464, 4596.908061838812, 4617.445906491175, 4646.814127434933, 4665.95334046662, 4668.906621867543, 5308.814191503279, 5578.24693666358, 5620.662760234399, 5645.979401020434, 5788.739506722123, 1131.8585176852894, 1189.2567145534042, 1201.2492192379764, 1338.7415829120596, 1338.9129706569042, 667.7462564225564, 802.3581135091903, 820.5774026376421, 823.4153750836853, 824.2458150791958, 4737.976310118427, 4945.727984960789, 5035.974820125876, 5081.822136225288, 5211.60912931524, 5200.0, 5591.728838629366, 5803.680797556175, 5822.184894114827, 5832.996007056369, 4603.976980115078, 4966.51004698589, 5256.868578285497, 5281.498257665516, 5302.019126461617, 2623.9533164131626, 2767.972320276812, 2832.2723138885262, 3021.818690878554, 3044.5737596736476, 5216.426193118754, 5440.564754819605, 5626.240457538262, 5879.559033072536, 5883.794067207713, 5553.916691249635, 5880.617759845596, 5925.377835327228, 5926.733297001645, 5951.5238780897425, 5614.466625670555, 5849.590528663038, 5863.0, 5881.382784660785, 5909.2908850938165, 4962.337616898235, 5440.905251478131, 5779.335376431596, 5786.566007549366, 5800.767969281182, 5290.470952904679, 5623.240862483676, 5698.715064246689, 5705.657660540381, 5736.139579063174, 5662.433756624305, 5884.499817515388, 5913.556483263858, 5925.170476133431, 5925.792597259076, 3213.9715291106622, 3559.822008899493, 3755.723054161598, 3793.981030094832, 3873.322168620424, 5793.188953546591, 5797.956367853827, 5801.3038435388025, 5801.9709901451915, 5832.096025116114, 5618.662880227036, 5637.098064309691, 5652.293463317085, 5693.28833895763, 5718.714064296685, 5150.40770311419, 5285.312909321764, 5533.474319939599, 5610.943890561205, 5613.719314034363, 5008.0, 5456.754446050017, 5464.43171709296, 5467.835964921059, 5528.811305569322, 5324.653897496651, 5356.616305744063, 5440.863978400572, 5572.60991730575, 5584.46947539983, 4901.803927842846, 5558.499735023867, 5561.555075593862, 5580.0, 5586.944130558642], "value": 4093.057301018459, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.7641825361123, 717.598145038778, 743.9844612104478, 766.9233076692328, 800.8278220182667, 14701.852981470181, 15450.0, 15495.457658981937, 15597.532074037768, 15603.141827199499, 13737.931310343434, 14415.63960900978, 14513.419463221458, 14518.564443066698, 14526.491572794954, 876.0538618292245, 891.4829398948626, 891.5319457284912, 893.5264309915755, 894.8031433084743, 702.5679207287507, 721.4841388407287, 864.1833467373335, 881.2113158722939, 888.1340692824509, 944.759086432962, 947.8625599288099, 949.4682977532569, 953.7567920180379, 956.1538038835611, 795.8607243732382, 865.3769286114051, 942.6323733743811, 945.3902233059672, 946.5220063867772, 623.8845813524513, 673.6497021548817, 701.4108149154735, 709.6061685764398, 710.7299226293975, 14284.428622855074, 14703.411863525445, 14792.334344954503, 14856.18290993995, 15206.08763474195, 12607.429893751949, 13948.930255348658, 14076.366563504667, 14129.858701412957, 14274.64313392163, 10559.04346782902, 11090.0, 11639.6508104757, 11760.529578816837, 12235.449404776806, 3408.84660190292, 3644.2893635741043, 3764.4353346997896, 3844.884653460401, 3895.7662540247675, 90320.54839725824, 91928.08071919261, 93869.0, 96090.03909960881, 96235.51882240614, 10429.843552346721, 10432.73918152037, 10502.369857808557, 10553.47232638365, 10808.027277544901, 19831.008449577464, 19996.90001549983, 20000.999950002442, 20056.899715501622, 20079.196832126552, 7292.927070729331, 7465.58939258346, 7633.198514156044, 7663.0, 7701.537907725555, 3764.9247015059545, 4346.348047792856, 4361.214981303332, 4428.33574963758, 4530.252508336088, 3569.910752231215, 3672.137047793766, 3689.4465830125696, 3698.556173259226, 3703.8518459261327, 665.1884700665181, 673.4040374268709, 675.7634827810325, 680.5236334565824, 699.7795694356262, 615.3047056825848, 658.1411258307954, 661.4774328280604, 666.1373521289844, 678.5182520410465, 6909.175922840091, 8166.346692264605, 8805.735827925173, 8933.865992010125, 9050.049744776703, 585.2158108135035, 599.4964230046781, 618.0914056337177, 620.6927570852457, 624.073251221935, 4297.333913243452, 4404.845830395985, 4428.269335559661, 4433.179861725591, 4479.708818926824, 72936.90594641084, 89028.5548572253, 113426.0, 114883.0, 119790.20314695286, 80958.0, 84580.15419845846, 86786.28385167921, 104576.0, 107948.14233359517, 40344.39483407751, 82561.0, 87832.0, 88913.55543222371, 91060.08939910648, 7161.534500257675, 9311.627534898527, 9705.17506011997, 9763.560639771085, 9799.265055120752, 47052.7647361761, 47442.52557474519, 47842.04315913592, 49819.25271121009, 53824.46175538351, 75911.8613220791, 76174.0, 76756.61621691856, 76770.0, 76820.46359072899, 12199.939000304941, 12469.750604987668, 12481.251124932533, 12552.246865188117, 12830.0, 14010.859891400956, 14026.579202623938, 14461.710765784836, 14569.781453277998, 14622.488212912496, 12844.293563854078, 13298.601041968757, 13345.999050071478, 13627.182369057888, 13737.175769453865, 12644.810327845275, 13055.151415157985, 13331.3334333281, 13462.990275729164, 13488.865111348761, 12511.775583545832, 12826.422810973716, 12965.870341296466, 13162.341882906008, 13212.603621891358, 10946.42520091818, 11106.0, 11216.383098929637, 11360.375179365214, 11362.829557556808, 9969.700908972742, 11181.0, 11309.377984210947, 11329.773404532029, 11342.659720208407], "value": 7385.415238837363, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1591.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 119.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 120.0], "value": 116.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 116.0, 129.0, 183.0, 144.0, 113.0, 117.0, 115.0, 145.0, 120.0], "value": 132.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 216.0, 270.0, 277.0, 205.0, 203.0, 195.0, 206.0, 196.0, 196.0], "value": 216.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 57.0, 87.0, 60.0, 61.0, 62.0, 58.0, 80.0, 58.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 86.0, 72.0, 68.0, 69.0, 89.0, 69.0, 72.0, 88.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 74.0, 134.0, 130.0, 121.0, 131.0, 135.0, 86.0], "value": 107.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 95.0, 149.0, 153.0, 95.0, 121.0, 151.0, 147.0, 94.0, 104.0], "value": 126.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 86.0, 90.0, 89.0, 123.0, 144.0, 139.0, 101.0], "value": 103.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 56.0, 56.0, 57.0, 58.0, 56.0, 59.0, 59.0, 58.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 76.0, 72.0, 76.0, 75.0, 68.0, 63.0, 66.0, 72.0, 72.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 89.0, 91.0, 87.0, 92.0, 92.0, 89.0, 88.0, 88.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 166.0, 163.0, 143.0, 147.0, 146.0, 163.0, 168.0, 214.0, 150.0], "value": 163.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 179.0, 154.0, 196.0, 188.0, 206.0, 243.0, 166.0, 160.0, 226.0], "value": 190.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 69.0, 72.0, 65.0, 81.0, 84.0, 90.0, 79.0, 62.0], "value": 74.3, "unit": "score"}]}]} 21:33:29 INFO - Return code: 0 21:33:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:33:29 ERROR - # TBPL SUCCESS # 21:33:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:33:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:33:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:33:29 INFO - 2016-10-26 21:33:29.171 screenresolution[1961:19141] starting screenresolution argv=screenresolution get 21:33:29 INFO - 2016-10-26 21:33:29.174 screenresolution[1961:19141] Display 0: 1600x1200x32@60 21:33:29 INFO - 2016-10-26 21:33:29.180 screenresolution[1962:19144] starting screenresolution argv=screenresolution list 21:33:29 INFO - Available Modes on Display 0 21:33:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:33:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:33:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:33:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:33:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:33:29 INFO - Intel Iris: 21:33:29 INFO - Chipset Model: Intel Iris 21:33:29 INFO - Type: GPU 21:33:29 INFO - Bus: Built-In 21:33:29 INFO - VRAM (Dynamic, Max): 1536 MB 21:33:29 INFO - Vendor: Intel (0x8086) 21:33:29 INFO - Device ID: 0x0a2e 21:33:29 INFO - Revision ID: 0x0009 21:33:29 INFO - Displays: 21:33:29 INFO - Display: 21:33:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:33:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:33:29 INFO - Display Serial Number: 1600x1200 60 21:33:29 INFO - Main Display: Yes 21:33:29 INFO - Mirror: Off 21:33:29 INFO - Online: Yes 21:33:29 INFO - Rotation: Supported 21:33:29 INFO - Return code: 0 21:33:29 INFO - Running post-action listener: _resource_record_post_action 21:33:29 INFO - [mozharness: 2016-10-27 04:33:29.419530Z] Finished run-tests step (success) 21:33:29 INFO - Running post-run listener: _resource_record_post_run 21:33:29 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 3225088; Write bytes: 707655680; Read time: 21; Write time: 1974 21:33:29 INFO - TinderboxPrint: CPU usage
54.4% 21:33:29 INFO - TinderboxPrint: I/O read bytes / time
3,225,088 / 21 21:33:29 INFO - TinderboxPrint: I/O write bytes / time
707,655,680 / 1,974 21:33:29 INFO - TinderboxPrint: CPU idle
2,209.1 (45.6%) 21:33:29 INFO - TinderboxPrint: CPU system
980.3 (20.2%) 21:33:29 INFO - TinderboxPrint: CPU user
1,660.4 (34.2%) 21:33:29 INFO - TinderboxPrint: Swap in / out
408,985,600 / 0 21:33:29 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398238208; Write bytes: 392783872; Read time: 29478; Write time: 782 21:33:29 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 2360832; Write bytes: 298422272; Read time: 18; Write time: 1143 21:33:29 INFO - Running post-run listener: _upload_blobber_files 21:33:29 INFO - Blob upload gear active. 21:33:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:33:29 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 21:33:29 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'] 21:33:29 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 21:33:30 INFO - (blobuploader) - INFO - Open directory for files ... 21:33:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:33:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:33:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:33:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:33:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:33:30 INFO - (blobuploader) - INFO - Done attempting. 21:33:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:33:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:33:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:33:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:33:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:33:31 INFO - (blobuploader) - INFO - Done attempting. 21:33:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:33:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:33:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:33:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:33:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:33:33 INFO - (blobuploader) - INFO - Done attempting. 21:33:33 INFO - (blobuploader) - INFO - Iteration through files over. 21:33:34 INFO - Return code: 0 21:33:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:33:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:33:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2790f3ba903c0fb2041319d457cbc3301e4b62cca316bccfd7cb468e3d4bd8aefff0ab14f646483f76844c929ef6525842613c3a1420b240bdf433a773a7f583", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/438e44ecab85d29a2e57868844432320705d5f371d1815ac8d29fe8127f22e85cbbb01f1ad375e928feea7e882668c1048d68099d65150e8507cad780c73c393", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/127eb14b70919968173d259c52c3230df4d448f7d2b85780a1ff87688d9b6fdd55acec1b6d4129676621361c425819145d3fe1f9d2d75fbc3f0eaf8dd64294c1"} 21:33:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:33:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:33:34 INFO - Contents: 21:33:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2790f3ba903c0fb2041319d457cbc3301e4b62cca316bccfd7cb468e3d4bd8aefff0ab14f646483f76844c929ef6525842613c3a1420b240bdf433a773a7f583", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/438e44ecab85d29a2e57868844432320705d5f371d1815ac8d29fe8127f22e85cbbb01f1ad375e928feea7e882668c1048d68099d65150e8507cad780c73c393", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/127eb14b70919968173d259c52c3230df4d448f7d2b85780a1ff87688d9b6fdd55acec1b6d4129676621361c425819145d3fe1f9d2d75fbc3f0eaf8dd64294c1"} 21:33:34 INFO - Running post-run listener: copy_logs_to_upload_dir 21:33:34 INFO - Copying logs to upload dir... 21:33:34 INFO - mkdir: /builds/slave/test/build/upload/logs 21:33:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.633374 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-10-26 21:33:34.105883) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 21:33:34.112950) ========= 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.gzOLxgRO1a/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.FsfAdjeyMt/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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/2790f3ba903c0fb2041319d457cbc3301e4b62cca316bccfd7cb468e3d4bd8aefff0ab14f646483f76844c929ef6525842613c3a1420b240bdf433a773a7f583", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/438e44ecab85d29a2e57868844432320705d5f371d1815ac8d29fe8127f22e85cbbb01f1ad375e928feea7e882668c1048d68099d65150e8507cad780c73c393", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/127eb14b70919968173d259c52c3230df4d448f7d2b85780a1ff87688d9b6fdd55acec1b6d4129676621361c425819145d3fe1f9d2d75fbc3f0eaf8dd64294c1"} build_url:https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011325 build_url: 'https://queue.taskcluster.net/v1/task/aqLaAy6BRYGskUgB8za_eQ/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/2790f3ba903c0fb2041319d457cbc3301e4b62cca316bccfd7cb468e3d4bd8aefff0ab14f646483f76844c929ef6525842613c3a1420b240bdf433a773a7f583", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/438e44ecab85d29a2e57868844432320705d5f371d1815ac8d29fe8127f22e85cbbb01f1ad375e928feea7e882668c1048d68099d65150e8507cad780c73c393", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/127eb14b70919968173d259c52c3230df4d448f7d2b85780a1ff87688d9b6fdd55acec1b6d4129676621361c425819145d3fe1f9d2d75fbc3f0eaf8dd64294c1"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 21:33:34.154133) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 21:33:34.154461) ========= 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.gzOLxgRO1a/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.FsfAdjeyMt/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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.005246 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 21:33:34.207136) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-26 21:33:34.207439) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-26 21:33:34.209788) ========= ========= Total master_lag: 0.38 =========