builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0104 starttime: 1477674211.7 results: success (0) buildid: 20161028073459 builduid: 35b7377895da4d69af434e667a072a13 revision: 04aa4dfb6ec598148d2de0cc5fec2a34130cdcf4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-28 10:03:31.703244) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-28 10:03:31.703742) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-28 10:03:31.704018) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-28 10:03:31.727817) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 10:03:31.728221) ========= 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.rSjeB4LOvp/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.F5EyYT32Dg/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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-28 10:03:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 219M=0s 2016-10-28 10:03:31 (219 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104230 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 10:03:31.856410) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 10:03:31.856775) ========= 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.rSjeB4LOvp/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.F5EyYT32Dg/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.029697 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 10:03:31.907703) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-28 10:03:31.908123) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 04aa4dfb6ec598148d2de0cc5fec2a34130cdcf4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/cedar --rev 04aa4dfb6ec598148d2de0cc5fec2a34130cdcf4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rSjeB4LOvp/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.F5EyYT32Dg/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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-28 10:03:31,987 truncating revision to first 12 chars 2016-10-28 10:03:31,987 Setting DEBUG logging. 2016-10-28 10:03:31,987 attempt 1/10 2016-10-28 10:03:31,987 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/04aa4dfb6ec5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-28 10:03:33,019 unpacking tar archive at: cedar-04aa4dfb6ec5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.311486 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-28 10:03:33.242378) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-28 10:03:33.242773) ========= script_repo_revision: 04aa4dfb6ec598148d2de0cc5fec2a34130cdcf4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-28 10:03:33.243155) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-28 10:03:33.243476) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-28 10:03:33.262277) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-28 10:03:33.262664) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 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', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rSjeB4LOvp/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.F5EyYT32Dg/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:03:33 INFO - MultiFileLogger online at 20161028 10:03:33 in /builds/slave/test 10:03:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 10:03:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:03:33 INFO - {'append_to_log': False, 10:03:33 INFO - 'base_work_dir': '/builds/slave/test', 10:03:33 INFO - 'blob_upload_branch': 'Cedar', 10:03:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:03:33 INFO - 'branch': 'Cedar', 10:03:33 INFO - 'buildbot_json_path': 'buildprops.json', 10:03:33 INFO - 'config_files': ('talos/mac_config.py',), 10:03:33 INFO - 'default_actions': ('clobber', 10:03:33 INFO - 'read-buildbot-config', 10:03:33 INFO - 'download-and-extract', 10:03:33 INFO - 'populate-webroot', 10:03:33 INFO - 'create-virtualenv', 10:03:33 INFO - 'install', 10:03:33 INFO - 'run-tests'), 10:03:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:03:33 INFO - 'download_minidump_stackwalk': True, 10:03:33 INFO - 'download_symbols': 'ondemand', 10:03:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:03:33 INFO - 'tooltool.py': '/tools/tooltool.py', 10:03:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:03:33 INFO - '/tools/misc-python/virtualenv.py')}, 10:03:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:03:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:03:33 INFO - 'installer_path': 'installer.exe', 10:03:33 INFO - 'log_level': 'info', 10:03:33 INFO - 'log_name': 'talos', 10:03:33 INFO - 'log_to_console': True, 10:03:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:03:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:03:33 INFO - 'opt_config_files': (), 10:03:33 INFO - 'pip_index': False, 10:03:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:03:33 INFO - 'cmd': ('bash', 10:03:33 INFO - '-c', 10:03:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:03:33 INFO - 'enabled': True, 10:03:33 INFO - 'halt_on_failure': False, 10:03:33 INFO - 'name': 'check_screen_resolution'},), 10:03:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:03:33 INFO - 'cmd': ('bash', 10:03:33 INFO - '-c', 10:03:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:03:33 INFO - 'enabled': True, 10:03:33 INFO - 'halt_on_failure': False, 10:03:33 INFO - 'name': 'check_screen_resolution'},), 10:03:33 INFO - 'run_cmd_checks_enabled': True, 10:03:33 INFO - 'sps_profile': False, 10:03:33 INFO - 'sps_profile_interval': 0, 10:03:33 INFO - 'suite': 'dromaeojs-e10s', 10:03:33 INFO - 'system_bits': '32', 10:03:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:03:33 INFO - 'title': 't-yosemite-r7-0104', 10:03:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:03:33 INFO - 'use_talos_json': True, 10:03:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:03:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:03:33 INFO - 'work_dir': 'build'} 10:03:33 INFO - [mozharness: 2016-10-28 17:03:33.471467Z] Running clobber step. 10:03:33 INFO - Running pre-action listener: _resource_record_pre_action 10:03:33 INFO - Running main action method: clobber 10:03:33 INFO - rmtree: /builds/slave/test/build 10:03:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:03:35 INFO - Running post-action listener: _resource_record_post_action 10:03:35 INFO - [mozharness: 2016-10-28 17:03:35.571205Z] Finished clobber step (success) 10:03:35 INFO - [mozharness: 2016-10-28 17:03:35.571280Z] Running read-buildbot-config step. 10:03:35 INFO - Running pre-action listener: _resource_record_pre_action 10:03:35 INFO - Running main action method: read_buildbot_config 10:03:35 INFO - Using buildbot properties: 10:03:35 INFO - { 10:03:35 INFO - "project": "", 10:03:35 INFO - "product": "firefox", 10:03:35 INFO - "script_repo_revision": "default", 10:03:35 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 10:03:35 INFO - "repository": "", 10:03:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 10:03:35 INFO - "buildid": "20161028073459", 10:03:35 INFO - "pgo_build": "False", 10:03:35 INFO - "basedir": "/builds/slave/test", 10:03:35 INFO - "buildnumber": 1, 10:03:35 INFO - "slavename": "t-yosemite-r7-0104", 10:03:35 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 10:03:35 INFO - "platform": "yosemite_r7", 10:03:35 INFO - "scheduler": "tests-cedar-macosx64-talos", 10:03:35 INFO - "branch": "cedar", 10:03:35 INFO - "repo_path": "projects/cedar", 10:03:35 INFO - "revision": "04aa4dfb6ec598148d2de0cc5fec2a34130cdcf4", 10:03:35 INFO - "stage_platform": "macosx64", 10:03:35 INFO - "builduid": "35b7377895da4d69af434e667a072a13", 10:03:35 INFO - "slavebuilddir": "test" 10:03:35 INFO - } 10:03:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 10:03:35 INFO - Running post-action listener: _resource_record_post_action 10:03:35 INFO - [mozharness: 2016-10-28 17:03:35.574543Z] Finished read-buildbot-config step (success) 10:03:35 INFO - [mozharness: 2016-10-28 17:03:35.574630Z] Running download-and-extract step. 10:03:35 INFO - Running pre-action listener: _resource_record_pre_action 10:03:35 INFO - Running main action method: download_and_extract 10:03:35 INFO - mkdir: /builds/slave/test/build/tests 10:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:35 INFO - trying https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 10:03:35 INFO - Downloading https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/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 10:03:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/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 10:03:36 INFO - Downloaded 1199 bytes. 10:03:36 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 10:03:36 INFO - Using the following test package requirements: 10:03:36 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 10:03:36 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:03:36 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 10:03:36 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:03:36 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 10:03:36 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:03:36 INFO - u'jsshell-mac.zip'], 10:03:36 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:03:36 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 10:03:36 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 10:03:36 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:03:36 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 10:03:36 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:03:36 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 10:03:36 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:03:36 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 10:03:36 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:03:36 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 10:03:36 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:03:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 10:03:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:03:36 INFO - Fetch https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 10:03:37 INFO - Http code: 200 10:03:37 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 10:03:37 INFO - x-amz-cf-id: 92lb7TGQWv3puTQaIyfdhrjvyM_yGeHqJvOV4WvNfJ223rcwfpbGjA== 10:03:37 INFO - x-amz-version-id: VkmCW7lQnb5kekCeetGms_45BPPIleHg 10:03:37 INFO - Expected file size: 16377249 10:03:37 INFO - Obtained file size: 16377249 10:03:44 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 10:03:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 10:03:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:03:44 INFO - Fetch https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 10:03:44 INFO - Http code: 200 10:03:44 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 10:03:44 INFO - x-amz-cf-id: fHIal7R3Ncb8UKLuO6mu-QcOBa7VVkuVXHfcqMiVDMQ-VK1F99T2rg== 10:03:44 INFO - x-amz-version-id: VkmCW7lQnb5kekCeetGms_45BPPIleHg 10:03:44 INFO - Expected file size: 16377249 10:03:44 INFO - Obtained file size: 16377249 10:03:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 10:03:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:03:51 INFO - Fetch https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 10:03:52 INFO - Http code: 200 10:03:52 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 10:03:52 INFO - x-amz-cf-id: oub0G3sNHMRN3T9n_ThyAbgydEytyqYjZAvCNjXUcHnyg92pMQDopA== 10:03:52 INFO - x-amz-version-id: RAyd.6SEUdt2cJ1VAtxNYHfWp2SpZ0jn 10:03:53 INFO - Expected file size: 14409028 10:03:53 INFO - Obtained file size: 14409028 10:03: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')]} 10:03:53 INFO - trying https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:03:53 INFO - Downloading https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 10:03:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/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 10:03:56 INFO - Downloaded 111525417 bytes. 10:03:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:03:56 INFO - mkdir: /builds/slave/test/properties 10:03:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:03:56 INFO - Writing to file /builds/slave/test/properties/build_url 10:03:56 INFO - Contents: 10:03:56 INFO - build_url:https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:03:57 INFO - Running post-action listener: _resource_record_post_action 10:03:57 INFO - Running post-action listener: set_extra_try_arguments 10:03:57 INFO - [mozharness: 2016-10-28 17:03:57.322249Z] Finished download-and-extract step (success) 10:03:57 INFO - [mozharness: 2016-10-28 17:03:57.322366Z] Running populate-webroot step. 10:03:57 INFO - Running pre-action listener: _resource_record_pre_action 10:03:57 INFO - Running main action method: populate_webroot 10:03:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:03:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:03:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:03:57 INFO - '--tppagecycles', 10:03:57 INFO - '7'], 10:03:57 INFO - 'tests': ['tsvgm']}}, 10:03:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:03:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:03:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:03:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:03:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:03:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:03:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:03:57 INFO - 'talos_options': ['--disable-e10s'], 10:03:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:03:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:03:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:03:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:03:57 INFO - 'talos_options': ['--disable-e10s'], 10:03:57 INFO - 'tests': ['damp', 'tps']}, 10:03:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:03:57 INFO - 'tests': ['damp', 'tps']}, 10:03:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:03:57 INFO - 'tests': ['dromaeo_dom']}, 10:03:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:03:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:03:57 INFO - 'tests': ['basic_compositor_video']}, 10:03:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:03:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:03:57 INFO - 'tests': ['a11yr', 10:03:57 INFO - 'ts_paint', 10:03:57 INFO - 'tpaint', 10:03:57 INFO - 'sessionrestore', 10:03:57 INFO - 'sessionrestore_no_auto_restore', 10:03:57 INFO - 'tabpaint']}, 10:03:57 INFO - 'other-e10s': {'tests': ['a11yr', 10:03:57 INFO - 'ts_paint', 10:03:57 INFO - 'tpaint', 10:03:57 INFO - 'sessionrestore', 10:03:57 INFO - 'sessionrestore_no_auto_restore', 10:03:57 INFO - 'tabpaint']}, 10:03:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:03:57 INFO - 'ts_paint', 10:03:57 INFO - 'tpaint', 10:03:57 INFO - 'sessionrestore', 10:03:57 INFO - 'sessionrestore_no_auto_restore', 10:03:57 INFO - 'tabpaint']}, 10:03:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:03:57 INFO - 'ts_paint', 10:03:57 INFO - 'tpaint', 10:03:57 INFO - 'sessionrestore', 10:03:57 INFO - 'sessionrestore_no_auto_restore', 10:03:57 INFO - 'tabpaint']}, 10:03:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:03:57 INFO - 'tests': ['a11yr', 10:03:57 INFO - 'ts_paint', 10:03:57 INFO - 'tpaint', 10:03:57 INFO - 'sessionrestore', 10:03:57 INFO - 'sessionrestore_no_auto_restore', 10:03:57 INFO - 'tabpaint']}, 10:03:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:03:57 INFO - 'tests': ['a11yr', 10:03:57 INFO - 'ts_paint', 10:03:57 INFO - 'tpaint', 10:03:57 INFO - 'sessionrestore', 10:03:57 INFO - 'sessionrestore_no_auto_restore', 10:03:57 INFO - 'tabpaint']}, 10:03:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:03:57 INFO - 'tests': ['tsvgx', 10:03:57 INFO - 'tsvgr_opacity', 10:03:57 INFO - 'tart', 10:03:57 INFO - 'tscrollx', 10:03:57 INFO - 'cart']}, 10:03:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:03:57 INFO - 'tsvgr_opacity', 10:03:57 INFO - 'tart', 10:03:57 INFO - 'tscrollx', 10:03:57 INFO - 'cart']}, 10:03:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:03:57 INFO - 'talos_options': ['--disable-e10s'], 10:03:57 INFO - 'tests': ['tp5o']}, 10:03:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:03:57 INFO - 'tests': ['tp5o']}, 10:03:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:03:57 INFO - 'talos_options': ['--disable-e10s', 10:03:57 INFO - '--xperf_path', 10:03:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:03:57 INFO - 'tests': ['tp5n']}, 10:03:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:03:57 INFO - 'talos_options': ['--xperf_path', 10:03:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:03:57 INFO - 'tests': ['tp5n']}}, 10:03:57 INFO - 'talos.zip': {'path': '', 10:03:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:03:57 INFO - Running post-action listener: _resource_record_post_action 10:03:57 INFO - [mozharness: 2016-10-28 17:03:57.340991Z] Finished populate-webroot step (success) 10:03:57 INFO - [mozharness: 2016-10-28 17:03:57.341096Z] Running create-virtualenv step. 10:03:57 INFO - Running pre-action listener: _resource_record_pre_action 10:03:57 INFO - Running main action method: create_virtualenv 10:03:57 INFO - Creating virtualenv /builds/slave/test/build/venv 10:03:57 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:03:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:03:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:03:57 INFO - Using real prefix '/tools/python27' 10:03:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:03:58 INFO - Installing distribute.............................................................................................................................................................................................done. 10:04:01 INFO - Installing pip.................done. 10:04:01 INFO - Return code: 0 10:04:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:04:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:04:01 INFO - Reading from file tmpfile_stdout 10:04:01 INFO - Output received: 10:04:01 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:04:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:04:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c88e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c804570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730492a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6a2d30>, '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 0x10c4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c69ab20>, '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.rSjeB4LOvp/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.F5EyYT32Dg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:04:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:04:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:04:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rSjeB4LOvp/Render', 10:04:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:01 INFO - 'HOME': '/Users/cltbld', 10:04:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:01 INFO - 'LOGNAME': 'cltbld', 10:04:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:01 INFO - 'MOZ_NO_REMOTE': '1', 10:04:01 INFO - 'NO_EM_RESTART': '1', 10:04:01 INFO - 'PAGER': '/bin/cat', 10:04:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:01 INFO - 'PWD': '/builds/slave/test', 10:04:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:01 INFO - 'SHELL': '/bin/bash', 10:04:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F5EyYT32Dg/Listeners', 10:04:01 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:04:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:01 INFO - 'USER': 'cltbld', 10:04:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:01 INFO - 'XPC_FLAGS': '0x0', 10:04:01 INFO - 'XPC_SERVICE_NAME': '0', 10:04:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:04:01 INFO - Cleaning up... 10:04:01 INFO - Return code: 0 10:04:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:04:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c88e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c804570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730492a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6a2d30>, '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 0x10c4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c69ab20>, '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.rSjeB4LOvp/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.F5EyYT32Dg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:04:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:04:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:04:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rSjeB4LOvp/Render', 10:04:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:01 INFO - 'HOME': '/Users/cltbld', 10:04:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:01 INFO - 'LOGNAME': 'cltbld', 10:04:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:01 INFO - 'MOZ_NO_REMOTE': '1', 10:04:01 INFO - 'NO_EM_RESTART': '1', 10:04:01 INFO - 'PAGER': '/bin/cat', 10:04:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:01 INFO - 'PWD': '/builds/slave/test', 10:04:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:01 INFO - 'SHELL': '/bin/bash', 10:04:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F5EyYT32Dg/Listeners', 10:04:01 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:04:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:01 INFO - 'USER': 'cltbld', 10:04:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:01 INFO - 'XPC_FLAGS': '0x0', 10:04:01 INFO - 'XPC_SERVICE_NAME': '0', 10:04:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:01 INFO - Downloading/unpacking psutil>=3.1.1 10:04:01 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 10:04:01 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 10:04:01 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 10:04:01 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 10:04:01 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 10:04:01 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 10:04:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:04:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:04:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:04:05 INFO - Installing collected packages: psutil 10:04:05 INFO - Running setup.py install for psutil 10:04:05 INFO - building 'psutil._psutil_osx' extension 10:04:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:04:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:04:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:04:06 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:04:06 INFO - building 'psutil._psutil_posix' extension 10:04:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:04:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:04:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:04:06 INFO - ^ 10:04:06 INFO - 1 warning generated. 10:04:06 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:04:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:04:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:04:06 INFO - Successfully installed psutil 10:04:06 INFO - Cleaning up... 10:04:06 INFO - Return code: 0 10:04:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:04:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c88e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c804570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730492a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6a2d30>, '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 0x10c4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c69ab20>, '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.rSjeB4LOvp/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.F5EyYT32Dg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:04:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:04:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:04:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rSjeB4LOvp/Render', 10:04:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:06 INFO - 'HOME': '/Users/cltbld', 10:04:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:06 INFO - 'LOGNAME': 'cltbld', 10:04:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:06 INFO - 'MOZ_NO_REMOTE': '1', 10:04:06 INFO - 'NO_EM_RESTART': '1', 10:04:06 INFO - 'PAGER': '/bin/cat', 10:04:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:06 INFO - 'PWD': '/builds/slave/test', 10:04:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:06 INFO - 'SHELL': '/bin/bash', 10:04:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F5EyYT32Dg/Listeners', 10:04:06 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:04:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:06 INFO - 'USER': 'cltbld', 10:04:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:06 INFO - 'XPC_FLAGS': '0x0', 10:04:06 INFO - 'XPC_SERVICE_NAME': '0', 10:04:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:04:06 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 10:04:06 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 10:04:06 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 10:04:06 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 10:04:06 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 10:04:06 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 10:04:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:04:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:04:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:04:09 INFO - Installing collected packages: mozsystemmonitor 10:04:09 INFO - Running setup.py install for mozsystemmonitor 10:04:09 INFO - Successfully installed mozsystemmonitor 10:04:09 INFO - Cleaning up... 10:04:09 INFO - Return code: 0 10:04:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:04: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')]} 10:04:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04: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')]} 10:04:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c88e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c804570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730492a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6a2d30>, '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 0x10c4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c69ab20>, '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.rSjeB4LOvp/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.F5EyYT32Dg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:04:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:04:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:04:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rSjeB4LOvp/Render', 10:04:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:09 INFO - 'HOME': '/Users/cltbld', 10:04:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:09 INFO - 'LOGNAME': 'cltbld', 10:04:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:09 INFO - 'MOZ_NO_REMOTE': '1', 10:04:09 INFO - 'NO_EM_RESTART': '1', 10:04:09 INFO - 'PAGER': '/bin/cat', 10:04:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:09 INFO - 'PWD': '/builds/slave/test', 10:04:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:09 INFO - 'SHELL': '/bin/bash', 10:04:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F5EyYT32Dg/Listeners', 10:04:09 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:04:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:09 INFO - 'USER': 'cltbld', 10:04:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:09 INFO - 'XPC_FLAGS': '0x0', 10:04:09 INFO - 'XPC_SERVICE_NAME': '0', 10:04:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:09 INFO - Downloading/unpacking jsonschema==2.5.1 10:04:09 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 10:04:09 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 10:04:09 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 10:04:09 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 10:04:09 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 10:04:09 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 10:04:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:04:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:04: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 10:04: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 10:04: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 10:04: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 10:04: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 10:04: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 10:04:12 INFO - Downloading functools32-3.2.3-2.tar.gz 10:04:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:04:12 INFO - warning: no files found matching '*.txt' 10:04:12 INFO - no previously-included directories found matching 'build' 10:04:12 INFO - no previously-included directories found matching 'dist' 10:04:12 INFO - no previously-included directories found matching '.git*' 10:04:12 INFO - Installing collected packages: jsonschema, functools32 10:04:12 INFO - Running setup.py install for functools32 10:04:12 INFO - warning: no files found matching '*.txt' 10:04:12 INFO - no previously-included directories found matching 'build' 10:04:12 INFO - no previously-included directories found matching 'dist' 10:04:12 INFO - no previously-included directories found matching '.git*' 10:04:12 INFO - Successfully installed jsonschema functools32 10:04:12 INFO - Cleaning up... 10:04:13 INFO - Return code: 0 10:04:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:04:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c88e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c804570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730492a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6a2d30>, '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 0x10c4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c69ab20>, '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.rSjeB4LOvp/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.F5EyYT32Dg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:04:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:04:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:04:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rSjeB4LOvp/Render', 10:04:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:13 INFO - 'HOME': '/Users/cltbld', 10:04:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:13 INFO - 'LOGNAME': 'cltbld', 10:04:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:13 INFO - 'MOZ_NO_REMOTE': '1', 10:04:13 INFO - 'NO_EM_RESTART': '1', 10:04:13 INFO - 'PAGER': '/bin/cat', 10:04:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:13 INFO - 'PWD': '/builds/slave/test', 10:04:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:13 INFO - 'SHELL': '/bin/bash', 10:04:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F5EyYT32Dg/Listeners', 10:04:13 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:04:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:13 INFO - 'USER': 'cltbld', 10:04:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:13 INFO - 'XPC_FLAGS': '0x0', 10:04:13 INFO - 'XPC_SERVICE_NAME': '0', 10:04:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:04:13 INFO - Cleaning up... 10:04:13 INFO - Return code: 0 10:04:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:04:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c88e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c804570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730492a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6a2d30>, '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 0x10c4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c69ab20>, '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.rSjeB4LOvp/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.F5EyYT32Dg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:04:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:04:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:04:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rSjeB4LOvp/Render', 10:04:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:13 INFO - 'HOME': '/Users/cltbld', 10:04:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:13 INFO - 'LOGNAME': 'cltbld', 10:04:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:13 INFO - 'MOZ_NO_REMOTE': '1', 10:04:13 INFO - 'NO_EM_RESTART': '1', 10:04:13 INFO - 'PAGER': '/bin/cat', 10:04:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:13 INFO - 'PWD': '/builds/slave/test', 10:04:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:13 INFO - 'SHELL': '/bin/bash', 10:04:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F5EyYT32Dg/Listeners', 10:04:13 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:04:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:13 INFO - 'USER': 'cltbld', 10:04:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:13 INFO - 'XPC_FLAGS': '0x0', 10:04:13 INFO - 'XPC_SERVICE_NAME': '0', 10:04:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:13 INFO - Downloading/unpacking blobuploader==1.2.4 10:04:13 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 10:04:13 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 10:04:13 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 10:04:13 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 10:04:13 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 10:04:13 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 10:04:16 INFO - Downloading blobuploader-1.2.4.tar.gz 10:04:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:04:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:04:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:04:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:04:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:04:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:04:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:04:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:04:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:04:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:04:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:04:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:04:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:04:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:04:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:04:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:04:17 INFO - Downloading docopt-0.6.1.tar.gz 10:04:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:04:17 INFO - Installing collected packages: blobuploader, requests, docopt 10:04:17 INFO - Running setup.py install for blobuploader 10:04:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:04:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:04:17 INFO - Running setup.py install for requests 10:04:18 INFO - Running setup.py install for docopt 10:04:18 INFO - Successfully installed blobuploader requests docopt 10:04:18 INFO - Cleaning up... 10:04:18 INFO - Return code: 0 10:04:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:04:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c88e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c804570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730492a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6a2d30>, '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 0x10c4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c69ab20>, '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.rSjeB4LOvp/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.F5EyYT32Dg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:04:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:04:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:04:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rSjeB4LOvp/Render', 10:04:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:18 INFO - 'HOME': '/Users/cltbld', 10:04:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:18 INFO - 'LOGNAME': 'cltbld', 10:04:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:18 INFO - 'MOZ_NO_REMOTE': '1', 10:04:18 INFO - 'NO_EM_RESTART': '1', 10:04:18 INFO - 'PAGER': '/bin/cat', 10:04:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:18 INFO - 'PWD': '/builds/slave/test', 10:04:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:18 INFO - 'SHELL': '/bin/bash', 10:04:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F5EyYT32Dg/Listeners', 10:04:18 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:04:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:18 INFO - 'USER': 'cltbld', 10:04:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:18 INFO - 'XPC_FLAGS': '0x0', 10:04:18 INFO - 'XPC_SERVICE_NAME': '0', 10:04:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:04:18 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-SVUM6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:04:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:04:18 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-_NBHzY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:04:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:04:18 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-ewkWj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:04:19 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-tjkzJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:04:19 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-OLoiaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:04:19 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-lPgY60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:04:19 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-j5BnAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:04:19 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-lU_bAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:04:19 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-2T07ZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:04:19 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-d1HSsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:04:19 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-VBUDZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:04:19 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-liRQ80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:04:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:04:19 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-nCq7pR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:04:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:04:20 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-qlIY9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:04:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:04:20 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-5lGFFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:04:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:04:20 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-VfO1Si-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:04:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:04:20 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Gifwxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:04:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:04:20 INFO - Running setup.py install for manifestparser 10:04:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:04:20 INFO - Running setup.py install for mozcrash 10:04:20 INFO - Running setup.py install for mozdebug 10:04:20 INFO - Running setup.py install for mozdevice 10:04:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:04:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:04:21 INFO - Running setup.py install for mozfile 10:04:21 INFO - Running setup.py install for mozhttpd 10:04:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:04:21 INFO - Running setup.py install for mozinfo 10:04:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:04:21 INFO - Running setup.py install for mozInstall 10:04:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:04:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:04:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:04:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:04:21 INFO - Running setup.py install for mozleak 10:04:21 INFO - Running setup.py install for mozlog 10:04:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:04:21 INFO - Running setup.py install for moznetwork 10:04:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:04:22 INFO - Running setup.py install for mozprocess 10:04:22 INFO - Running setup.py install for mozprofile 10:04:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:04:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:04:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:04:22 INFO - Running setup.py install for mozrunner 10:04:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:04:22 INFO - Running setup.py install for mozscreenshot 10:04:22 INFO - Running setup.py install for moztest 10:04:22 INFO - Running setup.py install for mozversion 10:04:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:04:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:04:23 INFO - Cleaning up... 10:04:23 INFO - Return code: 0 10:04:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:04: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')]} 10:04:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04: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')]} 10:04:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c88e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c804570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730492a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6a2d30>, '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 0x10c4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c69ab20>, '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.rSjeB4LOvp/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.F5EyYT32Dg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:04:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:04:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:04:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rSjeB4LOvp/Render', 10:04:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:23 INFO - 'HOME': '/Users/cltbld', 10:04:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:23 INFO - 'LOGNAME': 'cltbld', 10:04:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:23 INFO - 'MOZ_NO_REMOTE': '1', 10:04:23 INFO - 'NO_EM_RESTART': '1', 10:04:23 INFO - 'PAGER': '/bin/cat', 10:04:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:23 INFO - 'PWD': '/builds/slave/test', 10:04:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:23 INFO - 'SHELL': '/bin/bash', 10:04:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F5EyYT32Dg/Listeners', 10:04:23 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:04:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:23 INFO - 'USER': 'cltbld', 10:04:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:23 INFO - 'XPC_FLAGS': '0x0', 10:04:23 INFO - 'XPC_SERVICE_NAME': '0', 10:04:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:04:23 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-qm5PKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:04:23 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:04:23 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-jj7yNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:04:23 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)) 10:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:04:23 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-fTdD26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:04:23 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)) 10:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:04:23 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-hQcElb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:04:23 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)) 10:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:04:23 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-6gQdnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:04:23 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)) 10:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:04:23 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-gxL0_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:04:23 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)) 10:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:04:23 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-DK1Xc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:04:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:04:24 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-B_kGbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:04:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:04:24 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-NYK3Ad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:04:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:04:24 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-xSJqDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:04:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:04:24 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-MQXlDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:04:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:04:24 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-MkmUyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:04:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:04:24 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-77vsQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:04:24 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)) 10:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:04:24 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-IT0ftQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:04:24 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)) 10:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:04:24 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-XbqAPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:04:24 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)) 10:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:04:24 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-_8Doud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:04:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:04:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:04:25 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-47ozx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:04:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:04:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:04:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:04:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:04:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:04:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:04:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:04:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:04:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:04:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:04:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:04:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:04:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:04:27 INFO - Downloading blessings-1.6.tar.gz 10:04:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:04:27 INFO - Installing collected packages: blessings 10:04:27 INFO - Running setup.py install for blessings 10:04:27 INFO - Successfully installed blessings 10:04:27 INFO - Cleaning up... 10:04:28 INFO - Return code: 0 10:04:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:04:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:04:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:04:28 INFO - Reading from file tmpfile_stdout 10:04:28 INFO - Current package versions: 10:04:28 INFO - blessings == 1.6 10:04:28 INFO - blobuploader == 1.2.4 10:04:28 INFO - docopt == 0.6.1 10:04:28 INFO - functools32 == 3.2.3-2 10:04:28 INFO - jsonschema == 2.5.1 10:04:28 INFO - manifestparser == 1.1 10:04:28 INFO - mozInstall == 1.12 10:04:28 INFO - mozcrash == 0.17 10:04:28 INFO - mozdebug == 0.1 10:04:28 INFO - mozdevice == 0.48 10:04:28 INFO - mozfile == 1.2 10:04:28 INFO - mozhttpd == 0.7 10:04:28 INFO - mozinfo == 0.9 10:04:28 INFO - mozleak == 0.1 10:04:28 INFO - mozlog == 3.4 10:04:28 INFO - moznetwork == 0.27 10:04:28 INFO - mozprocess == 0.23 10:04:28 INFO - mozprofile == 0.28 10:04:28 INFO - mozrunner == 6.12 10:04:28 INFO - mozscreenshot == 0.1 10:04:28 INFO - mozsystemmonitor == 0.3 10:04:28 INFO - moztest == 0.7 10:04:28 INFO - mozversion == 1.4 10:04:28 INFO - psutil == 3.1.1 10:04:28 INFO - requests == 1.2.3 10:04:28 INFO - wsgiref == 0.1.2 10:04:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:04:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c88e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c804570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730492a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6a2d30>, '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 0x10c4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c69ab20>, '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.rSjeB4LOvp/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.F5EyYT32Dg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:04:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:04:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:04:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rSjeB4LOvp/Render', 10:04:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:29 INFO - 'HOME': '/Users/cltbld', 10:04:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:29 INFO - 'LOGNAME': 'cltbld', 10:04:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:29 INFO - 'MOZ_NO_REMOTE': '1', 10:04:29 INFO - 'NO_EM_RESTART': '1', 10:04:29 INFO - 'PAGER': '/bin/cat', 10:04:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:29 INFO - 'PWD': '/builds/slave/test', 10:04:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:29 INFO - 'SHELL': '/bin/bash', 10:04:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F5EyYT32Dg/Listeners', 10:04:29 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:04:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:29 INFO - 'USER': 'cltbld', 10:04:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:29 INFO - 'XPC_FLAGS': '0x0', 10:04:29 INFO - 'XPC_SERVICE_NAME': '0', 10:04:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:04:29 INFO - Cleaning up... 10:04:29 INFO - Return code: 0 10:04:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c88e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c804570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc730492a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6a2d30>, '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 0x10c4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c69ab20>, '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.rSjeB4LOvp/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.F5EyYT32Dg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:04:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:04:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:04:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rSjeB4LOvp/Render', 10:04:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:30 INFO - 'HOME': '/Users/cltbld', 10:04:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:30 INFO - 'LOGNAME': 'cltbld', 10:04:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:30 INFO - 'MOZ_NO_REMOTE': '1', 10:04:30 INFO - 'NO_EM_RESTART': '1', 10:04:30 INFO - 'PAGER': '/bin/cat', 10:04:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:30 INFO - 'PWD': '/builds/slave/test', 10:04:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:30 INFO - 'SHELL': '/bin/bash', 10:04:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F5EyYT32Dg/Listeners', 10:04:30 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:04:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:30 INFO - 'USER': 'cltbld', 10:04:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:30 INFO - 'XPC_FLAGS': '0x0', 10:04:30 INFO - 'XPC_SERVICE_NAME': '0', 10:04:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:04:30 INFO - Cleaning up... 10:04:30 INFO - Return code: 0 10:04:30 INFO - Running post-action listener: _resource_record_post_action 10:04:30 INFO - Running post-action listener: _start_resource_monitoring 10:04:30 INFO - Starting resource monitoring. 10:04:30 INFO - [mozharness: 2016-10-28 17:04:30.503031Z] Finished create-virtualenv step (success) 10:04:30 INFO - [mozharness: 2016-10-28 17:04:30.503980Z] Running install step. 10:04:30 INFO - Running pre-action listener: _resource_record_pre_action 10:04:30 INFO - Running main action method: install 10:04:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:04:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:04:30 INFO - Reading from file tmpfile_stdout 10:04:30 INFO - Detecting whether we're running mozinstall >=1.0... 10:04:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:04:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:04:30 INFO - Reading from file tmpfile_stdout 10:04:30 INFO - Output received: 10:04:30 INFO - Usage: mozinstall [options] installer 10:04:30 INFO - Options: 10:04:30 INFO - -h, --help show this help message and exit 10:04:30 INFO - -d DEST, --destination=DEST 10:04:30 INFO - Directory to install application into. [default: 10:04:30 INFO - "/builds/slave/test"] 10:04:30 INFO - --app=APP Application being installed. [default: firefox] 10:04:30 INFO - mkdir: /builds/slave/test/build/application 10:04:30 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'] 10:04:30 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 10:05:05 INFO - Reading from file tmpfile_stdout 10:05:05 INFO - Output received: 10:05:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:05:05 INFO - Running post-action listener: _resource_record_post_action 10:05:05 INFO - [mozharness: 2016-10-28 17:05:05.263588Z] Finished install step (success) 10:05:05 INFO - [mozharness: 2016-10-28 17:05:05.263728Z] Running run-tests step. 10:05:05 INFO - Running pre-action listener: _resource_record_pre_action 10:05:05 INFO - Running main action method: run_tests 10:05:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:05:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:05:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:05:05 INFO - 2016-10-28 10:05:05.278 screenresolution[1637:7562] starting screenresolution argv=screenresolution get 10:05:05 INFO - 2016-10-28 10:05:05.280 screenresolution[1637:7562] Display 0: 1600x1200x32@60 10:05:05 INFO - 2016-10-28 10:05:05.286 screenresolution[1638:7564] starting screenresolution argv=screenresolution list 10:05:05 INFO - Available Modes on Display 0 10:05:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:05:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:05:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:05:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:05:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:05:05 INFO - Intel Iris: 10:05:05 INFO - Chipset Model: Intel Iris 10:05:05 INFO - Type: GPU 10:05:05 INFO - Bus: Built-In 10:05:05 INFO - VRAM (Dynamic, Max): 1536 MB 10:05:05 INFO - Vendor: Intel (0x8086) 10:05:05 INFO - Device ID: 0x0a2e 10:05:05 INFO - Revision ID: 0x0009 10:05:05 INFO - Displays: 10:05:05 INFO - Display: 10:05:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:05:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:05:05 INFO - Display Serial Number: 1600x1200 60 10:05:05 INFO - Main Display: Yes 10:05:05 INFO - Mirror: Off 10:05:05 INFO - Online: Yes 10:05:05 INFO - Rotation: Supported 10:05:05 INFO - Return code: 0 10:05:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:05:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:05:05 INFO - Python 2.7.3 10:05:05 INFO - Return code: 0 10:05:05 INFO - grabbing minidump binary from tooltool 10:05: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')]} 10:05:05 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 0x10c6a2d30>, '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 0x10c4c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c69ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:05:05 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 10:05:05 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 10:05:05 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 10:05:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:05:05 INFO - Return code: 0 10:05:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:05:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:05:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:05:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:05:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:05:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 10:05:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0104 --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 10:05:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rSjeB4LOvp/Render', 10:05:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:05 INFO - 'HOME': '/Users/cltbld', 10:05:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:05 INFO - 'LOGNAME': 'cltbld', 10:05:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:05:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:05:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:05 INFO - 'MOZ_NO_REMOTE': '1', 10:05:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:05:05 INFO - 'NO_EM_RESTART': '1', 10:05:05 INFO - 'PAGER': '/bin/cat', 10:05:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:05 INFO - 'PWD': '/builds/slave/test', 10:05:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:05:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:05 INFO - 'SHELL': '/bin/bash', 10:05:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F5EyYT32Dg/Listeners', 10:05:05 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:05:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:05 INFO - 'USER': 'cltbld', 10:05:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:05 INFO - 'XPC_FLAGS': '0x0', 10:05:05 INFO - 'XPC_SERVICE_NAME': '0', 10:05:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 10:05:05 INFO - mozversion application_buildid: 20161028073459 10:05:05 INFO - mozversion application_changeset: 04aa4dfb6ec598148d2de0cc5fec2a34130cdcf4 10:05:05 INFO - mozversion application_display_name: Nightly 10:05:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:05:05 INFO - mozversion application_name: Firefox 10:05:05 INFO - mozversion application_remotingname: firefox 10:05:05 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 10:05:05 INFO - mozversion application_vendor: Mozilla 10:05:05 INFO - mozversion application_version: 52.0a1 10:05:05 INFO - mozversion platform_buildid: 20161028073459 10:05:05 INFO - mozversion platform_changeset: 04aa4dfb6ec598148d2de0cc5fec2a34130cdcf4 10:05:05 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 10:05:05 INFO - mozversion platform_version: 52.0a1 10:05:05 INFO - using testdate: 1477674305 10:05:05 INFO - actual date: 1477674305 10:05:05 INFO - starting webserver on 'localhost:49266' 10:05:05 INFO - SUITE-START | Running 2 tests 10:05:05 INFO - TEST-START | dromaeo_css 10:05:05 INFO - operating with platform_type : mac_ 10:05:05 INFO - Initialising browser for dromaeo_css test... 10:05:05 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmphYjndf/profile http://localhost:49266/getInfo.html) 10:05:08 INFO - PROCESS | 1648 | 2016-10-28 10:05:08.797 plugin-container[1650:7738] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 10:05:08 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:05:08 INFO - PROCESS | 1648 | 2016-10-28 10:05:08.800 plugin-container[1650:7738] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a1f, name = 'com.apple.CFPasteboardClient' 10:05:08 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:05:08 INFO - PROCESS | 1648 | 2016-10-28 10:05:08.800 plugin-container[1650:7738] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:05:08 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 10:05:08 INFO - PROCESS | 1648 | colorDepth:24 10:05:08 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 10:05:08 INFO - PROCESS | 1648 | __metrics 10:05:08 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 10:05:09 INFO - TEST-INFO | 1648: exit 0 10:05:09 INFO - Browser initialized. 10:05:09 INFO - Running cycle 1/1 for dromaeo_css test... 10:05:09 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmphYjndf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:05:12 INFO - PROCESS | 1651 | 2016-10-28 10:05:12.332 plugin-container[1652:7855] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 10:05:12 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:05:12 INFO - PROCESS | 1651 | 2016-10-28 10:05:12.333 plugin-container[1652:7855] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 10:05:12 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:05:12 INFO - PROCESS | 1651 | 2016-10-28 10:05:12.333 plugin-container[1652:7855] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:05:22 INFO - PROCESS | 1651 | RSS: Main: 236367872 10:05:22 INFO - PROCESS | 1651 | 10:06:11 INFO - PROCESS | 1651 | 1477674371640 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] 10:08:26 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 10:08:27 INFO - PROCESS | 1651 | RSS: Main: 251097088 10:08:27 INFO - PROCESS | 1651 | 10:11:29 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 10:11:30 INFO - PROCESS | 1651 | RSS: Main: 261730304 10:11:30 INFO - PROCESS | 1651 | 10:14:32 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 10:14:32 INFO - PROCESS | 1651 | RSS: Main: 265637888 10:14:32 INFO - PROCESS | 1651 | 10:17:34 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 10:17:35 INFO - PROCESS | 1651 | RSS: Main: 264130560 10:17:35 INFO - PROCESS | 1651 | 10:20:38 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 10:20:38 INFO - PROCESS | 1651 | RSS: Main: 269463552 10:20:38 INFO - PROCESS | 1651 | 10:23:41 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 10:23:41 INFO - PROCESS | 1651 | RSS: Main: 271568896 10:23:41 INFO - PROCESS | 1651 | 10:23:41 INFO - PROCESS | 1651 | __start_tp_report 10:23:41 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 10:23:41 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 10:23:41 INFO - PROCESS | 1651 | |i|pagename|runs| 10:23:41 INFO - PROCESS | 1651 | |0;dojo.html;3400.1839558505967;3417.8120203388817;3442.053435305292;3443.8966830995064;3447.1726785571464;5613.775448982046;5707.857303567413;5782.843431313739;5786.739596718147;5811.331696854852;5600.943990560093;5675.460831221028;5746.626469279504;5748.827535173951;5768.250127483432;4445.533219012005;4837.177679794443;5211.557017653485;5255.264263003182;5288.0745869472885;5230.137027390476;5322.92015619766;5335.599830012745;5356.625036247464;5372.462753724635;5132.794688212467;5189.948100518985;5290.285811415448;5308.071087559701;5344.759485823147;5220.1647736362;5241.0828105081455;5242.868928276824;5251.133562962106;5336.759845806948;5164.9670065986575;5166.302549155854;5183.663061900965;5188.766505507261;5219.765110569995;4933.481984391623;5020.970701006285;5659.971700141473;5691.573132015115;5696.772129114872;5164.561012313958;6186.288576813679;6212.099245609406;6213.409726076015;6218.129461875341;6208.286047104596;6230.626162430223;6245.094461303085;6245.906311405332;6257.092721555395;5715.714214289269;5732.226149469851;5798.101294299432;5799.9130013049835;5820.94179058204;6236.501079913596;6264.1856558648;6294.905576416358;6296.937030629727;6305.495560355161;6143.56995010354;6200.875982480326;6203.844903877439;6221.626702397871;6221.875562488724;6115.755369785159;6168.599041062316;6171.876562468726;6233.812985610439;6245.812625621239;6063.242094738158;6094.864090282533;6148.354422785633;6221.471174950089;6246.187995560548;5101.9489805101475;5545.584081193846;5820.883582328414;6264.5301602380005;6297.149884765589;4546.795394207269;4773.689710168897;4843;4976.776045077908;5008.574271186917;3784.9402167393173;3979.5622481527007;4151.14901445203;4361.345798130243;4363.040131171138;4861.343718598015;5231.163013917755;5391.32608423947;5799.3040835100055;5806.44838740319;6291.622502649856;6292.4966002719675;6299.244090709144;6299.338569450233;6305.936940630627;5608.6073974821375;5685.630434021776;5707.172459993278;5732.885342293214;5778.306603207558;5392.865178370572;5396.0556902542685;5410.48600382963;5411.540019098342;5438.320209973754;5744.454276843693;6308.905366419415;6309.779157729366;6324.778632747923;6331.683415829282;5582.469665381782;5961.91057134152;6111.926012480351;6177;6213.751449941951;5113.744312784272;5807.738651760596;6305.306416294112;6325.96837015812;6328.525360598066;6234.220722409699;6258.780942666985;6273.37266273369;6275.215598050244;6276.905846412399;6127.662978536223;6208.255009398901;6215.098810672517;6257.9674353731125;6263.843403914939;6094.51243900478;6137.254412462313;6153;6178.752849885954;6202.658853762906;6103.511719062553;6108.511319094373;6185.536084793748;6191.752329906753;6193.194884664965;4861.562459378673;5552.7778888843995;5650.85872853182;5896.233489646319;5907.143464197753;4445.688801783844;5098.1842905135;6106.06632622643;6121.239585875057;6142.590862071414;5205.45342739007;5924.881502369843;5958.851028724143;5974.701264936649;5976.7908123215475 10:23:41 INFO - PROCESS | 1651 | |1;ext.html;471.2789432168782;480.00638677936684;533.8745394832214;538.5530010091625;544.1321092856897;39670;41435.442101368826;41466.58533414665;41624;43868;42904;43361;43421;43518.91202719934;43531.129377412435;9568.138867501906;9701.27240456965;9703.805923881519;9754.122129008389;9768.853467197998;9541.141297283242;9618.855717164248;9624.903750962505;9648.80702385952;9650.131488166064;9630.18143457807;9638.373505722106;9655.46894920779;9664.27517936154;9687.273454490905;9557.617695292214;9587.90412095877;9643.180329671986;9645.373050751748;9670.032996700344;9431.056894310583;9486.241100711995;9501.144896959238;9505.19205867502;9538.570764315622;40285.59714402848;40819.38770918438;41001.794991025155;41030;41279.55521556761;3567.554055743032;3576.678098971079;3673.779573225615;3821.4840996465678;4062.065724883264;30332.54501182484;31046.379072418425;31275.687243127733;31637.367252655047;31687.20781980446;3180.3480286541435;3279.918002049968;3308.7849289796327;3339.2653616204398;3366.5791776027995;439280;443917;447171;447240.7637961822;448606;54916.45083549193;55329.446705533235;61052.38947610467;61460.38539614636;61611;64154;64572.354276457576;64673;64681.67659161674;64814.67592662007;15949.20253987296;16068.839311606967;16152.030878147349;16172.433964811411;16193.190340482928;12437.689057773629;12493.375331233401;12529.812052819216;12535.31055791922;12559.372031398576;16105.758413623806;17401.955882646827;17623.911880440515;17678.11609419524;17757;3236.8543415546355;3283.8998935356444;5822.243108395966;7043;7094.858102837914;731.7133956386293;938.6292414496372;964.1515466389673;1001.6143784642819;1098.8901109889107;24264.393390165387;30219.848900755354;30484.085477435714;30770;30896;893.9865902011345;1026.2610920137488;1035.9899098379171;1066.0352381095122;1101.3502033800096;13628.86371136276;14195;15355.769663455058;15381.69255613263;15767.211639417983;540814;629452;635287;636019;639190;51338;58695.41304586985;58759;59466.108008379044;60206.79586408247;1351.6283022168982;1402.2217669193492;1412.9222892740793;1414.1091112598997;1420.2614640386735;29412.411751765274;30189.547156792225;30201.697983019887;30610.84694576513;30611.387772244874;51238.48761512337;51366;51430.45708628747;51702;51777;585710;587845;589673;589795;590928;26965.786539606157;27046.91812327485;27305.590416143375;27405;27476.45047099007;46665.53334466612;47567;47826;48844.77888052827;49005.50994490009;34990.95027149189;38197.61802381941;38242;40582.51944630925;41092.58907410887;39066.414003789396;39739.80130099331;39856.60143398529;40097.99755006148;40886.59113408828;38397.424038638885;38585.80707096559;38595;38806.80596596999;38945.610543894196;33148.33703325968;34991.30017399689;35132.82433587918;35454.8227258862;35714;23880;25782.355441114123;25903;25904.611430828176;26410 10:23:41 INFO - PROCESS | 1651 | |2;jquery.html;339.5229702268313;356.86260789596014;357.91052236940754;371.240812538359;384.88068698703404;19619.11713972871;20072.89963550182;20298;20874;20983.265585704503;20401.897990510064;20667.586648267064;20772;21041.368758937217;21273.787262127375;4838.806447742086;5627.240322556464;5761.971190144043;5851.444112809277;6439.517036222279;6386.521010924176;6489.805305840832;6546.836329091763;6569.310222426648;6575.671216439159;4461.799219035135;5947;5954.196183515235;6025.487833534155;6471.676416179196;5774.595778295521;5921.259842519685;5962.373950735154;5993.700314984255;5994.760209591633;5192.792288308463;5212.661177023481;5216.37403511581;5233.005728911495;5234.842954711365;12642.2414655121;12878.742425151539;18334.816651833444;19920.501987450283;24643.137490187757;2069.379186244121;2145.549434618732;2156.029786596038;2239.507308392151;2511.510255500178;9888;10073.798524029551;10494.737631559196;13947.372368243454;15641.296141673538;1782.5721826761614;1785.1520527749344;1818.7544681467964;1838.2922574808795;1924.201456395594;118712.40643796812;126256;127495.36252318679;128066;129623.05565416526;7989.7203597873795;10661.667291588552;10781.407022613832;10818.404987725751;10954.123670106372;11838.230515016496;12110.333931633844;14291.785623215661;14324.283785810669;14325.713485730435;7318.963405183046;7609.162992070867;8337.249647531751;8396.118407567239;8411.747647570583;6993;7867.685292588232;8100.756977290691;8147.837043259102;8264.256216940385;3777.773333599993;4288.64349415744;4401.361802538614;4425.822967081281;4481.260592002283;1629.4867116858152;1632.2491653839368;1852.990735046316;1889.5465088378965;1905.5902980858905;795.1372760554747;852.663198036772;860.9096044915319;985.6205360936091;1000.9149222316039;19679.90160049219;20862.791372086387;20982.58034839325;21270.574588508454;21294;889.5107690770051;940.861557515414;951.8905325887612;1013.022433351819;1032.5043978890023;10803.919608039134;10906.61826836057;11234.662960111209;11402;11410.657680269605;531155;590774;593241;599281;605008;119122;121329;122455.38772306082;136555;144097;832.8084540555585;962.3696478806393;997.6558087459815;1013.0679774607231;1015.9136473399695;18670.813291866907;18911.432657020312;18993.43019709411;19093;19104.33134840274;49438;53046.73476632592;53107.468925310255;53339;53793.193102104284;72856.81429557122;80690.59654701689;80869.59565202137;81873.77189342283;82697;6610.636414997221;7714.421418393756;7828.921710782819;7911.960440197762;8045.132927785312;9930.553125109533;12371.072169587136;12380.876191237972;12571.308578028296;12618.495260189857;9939.105480506789;11028.944855275673;11037.779244415227;11038.17213708959;11144.164187686118;10529.842052369373;10746.247762656853;10812.459377031275;11148.498317575566;11215.327080375204;9265.44407335562;11082.279651822928;11155.553777848796;11214.49534770921;11307.208495405243;7794.2205779423875;7815;8150.307223886036;8153.592320383838;8198.622399504315;8613.31093512505;10094.596216151273;10890.401027943539;10933.234673572773;11432.48553815097 10:23:41 INFO - PROCESS | 1651 | |3;mootools.html;1416.3272445588348;1525.2983627531335;1708.7351460523616;1778.7509748635184;1823.7082066869295;7511.098668159815;7817.061952565686;8059.597020148991;8471.449355791869;8503.829923401536;7167.605781682006;8239.958800206006;8261.421700480969;8376.455530390536;8385.161483851613;2270.5345404192185;2282.851614645051;2286.314105768271;2287.2109122352845;2302.4934514406805;2240.09276243121;2277.2257432472957;2283.474800795818;2287.5310561334886;2295.8048565871923;2263.321003698892;2265.833095955584;2287.748347681762;2288.096202000209;2305.804006659436;2213.6458166693333;2234.19568955176;2240.6863039174527;2245.0795173978754;2260.8417410781253;2240.171136679423;2290.5075408787193;2297.425643589103;2302.9654555181687;2315.097112126272;8463.449875758057;8485.915140848574;8494.40539162259;8524.232819046316;8541.701040463555;5101.821436249714;5244.685318880879;5245.370555533361;5262.97368513155;5266.15741481365;3262.200760813616;3338.983305083459;3599.8200089995394;3605.296967091418;3609.6390360963956;1008.6268080810074;1028.5063169678597;1044.17510166968;1059.2955684469819;1059.6609085092696;10752.6774196775;10776.838347424717;10877.67366978992;10898.073663738593;10950.178736594788;4114.868411186948;4462.754548499864;4503.234450143484;4670.229412147026;4699.78850951708;9094.727158185266;9361.485118318558;9396.671116511026;9400.717978460658;9438.622455101857;5199.532042116152;5209.635325527177;5210.452902445264;5220.504052115043;5230.0585894539345;3921.3529767588598;4081.327254407015;4715.481297057321;4798.256270278112;4813.831515896997;3413.992872102736;3721.2557488501866;3752.268307680031;3890.435886796399;3892.8637497687446;1189.8691143974156;1232.2132907284474;1238.0377102292966;1246.3269834289563;1249.881261280177;865.9956700216459;910.4264313482464;911.8723378726984;915.7481692534607;936.8051781214514;5639.915401268984;5670.659760414388;5783.826485205451;5902.940970590325;5966.224390829251;432.26946460481685;438.79595987865156;440.90740944402035;991.306085739985;991.5438898106952;3786.8674596388996;3907.5506316773367;3960.0495880988356;3973.543042550086;3999.7600143991453;9852.655157069612;10082.647107351208;10185.541650625592;10202.28583999113;10213.540390682438;9245.121713437213;9853;10038.548265328078;10108.747281318027;10148.289619726549;7898.565578893101;7909.485883417559;7935.801604959806;7981.401394895406;8031.558264295403;5548.778048878;5636.85907852307;5659.971700141473;5736.5410767139365;5796.768129274782;9253.306002050007;9311.813763724824;9381.953090234505;9411.058894110534;9444.102810233016;9456.385334953206;9539.761505962406;9560.091791279818;9600.807983840425;9670.661526846527;6264.937350626436;6497.5126865484335;6708.396244338033;6832.077669514552;6839.076724642109;7742.8451430969935;7957.8408431832195;7992;8122.634481448467;8139.308158806568;6917.169939607279;7022.859542809218;7035.050268213726;7071.858562828817;7145.285471453021;6476;7027.789166325018;7044.964775176091;7054.611996340251;7097.467689923174;6622.238442578972;6675.732970681119;6747.763828265987;6758.053872457762;6767.120274364301;4872.634552408513;4895.779689913891;4919.926201106915;4966.428860681068;4988.1270777613045;3997.7201595889173;4669.346291519122;4724.503927087744;4818.807247710192;4841.975790121168 10:23:41 INFO - PROCESS | 1651 | |4;prototype.html;274.7307439628915;288.57723435167463;292.1906319494999;300.23290492790915;304.9268175637846;5254.6847189168675;5340.359156901177;5488.572971027532;5536.529395001425;5542.4457554244555;4823.20417131173;5205.531502164805;5324.494173053554;5447.046766815802;5482.205080263359;3611.7110631149444;3685.4656074869126;4137.586241375853;4170.958290417103;4198.475190601175;3833.936027810516;4132.814023368941;4199.727017743852;4209.810558524874;4212.473440819897;3424.708899743525;4079.9184016319655;4081.7755023473696;4094.75431474111;4103.281925662997;3279.8688052477873;3754;4069.776162311072;4099.877003689894;4125.360569111763;3087.7529797616135;3171;3531;3550.4496802995577;3578.588462326814;5107.055194788976;5263.578913686896;5292.364916210041;5297.496737809902;5325.813596524103;4019.738716983387;4661.557152070548;4671.602913752336;4689.671722979393;4694.483606803249;5723.627964182315;5723.942760572424;5733;5751.913721294184;5755.338136114358;3759.5676497202626;3768.812823960444;3831.8084095795098;3892.552356479013;3916.1398335790554;5560.36055853578;5641.830745077655;6040.124181993587;6081.939180608181;6108.816735497942;3940.1875137436527;3943.3296339622334;3945.4870866787132;3951.920961580752;3959.425883246913;3376.2909788944107;3758.342290099222;3970.7617542947514;3972.1658451724884;3989.2221016901426;4661.300804879295;4666.929996050062;4686.6485013624115;4690.437147542317;4711.67018308722;5611.831645050655;5805.709714514257;5856.677882716492;5864.237649105674;5907.468327850428;1195.6711904226406;1266.2718936611302;1282.7178020835402;1304.2630913534013;1325.627975045823;876.0626130040795;889.5952341684518;894.3069121430943;897.9640814367483;907.4328544659588;4791.401074865642;5359.410464848864;5389.326334208224;5399.163129714901;5412.02461101695;4892.633052521075;5014.222795466709;5175.1628171999155;5435.347758268954;5820.708964551755;4557.6581756368405;4842.530309542159;5144.254083157921;5361.043219224955;5380.757865896044;2479.652848601197;2550.3624093976505;2605.59297979093;2615.6991945926084;2648.5232658121336;4372.650187984954;4740.95259047412;4756.382829837872;4959.321966138317;5154.5103215194495;4649.930515981343;4741.549552792549;4853.539767761268;5058.317127187856;5087.97456012725;4802.73584952831;5078.13671675816;5091.439941606421;5126.6923984561045;5185.792568297225;4199.916001680011;4368.121707665734;4831.492693267156;4861.95138048615;4926.433460152129;4363.6072753452345;4696.66145148631;4888.706677599355;4893.7797799099835;4898.338724272177;4130.421740956329;4562.61217796491;4820.566149046463;5060.367454068241;5141.614378921521;3026.576279320853;3103.844807759648;3323.900282991514;3396.7792093513845;3417.94873076909;4247.920257813184;4464.598186163261;4943;4995.375578052744;5755.7697692093525;4572.771361431848;5637.802676906289;5756.539476841759;5799.652020878761;5837.6497410155525;5796.608983744557;5812.738426770721;5821.446962538552;5854;5885.175115944457;4316.978415107905;4952.826651067196;5067;5176.421191536618;5677.659340439587;4574.725516469022;4787.521247875185;5122.436531981404;5596.38439771633;5874.471297583238;5682.863427314636;5719.656820590778;5767.2502574666705;5783.913241301468;5806.709664516842 10:23:41 INFO - PROCESS | 1651 | |5;yui.html;680.819582810555;709;730.565313638385;769.20387399042;809.5102463009879;14397.568072957802;15106.471273505429;15407.537773866774;15434.768478472832;15492.845071549282;13843.892488600914;14036.929815350935;14053;14058.578242652711;14089.859101408983;653.0954627840451;718.335539625844;860.3934226370394;878.5080355001204;884.8672699095123;889.4174315823146;892.5849479991803;904.1320332480826;906.1346414174448;906.6645341223757;821.2854816309789;922.8108237811266;955.2262667239523;959.1463597398321;961.995190024049;934.6541779541614;956.3687965942514;957.0955447102491;959.0792838874654;959.2517836087828;703.4161645833946;708.8440543080515;710.2790667472519;710.7974227345181;713.0231582731639;12492.312922789242;14903.552893413214;15292.158931258777;15313.46402875894;15395.230238488142;13996.230207338593;14145.363458644282;14259;14284.428622855074;14352.282385880664;11423.314601123959;11470.942645286721;11595.130365222558;11838.230515016496;11857;3309.973908088476;3720.21875406165;3744.8016634676915;3758.32350176968;3773.2453509297975;86027.70958435691;93226.13547729075;99373;102165.4891725544;103672;10176.949115254376;10769.62306319275;11018.11855051594;11098.389588572545;11226.326420414802;22488.77511224867;22594.435139121324;22608.434789130075;22631.773682263294;22641.4339641507;7762.262585054411;7825.100113487022;7892;7976.202379762093;8067.39494537912;4106.507219133723;4195.160967806451;4205.768682722419;4207.579242075829;4209.831606735696;3050.7559395248327;3437.05480992724;3505.2288496530723;3518.20840310928;3538.274653695986;633.9270983836825;642.6272761798145;646.7606985415426;652.6736631684158;658.8897707363013;546.6528754241085;580.5181699189662;616.202018386191;626.7712285015937;680.3264767879863;8886.688965886164;9001.819963600823;9198.67804626835;9258.120478554527;9299.53502324881;618.9725056406344;645.5404907303097;656.3174298729268;661.3650895140622;670.9563878347892;4329.415528903621;4350.651947844165;4507.1745942893185;4561.042181141997;4632.050429661978;105840.47079764552;106934.93065069405;113821.430892845;113925;121214;66401.6679916607;92178.61732074025;93265;99987;109151.81696366187;84777.57611211904;86471.70292445619;87611.12388876158;87789.56105219433;89784.10215897758;7543.811404714926;9528.61885524599;9824.754381140528;9899.505024748878;10513.57945682164;40930.38604420877;44752.77623611861;49104;56820.431795681514;58312.70843645755;50191.24713129233;75929;79062.20937790548;81618.59190704009;83810.58094709732;11411.60059397917;11796.3512006837;12941.676458088623;13091.386225714523;13665.385057672627;14417.207053612152;14526.636834079232;14716.411343546566;14740.336684848993;14959.77560336574;13109.541166059142;13445.46218151263;13529.661758456117;13698.38357273945;13955.701378322634;13196;13248.271345075727;13406.664833379244;13455.932720336335;13677;12831.739524146049;13037.044393683658;13070.477180912658;13337.799933001206;13882.791758123838;10746.731331716457;10831.51258193399;11086.334819910831;11178.552857885594;11435.428228588371;8717.607707653044;9128.224100951227;9914.504274786375;11235.66293011211;11299.548018079186 10:23:41 INFO - PROCESS | 1651 | __end_tp_report 10:23:41 INFO - PROCESS | 1651 | __start_cc_report 10:23:41 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,362 10:23:41 INFO - PROCESS | 1651 | __end_cc_report 10:23:41 INFO - PROCESS | 1651 | __startTimestamp1477675421630__endTimestamp 10:23:41 INFO - PROCESS | 1651 | 10:23:41 INFO - PROCESS | 1651 | ------- Summary: start ------- 10:23:41 INFO - PROCESS | 1651 | Number of tests: 6 10:23:41 INFO - PROCESS | 1651 | 10:23:41 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5656.68 Median:5799.30 stddev:676.19 (11.7%) stddev-sans-first:654.68 10:23:41 INFO - PROCESS | 1651 | Values: 3400.2 3417.8 3442.1 3443.9 3447.2 5613.8 5707.9 5782.8 5786.7 5811.3 5600.9 5675.5 5746.6 5748.8 5768.3 4445.5 4837.2 5211.6 5255.3 5288.1 5230.1 5322.9 5335.6 5356.6 5372.5 5132.8 5189.9 5290.3 5308.1 5344.8 5220.2 5241.1 5242.9 5251.1 5336.8 5165.0 5166.3 5183.7 5188.8 5219.8 4933.5 5021.0 5660.0 5691.6 5696.8 5164.6 6186.3 6212.1 6213.4 6218.1 6208.3 6230.6 6245.1 6245.9 6257.1 5715.7 5732.2 5798.1 5799.9 5820.9 6236.5 6264.2 6294.9 6296.9 6305.5 6143.6 6200.9 6203.8 6221.6 6221.9 6115.8 6168.6 6171.9 6233.8 6245.8 6063.2 6094.9 6148.4 6221.5 6246.2 5101.9 5545.6 5820.9 6264.5 6297.1 4546.8 4773.7 4843.0 4976.8 5008.6 3784.9 3979.6 4151.1 4361.3 4363.0 4861.3 5231.2 5391.3 5799.3 5806.4 6291.6 6292.5 6299.2 6299.3 6305.9 5608.6 5685.6 5707.2 5732.9 5778.3 5392.9 5396.1 5410.5 5411.5 5438.3 5744.5 6308.9 6309.8 6324.8 6331.7 5582.5 5961.9 6111.9 6177.0 6213.8 5113.7 5807.7 6305.3 6326.0 6328.5 6234.2 6258.8 6273.4 6275.2 6276.9 6127.7 6208.3 6215.1 6258.0 6263.8 6094.5 6137.3 6153.0 6178.8 6202.7 6103.5 6108.5 6185.5 6191.8 6193.2 4861.6 5552.8 5650.9 5896.2 5907.1 4445.7 5098.2 6106.1 6121.2 6142.6 5205.5 5924.9 5958.9 5974.7 5976.8 10:23:41 INFO - PROCESS | 1651 | 10:23:41 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:68816.86 Median:41015.90 stddev:148376.08 (361.8%) stddev-sans-first:148703.58 10:23:41 INFO - PROCESS | 1651 | Values: 471.3 480.0 533.9 538.6 544.1 39670.0 41435.4 41466.6 41624.0 43868.0 42904.0 43361.0 43421.0 43518.9 43531.1 9568.1 9701.3 9703.8 9754.1 9768.9 9541.1 9618.9 9624.9 9648.8 9650.1 9630.2 9638.4 9655.5 9664.3 9687.3 9557.6 9587.9 9643.2 9645.4 9670.0 9431.1 9486.2 9501.1 9505.2 9538.6 40285.6 40819.4 41001.8 41030.0 41279.6 3567.6 3576.7 3673.8 3821.5 4062.1 30332.5 31046.4 31275.7 31637.4 31687.2 3180.3 3279.9 3308.8 3339.3 3366.6 439280.0 443917.0 447171.0 447240.8 448606.0 54916.5 55329.4 61052.4 61460.4 61611.0 64154.0 64572.4 64673.0 64681.7 64814.7 15949.2 16068.8 16152.0 16172.4 16193.2 12437.7 12493.4 12529.8 12535.3 12559.4 16105.8 17402.0 17623.9 17678.1 17757.0 3236.9 3283.9 5822.2 7043.0 7094.9 731.7 938.6 964.2 1001.6 1098.9 24264.4 30219.8 30484.1 30770.0 30896.0 894.0 1026.3 1036.0 1066.0 1101.4 13628.9 14195.0 15355.8 15381.7 15767.2 540814.0 629452.0 635287.0 636019.0 639190.0 51338.0 58695.4 58759.0 59466.1 60206.8 1351.6 1402.2 1412.9 1414.1 1420.3 29412.4 30189.5 30201.7 30610.8 30611.4 51238.5 51366.0 51430.5 51702.0 51777.0 585710.0 587845.0 589673.0 589795.0 590928.0 26965.8 27046.9 27305.6 27405.0 27476.5 46665.5 47567.0 47826.0 48844.8 49005.5 34991.0 38197.6 38242.0 40582.5 41092.6 39066.4 39739.8 39856.6 40098.0 40886.6 38397.4 38585.8 38595.0 38806.8 38945.6 33148.3 34991.3 35132.8 35454.8 35714.0 23880.0 25782.4 25903.0 25904.6 26410.0 10:23:41 INFO - PROCESS | 1651 | 10:23:41 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:34591.30 Median:2197.77 stddev:98007.42 (4459.4%) stddev-sans-first:98248.62 10:23:41 INFO - PROCESS | 1651 | Values: 339.5 356.9 357.9 371.2 384.9 19619.1 20072.9 20298.0 20874.0 20983.3 20401.9 20667.6 20772.0 21041.4 21273.8 4838.8 5627.2 5762.0 5851.4 6439.5 6386.5 6489.8 6546.8 6569.3 6575.7 4461.8 5947.0 5954.2 6025.5 6471.7 5774.6 5921.3 5962.4 5993.7 5994.8 5192.8 5212.7 5216.4 5233.0 5234.8 12642.2 12878.7 18334.8 19920.5 24643.1 2069.4 2145.5 2156.0 2239.5 2511.5 9888.0 10073.8 10494.7 13947.4 15641.3 1782.6 1785.2 1818.8 1838.3 1924.2 118712.4 126256.0 127495.4 128066.0 129623.1 7989.7 10661.7 10781.4 10818.4 10954.1 11838.2 12110.3 14291.8 14324.3 14325.7 7319.0 7609.2 8337.2 8396.1 8411.7 6993.0 7867.7 8100.8 8147.8 8264.3 3777.8 4288.6 4401.4 4425.8 4481.3 1629.5 1632.2 1853.0 1889.5 1905.6 795.1 852.7 860.9 985.6 1000.9 19679.9 20862.8 20982.6 21270.6 21294.0 889.5 940.9 951.9 1013.0 1032.5 10803.9 10906.6 11234.7 11402.0 11410.7 531155.0 590774.0 593241.0 599281.0 605008.0 119122.0 121329.0 122455.4 136555.0 144097.0 832.8 962.4 997.7 1013.1 1015.9 18670.8 18911.4 18993.4 19093.0 19104.3 49438.0 53046.7 53107.5 53339.0 53793.2 72856.8 80690.6 80869.6 81873.8 82697.0 6610.6 7714.4 7828.9 7912.0 8045.1 9930.6 12371.1 12380.9 12571.3 12618.5 9939.1 11028.9 11037.8 11038.2 11144.2 10529.8 10746.2 10812.5 11148.5 11215.3 9265.4 11082.3 11155.6 11214.5 11307.2 7794.2 7815.0 8150.3 8153.6 8198.6 8613.3 10094.6 10890.4 10933.2 11432.5 10:23:41 INFO - PROCESS | 1651 | 10:23:41 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5428.69 Median:5150.68 stddev:2992.01 (58.1%) stddev-sans-first:2985.20 10:23:41 INFO - PROCESS | 1651 | Values: 1416.3 1525.3 1708.7 1778.8 1823.7 7511.1 7817.1 8059.6 8471.4 8503.8 7167.6 8240.0 8261.4 8376.5 8385.2 2270.5 2282.9 2286.3 2287.2 2302.5 2240.1 2277.2 2283.5 2287.5 2295.8 2263.3 2265.8 2287.7 2288.1 2305.8 2213.6 2234.2 2240.7 2245.1 2260.8 2240.2 2290.5 2297.4 2303.0 2315.1 8463.4 8485.9 8494.4 8524.2 8541.7 5101.8 5244.7 5245.4 5263.0 5266.2 3262.2 3339.0 3599.8 3605.3 3609.6 1008.6 1028.5 1044.2 1059.3 1059.7 10752.7 10776.8 10877.7 10898.1 10950.2 4114.9 4462.8 4503.2 4670.2 4699.8 9094.7 9361.5 9396.7 9400.7 9438.6 5199.5 5209.6 5210.5 5220.5 5230.1 3921.4 4081.3 4715.5 4798.3 4813.8 3414.0 3721.3 3752.3 3890.4 3892.9 1189.9 1232.2 1238.0 1246.3 1249.9 866.0 910.4 911.9 915.7 936.8 5639.9 5670.7 5783.8 5902.9 5966.2 432.3 438.8 440.9 991.3 991.5 3786.9 3907.6 3960.0 3973.5 3999.8 9852.7 10082.6 10185.5 10202.3 10213.5 9245.1 9853.0 10038.5 10108.7 10148.3 7898.6 7909.5 7935.8 7981.4 8031.6 5548.8 5636.9 5660.0 5736.5 5796.8 9253.3 9311.8 9382.0 9411.1 9444.1 9456.4 9539.8 9560.1 9600.8 9670.7 6264.9 6497.5 6708.4 6832.1 6839.1 7742.8 7957.8 7992.0 8122.6 8139.3 6917.2 7022.9 7035.1 7071.9 7145.3 6476.0 7027.8 7045.0 7054.6 7097.5 6622.2 6675.7 6747.8 6758.1 6767.1 4872.6 4895.8 4919.9 4966.4 4988.1 3997.7 4669.3 4724.5 4818.8 4842.0 10:23:41 INFO - PROCESS | 1651 | 10:23:41 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4401.62 Median:4821.89 stddev:1362.81 (28.3%) stddev-sans-first:1330.96 10:23:41 INFO - PROCESS | 1651 | Values: 274.7 288.6 292.2 300.2 304.9 5254.7 5340.4 5488.6 5536.5 5542.4 4823.2 5205.5 5324.5 5447.0 5482.2 3611.7 3685.5 4137.6 4171.0 4198.5 3833.9 4132.8 4199.7 4209.8 4212.5 3424.7 4079.9 4081.8 4094.8 4103.3 3279.9 3754.0 4069.8 4099.9 4125.4 3087.8 3171.0 3531.0 3550.4 3578.6 5107.1 5263.6 5292.4 5297.5 5325.8 4019.7 4661.6 4671.6 4689.7 4694.5 5723.6 5723.9 5733.0 5751.9 5755.3 3759.6 3768.8 3831.8 3892.6 3916.1 5560.4 5641.8 6040.1 6081.9 6108.8 3940.2 3943.3 3945.5 3951.9 3959.4 3376.3 3758.3 3970.8 3972.2 3989.2 4661.3 4666.9 4686.6 4690.4 4711.7 5611.8 5805.7 5856.7 5864.2 5907.5 1195.7 1266.3 1282.7 1304.3 1325.6 876.1 889.6 894.3 898.0 907.4 4791.4 5359.4 5389.3 5399.2 5412.0 4892.6 5014.2 5175.2 5435.3 5820.7 4557.7 4842.5 5144.3 5361.0 5380.8 2479.7 2550.4 2605.6 2615.7 2648.5 4372.7 4741.0 4756.4 4959.3 5154.5 4649.9 4741.5 4853.5 5058.3 5088.0 4802.7 5078.1 5091.4 5126.7 5185.8 4199.9 4368.1 4831.5 4862.0 4926.4 4363.6 4696.7 4888.7 4893.8 4898.3 4130.4 4562.6 4820.6 5060.4 5141.6 3026.6 3103.8 3323.9 3396.8 3417.9 4247.9 4464.6 4943.0 4995.4 5755.8 4572.8 5637.8 5756.5 5799.7 5837.6 5796.6 5812.7 5821.4 5854.0 5885.2 4317.0 4952.8 5067.0 5176.4 5677.7 4574.7 4787.5 5122.4 5596.4 5874.5 5682.9 5719.7 5767.3 5783.9 5806.7 10:23:41 INFO - PROCESS | 1651 | 10:23:41 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:20935.96 Median:4208.71 stddev:30757.57 (730.8%) stddev-sans-first:30806.25 10:23:41 INFO - PROCESS | 1651 | Values: 680.8 709.0 730.6 769.2 809.5 14397.6 15106.5 15407.5 15434.8 15492.8 13843.9 14036.9 14053.0 14058.6 14089.9 653.1 718.3 860.4 878.5 884.9 889.4 892.6 904.1 906.1 906.7 821.3 922.8 955.2 959.1 962.0 934.7 956.4 957.1 959.1 959.3 703.4 708.8 710.3 710.8 713.0 12492.3 14903.6 15292.2 15313.5 15395.2 13996.2 14145.4 14259.0 14284.4 14352.3 11423.3 11470.9 11595.1 11838.2 11857.0 3310.0 3720.2 3744.8 3758.3 3773.2 86027.7 93226.1 99373.0 102165.5 103672.0 10176.9 10769.6 11018.1 11098.4 11226.3 22488.8 22594.4 22608.4 22631.8 22641.4 7762.3 7825.1 7892.0 7976.2 8067.4 4106.5 4195.2 4205.8 4207.6 4209.8 3050.8 3437.1 3505.2 3518.2 3538.3 633.9 642.6 646.8 652.7 658.9 546.7 580.5 616.2 626.8 680.3 8886.7 9001.8 9198.7 9258.1 9299.5 619.0 645.5 656.3 661.4 671.0 4329.4 4350.7 4507.2 4561.0 4632.1 105840.5 106934.9 113821.4 113925.0 121214.0 66401.7 92178.6 93265.0 99987.0 109151.8 84777.6 86471.7 87611.1 87789.6 89784.1 7543.8 9528.6 9824.8 9899.5 10513.6 40930.4 44752.8 49104.0 56820.4 58312.7 50191.2 75929.0 79062.2 81618.6 83810.6 11411.6 11796.4 12941.7 13091.4 13665.4 14417.2 14526.6 14716.4 14740.3 14959.8 13109.5 13445.5 13529.7 13698.4 13955.7 13196.0 13248.3 13406.7 13455.9 13677.0 12831.7 13037.0 13070.5 13337.8 13882.8 10746.7 10831.5 11086.3 11178.6 11435.4 8717.6 9128.2 9914.5 11235.7 11299.5 10:23:41 INFO - PROCESS | 1651 | -------- Summary: end -------- 10:23:41 INFO - PROCESS | 1651 | 10:23:42 INFO - TEST-INFO | 1651: exit 0 10:23:42 INFO - TEST-OK | dromaeo_css | took 1116413ms 10:23:42 INFO - TEST-START | kraken 10:23:42 INFO - operating with platform_type : mac_ 10:23:42 INFO - Initialising browser for kraken test... 10:23:42 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpaGpABn/profile http://localhost:49266/getInfo.html) 10:23:44 INFO - PROCESS | 1659 | 2016-10-28 10:23:44.887 plugin-container[1660:9832] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 10:23:44 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:23:44 INFO - PROCESS | 1659 | 2016-10-28 10:23:44.890 plugin-container[1660:9832] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e13, name = 'com.apple.CFPasteboardClient' 10:23:44 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:23:44 INFO - PROCESS | 1659 | 2016-10-28 10:23:44.890 plugin-container[1660:9832] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:23:44 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 10:23:44 INFO - PROCESS | 1659 | colorDepth:24 10:23:44 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 10:23:44 INFO - PROCESS | 1659 | __metrics 10:23:45 ERROR - PROCESS | 1659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 10:23:45 INFO - TEST-INFO | 1659: exit 0 10:23:45 INFO - Browser initialized. 10:23:45 INFO - Running cycle 1/1 for kraken test... 10:23:45 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpaGpABn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:23:48 INFO - PROCESS | 1661 | 2016-10-28 10:23:48.077 plugin-container[1662:9944] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863b, name = 'com.apple.tsm.portname' 10:23:48 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:23:48 INFO - PROCESS | 1661 | 2016-10-28 10:23:48.077 plugin-container[1662:9944] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 10:23:48 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:23:48 INFO - PROCESS | 1661 | 2016-10-28 10:23:48.077 plugin-container[1662:9944] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:23:57 INFO - PROCESS | 1661 | RSS: Main: 236208128 10:23:57 INFO - PROCESS | 1661 | 10:24:01 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 10:24:02 INFO - PROCESS | 1661 | RSS: Main: 248905728 10:24:02 INFO - PROCESS | 1661 | 10:24:04 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 10:24:05 INFO - PROCESS | 1661 | RSS: Main: 257482752 10:24:05 INFO - PROCESS | 1661 | 10:24:08 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 10:24:09 INFO - PROCESS | 1661 | RSS: Main: 259653632 10:24:09 INFO - PROCESS | 1661 | 10:24:11 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 10:24:11 INFO - PROCESS | 1661 | RSS: Main: 261906432 10:24:11 INFO - PROCESS | 1661 | 10:24:13 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:24:14 INFO - PROCESS | 1661 | RSS: Main: 262160384 10:24:14 INFO - PROCESS | 1661 | 10:24:17 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 10:24:17 INFO - PROCESS | 1661 | RSS: Main: 262787072 10:24:17 INFO - PROCESS | 1661 | 10:24:20 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 10:24:21 INFO - PROCESS | 1661 | RSS: Main: 262922240 10:24:21 INFO - PROCESS | 1661 | 10:24:24 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 10:24:24 INFO - PROCESS | 1661 | RSS: Main: 263335936 10:24:24 INFO - PROCESS | 1661 | 10:24:26 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:24:26 INFO - PROCESS | 1661 | RSS: Main: 262926336 10:24:26 INFO - PROCESS | 1661 | 10:24:29 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:24:29 INFO - PROCESS | 1661 | RSS: Main: 262918144 10:24:29 INFO - PROCESS | 1661 | 10:24:31 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:24:32 INFO - PROCESS | 1661 | RSS: Main: 263004160 10:24:32 INFO - PROCESS | 1661 | 10:24:35 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:24:36 INFO - PROCESS | 1661 | RSS: Main: 263303168 10:24:36 INFO - PROCESS | 1661 | 10:24:39 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:24:39 INFO - PROCESS | 1661 | RSS: Main: 263323648 10:24:39 INFO - PROCESS | 1661 | 10:24:41 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 10:24:41 INFO - PROCESS | 1661 | RSS: Main: 265015296 10:24:41 INFO - PROCESS | 1661 | 10:24:41 INFO - PROCESS | 1661 | __start_tp_report 10:24:41 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 10:24:41 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 10:24:41 INFO - PROCESS | 1661 | |i|pagename|runs| 10:24:41 INFO - PROCESS | 1661 | |0;ai-astar;108;113;119;169;170;154;127;141;151;118 10:24:41 INFO - PROCESS | 1661 | |1;audio-beat-detection;142;127;115;154;113;117;129;114;142;109 10:24:41 INFO - PROCESS | 1661 | |2;audio-dft;250;205;198;202;200;199;195;197;201;196 10:24:41 INFO - PROCESS | 1661 | |3;audio-fft;82;66;60;86;81;60;59;59;80;61 10:24:41 INFO - PROCESS | 1661 | |4;audio-oscillator;83;71;88;74;69;71;86;72;88;95 10:24:41 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;89;104;90;88;92;87;92;88;89;87 10:24:41 INFO - PROCESS | 1661 | |6;imaging-darkroom;95;95;97;95;96;97;108;153;122;96 10:24:41 INFO - PROCESS | 1661 | |7;imaging-desaturate;72;116;138;138;89;88;92;87;89;88 10:24:41 INFO - PROCESS | 1661 | |8;json-parse-financial;58;57;57;58;59;60;60;60;59;60 10:24:41 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;78;61;75;100;111;106;100;76;69;71 10:24:41 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;93;91;89;89;90;89;89;94;89;85 10:24:41 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;181;177;162;161;159;150;147;151;196;166 10:24:41 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;176;179;171;174;202;227;197;177;176;175 10:24:41 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;71;72;69;71;73;72;69;71;71;68 10:24:41 INFO - PROCESS | 1661 | __end_tp_report 10:24:41 INFO - PROCESS | 1661 | __start_cc_report 10:24:41 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,949 10:24:41 INFO - PROCESS | 1661 | __end_cc_report 10:24:41 INFO - PROCESS | 1661 | __startTimestamp1477675481895__endTimestamp 10:24:41 INFO - PROCESS | 1661 | 10:24:41 INFO - PROCESS | 1661 | ------- Summary: start ------- 10:24:41 INFO - PROCESS | 1661 | Number of tests: 14 10:24:41 INFO - PROCESS | 1661 | 10:24:41 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:137.00 Median:146.00 stddev:23.13 (15.8%) stddev-sans-first:22.03 10:24:41 INFO - PROCESS | 1661 | Values: 108.0 113.0 119.0 169.0 170.0 154.0 127.0 141.0 151.0 118.0 10:24:41 INFO - PROCESS | 1661 | 10:24:41 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:126.20 Median:128.00 stddev:15.31 (12.0%) stddev-sans-first:15.13 10:24:41 INFO - PROCESS | 1661 | Values: 142.0 127.0 115.0 154.0 113.0 117.0 129.0 114.0 142.0 109.0 10:24:41 INFO - PROCESS | 1661 | 10:24:41 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:204.30 Median:200.50 stddev:16.33 (8.1%) stddev-sans-first:3.15 10:24:41 INFO - PROCESS | 1661 | Values: 250.0 205.0 198.0 202.0 200.0 199.0 195.0 197.0 201.0 196.0 10:24:41 INFO - PROCESS | 1661 | 10:24:41 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:69.40 Median:73.00 stddev:11.34 (15.5%) stddev-sans-first:11.07 10:24:41 INFO - PROCESS | 1661 | Values: 82.0 66.0 60.0 86.0 81.0 60.0 59.0 59.0 80.0 61.0 10:24:41 INFO - PROCESS | 1661 | 10:24:41 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:79.70 Median:84.50 stddev:9.31 (11.0%) stddev-sans-first:9.80 10:24:41 INFO - PROCESS | 1661 | Values: 83.0 71.0 88.0 74.0 69.0 71.0 86.0 72.0 88.0 95.0 10:24:41 INFO - PROCESS | 1661 | 10:24:41 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:90.60 Median:89.00 stddev:5.04 (5.7%) stddev-sans-first:5.31 10:24:41 INFO - PROCESS | 1661 | Values: 89.0 104.0 90.0 88.0 92.0 87.0 92.0 88.0 89.0 87.0 10:24:41 INFO - PROCESS | 1661 | 10:24:41 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:105.40 Median:95.50 stddev:18.83 (19.7%) stddev-sans-first:19.59 10:24:41 INFO - PROCESS | 1661 | Values: 95.0 95.0 97.0 95.0 96.0 97.0 108.0 153.0 122.0 96.0 10:24:41 INFO - PROCESS | 1661 | 10:24:41 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:99.70 Median:88.00 stddev:22.83 (25.9%) stddev-sans-first:21.90 10:24:41 INFO - PROCESS | 1661 | Values: 72.0 116.0 138.0 138.0 89.0 88.0 92.0 87.0 89.0 88.0 10:24:41 INFO - PROCESS | 1661 | 10:24:41 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:59.50 stddev:1.23 (2.1%) stddev-sans-first:1.27 10:24:41 INFO - PROCESS | 1661 | Values: 58.0 57.0 57.0 58.0 59.0 60.0 60.0 60.0 59.0 60.0 10:24:41 INFO - PROCESS | 1661 | 10:24:41 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:84.70 Median:70.00 stddev:17.71 (25.3%) stddev-sans-first:18.62 10:24:41 INFO - PROCESS | 1661 | Values: 78.0 61.0 75.0 100.0 111.0 106.0 100.0 76.0 69.0 71.0 10:24:41 INFO - PROCESS | 1661 | 10:24:41 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:89.50 stddev:2.49 (2.8%) stddev-sans-first:2.35 10:24:41 INFO - PROCESS | 1661 | Values: 93.0 91.0 89.0 89.0 90.0 89.0 89.0 94.0 89.0 85.0 10:24:41 INFO - PROCESS | 1661 | 10:24:41 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:165.00 Median:164.00 stddev:15.52 (9.5%) stddev-sans-first:15.34 10:24:41 INFO - PROCESS | 1661 | Values: 181.0 177.0 162.0 161.0 159.0 150.0 147.0 151.0 196.0 166.0 10:24:41 INFO - PROCESS | 1661 | 10:24:41 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.40 Median:178.00 stddev:17.87 (10.0%) stddev-sans-first:18.63 10:24:41 INFO - PROCESS | 1661 | Values: 176.0 179.0 171.0 174.0 202.0 227.0 197.0 177.0 176.0 175.0 10:24:41 INFO - PROCESS | 1661 | 10:24:41 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.70 Median:71.00 stddev:1.57 (2.2%) stddev-sans-first:1.66 10:24:41 INFO - PROCESS | 1661 | Values: 71.0 72.0 69.0 71.0 73.0 72.0 69.0 71.0 71.0 68.0 10:24:41 INFO - PROCESS | 1661 | -------- Summary: end -------- 10:24:41 INFO - PROCESS | 1661 | 10:24:42 INFO - TEST-INFO | 1661: exit 0 10:24:42 INFO - TEST-OK | kraken | took 60461ms 10:24:42 INFO - SUITE-END | took 1176s 10:24:43 INFO - Completed test suite (00:19:37) 10:24:43 INFO - javascript benchmark 10:24:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7158.473934183156, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3400.1839558505967, 3417.8120203388817, 3442.053435305292, 3443.8966830995064, 3447.1726785571464, 5613.775448982046, 5707.857303567413, 5782.843431313739, 5786.739596718147, 5811.331696854852, 5600.943990560093, 5675.460831221028, 5746.626469279504, 5748.827535173951, 5768.250127483432, 4445.533219012005, 4837.177679794443, 5211.557017653485, 5255.264263003182, 5288.0745869472885, 5230.137027390476, 5322.92015619766, 5335.599830012745, 5356.625036247464, 5372.462753724635, 5132.794688212467, 5189.948100518985, 5290.285811415448, 5308.071087559701, 5344.759485823147, 5220.1647736362, 5241.0828105081455, 5242.868928276824, 5251.133562962106, 5336.759845806948, 5164.9670065986575, 5166.302549155854, 5183.663061900965, 5188.766505507261, 5219.765110569995, 4933.481984391623, 5020.970701006285, 5659.971700141473, 5691.573132015115, 5696.772129114872, 5164.561012313958, 6186.288576813679, 6212.099245609406, 6213.409726076015, 6218.129461875341, 6208.286047104596, 6230.626162430223, 6245.094461303085, 6245.906311405332, 6257.092721555395, 5715.714214289269, 5732.226149469851, 5798.101294299432, 5799.9130013049835, 5820.94179058204, 6236.501079913596, 6264.1856558648, 6294.905576416358, 6296.937030629727, 6305.495560355161, 6143.56995010354, 6200.875982480326, 6203.844903877439, 6221.626702397871, 6221.875562488724, 6115.755369785159, 6168.599041062316, 6171.876562468726, 6233.812985610439, 6245.812625621239, 6063.242094738158, 6094.864090282533, 6148.354422785633, 6221.471174950089, 6246.187995560548, 5101.9489805101475, 5545.584081193846, 5820.883582328414, 6264.5301602380005, 6297.149884765589, 4546.795394207269, 4773.689710168897, 4843.0, 4976.776045077908, 5008.574271186917, 3784.9402167393173, 3979.5622481527007, 4151.14901445203, 4361.345798130243, 4363.040131171138, 4861.343718598015, 5231.163013917755, 5391.32608423947, 5799.3040835100055, 5806.44838740319, 6291.622502649856, 6292.4966002719675, 6299.244090709144, 6299.338569450233, 6305.936940630627, 5608.6073974821375, 5685.630434021776, 5707.172459993278, 5732.885342293214, 5778.306603207558, 5392.865178370572, 5396.0556902542685, 5410.48600382963, 5411.540019098342, 5438.320209973754, 5744.454276843693, 6308.905366419415, 6309.779157729366, 6324.778632747923, 6331.683415829282, 5582.469665381782, 5961.91057134152, 6111.926012480351, 6177.0, 6213.751449941951, 5113.744312784272, 5807.738651760596, 6305.306416294112, 6325.96837015812, 6328.525360598066, 6234.220722409699, 6258.780942666985, 6273.37266273369, 6275.215598050244, 6276.905846412399, 6127.662978536223, 6208.255009398901, 6215.098810672517, 6257.9674353731125, 6263.843403914939, 6094.51243900478, 6137.254412462313, 6153.0, 6178.752849885954, 6202.658853762906, 6103.511719062553, 6108.511319094373, 6185.536084793748, 6191.752329906753, 6193.194884664965, 4861.562459378673, 5552.7778888843995, 5650.85872853182, 5896.233489646319, 5907.143464197753, 4445.688801783844, 5098.1842905135, 6106.06632622643, 6121.239585875057, 6142.590862071414, 5205.45342739007, 5924.881502369843, 5958.851028724143, 5974.701264936649, 5976.7908123215475], "value": 5615.537537666957, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.2789432168782, 480.00638677936684, 533.8745394832214, 538.5530010091625, 544.1321092856897, 39670.0, 41435.442101368826, 41466.58533414665, 41624.0, 43868.0, 42904.0, 43361.0, 43421.0, 43518.91202719934, 43531.129377412435, 9568.138867501906, 9701.27240456965, 9703.805923881519, 9754.122129008389, 9768.853467197998, 9541.141297283242, 9618.855717164248, 9624.903750962505, 9648.80702385952, 9650.131488166064, 9630.18143457807, 9638.373505722106, 9655.46894920779, 9664.27517936154, 9687.273454490905, 9557.617695292214, 9587.90412095877, 9643.180329671986, 9645.373050751748, 9670.032996700344, 9431.056894310583, 9486.241100711995, 9501.144896959238, 9505.19205867502, 9538.570764315622, 40285.59714402848, 40819.38770918438, 41001.794991025155, 41030.0, 41279.55521556761, 3567.554055743032, 3576.678098971079, 3673.779573225615, 3821.4840996465678, 4062.065724883264, 30332.54501182484, 31046.379072418425, 31275.687243127733, 31637.367252655047, 31687.20781980446, 3180.3480286541435, 3279.918002049968, 3308.7849289796327, 3339.2653616204398, 3366.5791776027995, 439280.0, 443917.0, 447171.0, 447240.7637961822, 448606.0, 54916.45083549193, 55329.446705533235, 61052.38947610467, 61460.38539614636, 61611.0, 64154.0, 64572.354276457576, 64673.0, 64681.67659161674, 64814.67592662007, 15949.20253987296, 16068.839311606967, 16152.030878147349, 16172.433964811411, 16193.190340482928, 12437.689057773629, 12493.375331233401, 12529.812052819216, 12535.31055791922, 12559.372031398576, 16105.758413623806, 17401.955882646827, 17623.911880440515, 17678.11609419524, 17757.0, 3236.8543415546355, 3283.8998935356444, 5822.243108395966, 7043.0, 7094.858102837914, 731.7133956386293, 938.6292414496372, 964.1515466389673, 1001.6143784642819, 1098.8901109889107, 24264.393390165387, 30219.848900755354, 30484.085477435714, 30770.0, 30896.0, 893.9865902011345, 1026.2610920137488, 1035.9899098379171, 1066.0352381095122, 1101.3502033800096, 13628.86371136276, 14195.0, 15355.769663455058, 15381.69255613263, 15767.211639417983, 540814.0, 629452.0, 635287.0, 636019.0, 639190.0, 51338.0, 58695.41304586985, 58759.0, 59466.108008379044, 60206.79586408247, 1351.6283022168982, 1402.2217669193492, 1412.9222892740793, 1414.1091112598997, 1420.2614640386735, 29412.411751765274, 30189.547156792225, 30201.697983019887, 30610.84694576513, 30611.387772244874, 51238.48761512337, 51366.0, 51430.45708628747, 51702.0, 51777.0, 585710.0, 587845.0, 589673.0, 589795.0, 590928.0, 26965.786539606157, 27046.91812327485, 27305.590416143375, 27405.0, 27476.45047099007, 46665.53334466612, 47567.0, 47826.0, 48844.77888052827, 49005.50994490009, 34990.95027149189, 38197.61802381941, 38242.0, 40582.51944630925, 41092.58907410887, 39066.414003789396, 39739.80130099331, 39856.60143398529, 40097.99755006148, 40886.59113408828, 38397.424038638885, 38585.80707096559, 38595.0, 38806.80596596999, 38945.610543894196, 33148.33703325968, 34991.30017399689, 35132.82433587918, 35454.8227258862, 35714.0, 23880.0, 25782.355441114123, 25903.0, 25904.611430828176, 26410.0], "value": 19829.500134859336, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.5229702268313, 356.86260789596014, 357.91052236940754, 371.240812538359, 384.88068698703404, 19619.11713972871, 20072.89963550182, 20298.0, 20874.0, 20983.265585704503, 20401.897990510064, 20667.586648267064, 20772.0, 21041.368758937217, 21273.787262127375, 4838.806447742086, 5627.240322556464, 5761.971190144043, 5851.444112809277, 6439.517036222279, 6386.521010924176, 6489.805305840832, 6546.836329091763, 6569.310222426648, 6575.671216439159, 4461.799219035135, 5947.0, 5954.196183515235, 6025.487833534155, 6471.676416179196, 5774.595778295521, 5921.259842519685, 5962.373950735154, 5993.700314984255, 5994.760209591633, 5192.792288308463, 5212.661177023481, 5216.37403511581, 5233.005728911495, 5234.842954711365, 12642.2414655121, 12878.742425151539, 18334.816651833444, 19920.501987450283, 24643.137490187757, 2069.379186244121, 2145.549434618732, 2156.029786596038, 2239.507308392151, 2511.510255500178, 9888.0, 10073.798524029551, 10494.737631559196, 13947.372368243454, 15641.296141673538, 1782.5721826761614, 1785.1520527749344, 1818.7544681467964, 1838.2922574808795, 1924.201456395594, 118712.40643796812, 126256.0, 127495.36252318679, 128066.0, 129623.05565416526, 7989.7203597873795, 10661.667291588552, 10781.407022613832, 10818.404987725751, 10954.123670106372, 11838.230515016496, 12110.333931633844, 14291.785623215661, 14324.283785810669, 14325.713485730435, 7318.963405183046, 7609.162992070867, 8337.249647531751, 8396.118407567239, 8411.747647570583, 6993.0, 7867.685292588232, 8100.756977290691, 8147.837043259102, 8264.256216940385, 3777.773333599993, 4288.64349415744, 4401.361802538614, 4425.822967081281, 4481.260592002283, 1629.4867116858152, 1632.2491653839368, 1852.990735046316, 1889.5465088378965, 1905.5902980858905, 795.1372760554747, 852.663198036772, 860.9096044915319, 985.6205360936091, 1000.9149222316039, 19679.90160049219, 20862.791372086387, 20982.58034839325, 21270.574588508454, 21294.0, 889.5107690770051, 940.861557515414, 951.8905325887612, 1013.022433351819, 1032.5043978890023, 10803.919608039134, 10906.61826836057, 11234.662960111209, 11402.0, 11410.657680269605, 531155.0, 590774.0, 593241.0, 599281.0, 605008.0, 119122.0, 121329.0, 122455.38772306082, 136555.0, 144097.0, 832.8084540555585, 962.3696478806393, 997.6558087459815, 1013.0679774607231, 1015.9136473399695, 18670.813291866907, 18911.432657020312, 18993.43019709411, 19093.0, 19104.33134840274, 49438.0, 53046.73476632592, 53107.468925310255, 53339.0, 53793.193102104284, 72856.81429557122, 80690.59654701689, 80869.59565202137, 81873.77189342283, 82697.0, 6610.636414997221, 7714.421418393756, 7828.921710782819, 7911.960440197762, 8045.132927785312, 9930.553125109533, 12371.072169587136, 12380.876191237972, 12571.308578028296, 12618.495260189857, 9939.105480506789, 11028.944855275673, 11037.779244415227, 11038.17213708959, 11144.164187686118, 10529.842052369373, 10746.247762656853, 10812.459377031275, 11148.498317575566, 11215.327080375204, 9265.44407335562, 11082.279651822928, 11155.553777848796, 11214.49534770921, 11307.208495405243, 7794.2205779423875, 7815.0, 8150.307223886036, 8153.592320383838, 8198.622399504315, 8613.31093512505, 10094.596216151273, 10890.401027943539, 10933.234673572773, 11432.48553815097], "value": 9331.76131709469, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1416.3272445588348, 1525.2983627531335, 1708.7351460523616, 1778.7509748635184, 1823.7082066869295, 7511.098668159815, 7817.061952565686, 8059.597020148991, 8471.449355791869, 8503.829923401536, 7167.605781682006, 8239.958800206006, 8261.421700480969, 8376.455530390536, 8385.161483851613, 2270.5345404192185, 2282.851614645051, 2286.314105768271, 2287.2109122352845, 2302.4934514406805, 2240.09276243121, 2277.2257432472957, 2283.474800795818, 2287.5310561334886, 2295.8048565871923, 2263.321003698892, 2265.833095955584, 2287.748347681762, 2288.096202000209, 2305.804006659436, 2213.6458166693333, 2234.19568955176, 2240.6863039174527, 2245.0795173978754, 2260.8417410781253, 2240.171136679423, 2290.5075408787193, 2297.425643589103, 2302.9654555181687, 2315.097112126272, 8463.449875758057, 8485.915140848574, 8494.40539162259, 8524.232819046316, 8541.701040463555, 5101.821436249714, 5244.685318880879, 5245.370555533361, 5262.97368513155, 5266.15741481365, 3262.200760813616, 3338.983305083459, 3599.8200089995394, 3605.296967091418, 3609.6390360963956, 1008.6268080810074, 1028.5063169678597, 1044.17510166968, 1059.2955684469819, 1059.6609085092696, 10752.6774196775, 10776.838347424717, 10877.67366978992, 10898.073663738593, 10950.178736594788, 4114.868411186948, 4462.754548499864, 4503.234450143484, 4670.229412147026, 4699.78850951708, 9094.727158185266, 9361.485118318558, 9396.671116511026, 9400.717978460658, 9438.622455101857, 5199.532042116152, 5209.635325527177, 5210.452902445264, 5220.504052115043, 5230.0585894539345, 3921.3529767588598, 4081.327254407015, 4715.481297057321, 4798.256270278112, 4813.831515896997, 3413.992872102736, 3721.2557488501866, 3752.268307680031, 3890.435886796399, 3892.8637497687446, 1189.8691143974156, 1232.2132907284474, 1238.0377102292966, 1246.3269834289563, 1249.881261280177, 865.9956700216459, 910.4264313482464, 911.8723378726984, 915.7481692534607, 936.8051781214514, 5639.915401268984, 5670.659760414388, 5783.826485205451, 5902.940970590325, 5966.224390829251, 432.26946460481685, 438.79595987865156, 440.90740944402035, 991.306085739985, 991.5438898106952, 3786.8674596388996, 3907.5506316773367, 3960.0495880988356, 3973.543042550086, 3999.7600143991453, 9852.655157069612, 10082.647107351208, 10185.541650625592, 10202.28583999113, 10213.540390682438, 9245.121713437213, 9853.0, 10038.548265328078, 10108.747281318027, 10148.289619726549, 7898.565578893101, 7909.485883417559, 7935.801604959806, 7981.401394895406, 8031.558264295403, 5548.778048878, 5636.85907852307, 5659.971700141473, 5736.5410767139365, 5796.768129274782, 9253.306002050007, 9311.813763724824, 9381.953090234505, 9411.058894110534, 9444.102810233016, 9456.385334953206, 9539.761505962406, 9560.091791279818, 9600.807983840425, 9670.661526846527, 6264.937350626436, 6497.5126865484335, 6708.396244338033, 6832.077669514552, 6839.076724642109, 7742.8451430969935, 7957.8408431832195, 7992.0, 8122.634481448467, 8139.308158806568, 6917.169939607279, 7022.859542809218, 7035.050268213726, 7071.858562828817, 7145.285471453021, 6476.0, 7027.789166325018, 7044.964775176091, 7054.611996340251, 7097.467689923174, 6622.238442578972, 6675.732970681119, 6747.763828265987, 6758.053872457762, 6767.120274364301, 4872.634552408513, 4895.779689913891, 4919.926201106915, 4966.428860681068, 4988.1270777613045, 3997.7201595889173, 4669.346291519122, 4724.503927087744, 4818.807247710192, 4841.975790121168], "value": 4367.497781873556, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [274.7307439628915, 288.57723435167463, 292.1906319494999, 300.23290492790915, 304.9268175637846, 5254.6847189168675, 5340.359156901177, 5488.572971027532, 5536.529395001425, 5542.4457554244555, 4823.20417131173, 5205.531502164805, 5324.494173053554, 5447.046766815802, 5482.205080263359, 3611.7110631149444, 3685.4656074869126, 4137.586241375853, 4170.958290417103, 4198.475190601175, 3833.936027810516, 4132.814023368941, 4199.727017743852, 4209.810558524874, 4212.473440819897, 3424.708899743525, 4079.9184016319655, 4081.7755023473696, 4094.75431474111, 4103.281925662997, 3279.8688052477873, 3754.0, 4069.776162311072, 4099.877003689894, 4125.360569111763, 3087.7529797616135, 3171.0, 3531.0, 3550.4496802995577, 3578.588462326814, 5107.055194788976, 5263.578913686896, 5292.364916210041, 5297.496737809902, 5325.813596524103, 4019.738716983387, 4661.557152070548, 4671.602913752336, 4689.671722979393, 4694.483606803249, 5723.627964182315, 5723.942760572424, 5733.0, 5751.913721294184, 5755.338136114358, 3759.5676497202626, 3768.812823960444, 3831.8084095795098, 3892.552356479013, 3916.1398335790554, 5560.36055853578, 5641.830745077655, 6040.124181993587, 6081.939180608181, 6108.816735497942, 3940.1875137436527, 3943.3296339622334, 3945.4870866787132, 3951.920961580752, 3959.425883246913, 3376.2909788944107, 3758.342290099222, 3970.7617542947514, 3972.1658451724884, 3989.2221016901426, 4661.300804879295, 4666.929996050062, 4686.6485013624115, 4690.437147542317, 4711.67018308722, 5611.831645050655, 5805.709714514257, 5856.677882716492, 5864.237649105674, 5907.468327850428, 1195.6711904226406, 1266.2718936611302, 1282.7178020835402, 1304.2630913534013, 1325.627975045823, 876.0626130040795, 889.5952341684518, 894.3069121430943, 897.9640814367483, 907.4328544659588, 4791.401074865642, 5359.410464848864, 5389.326334208224, 5399.163129714901, 5412.02461101695, 4892.633052521075, 5014.222795466709, 5175.1628171999155, 5435.347758268954, 5820.708964551755, 4557.6581756368405, 4842.530309542159, 5144.254083157921, 5361.043219224955, 5380.757865896044, 2479.652848601197, 2550.3624093976505, 2605.59297979093, 2615.6991945926084, 2648.5232658121336, 4372.650187984954, 4740.95259047412, 4756.382829837872, 4959.321966138317, 5154.5103215194495, 4649.930515981343, 4741.549552792549, 4853.539767761268, 5058.317127187856, 5087.97456012725, 4802.73584952831, 5078.13671675816, 5091.439941606421, 5126.6923984561045, 5185.792568297225, 4199.916001680011, 4368.121707665734, 4831.492693267156, 4861.95138048615, 4926.433460152129, 4363.6072753452345, 4696.66145148631, 4888.706677599355, 4893.7797799099835, 4898.338724272177, 4130.421740956329, 4562.61217796491, 4820.566149046463, 5060.367454068241, 5141.614378921521, 3026.576279320853, 3103.844807759648, 3323.900282991514, 3396.7792093513845, 3417.94873076909, 4247.920257813184, 4464.598186163261, 4943.0, 4995.375578052744, 5755.7697692093525, 4572.771361431848, 5637.802676906289, 5756.539476841759, 5799.652020878761, 5837.6497410155525, 5796.608983744557, 5812.738426770721, 5821.446962538552, 5854.0, 5885.175115944457, 4316.978415107905, 4952.826651067196, 5067.0, 5176.421191536618, 5677.659340439587, 4574.725516469022, 4787.521247875185, 5122.436531981404, 5596.38439771633, 5874.471297583238, 5682.863427314636, 5719.656820590778, 5767.2502574666705, 5783.913241301468, 5806.709664516842], "value": 3967.3279618306683, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [680.819582810555, 709.0, 730.565313638385, 769.20387399042, 809.5102463009879, 14397.568072957802, 15106.471273505429, 15407.537773866774, 15434.768478472832, 15492.845071549282, 13843.892488600914, 14036.929815350935, 14053.0, 14058.578242652711, 14089.859101408983, 653.0954627840451, 718.335539625844, 860.3934226370394, 878.5080355001204, 884.8672699095123, 889.4174315823146, 892.5849479991803, 904.1320332480826, 906.1346414174448, 906.6645341223757, 821.2854816309789, 922.8108237811266, 955.2262667239523, 959.1463597398321, 961.995190024049, 934.6541779541614, 956.3687965942514, 957.0955447102491, 959.0792838874654, 959.2517836087828, 703.4161645833946, 708.8440543080515, 710.2790667472519, 710.7974227345181, 713.0231582731639, 12492.312922789242, 14903.552893413214, 15292.158931258777, 15313.46402875894, 15395.230238488142, 13996.230207338593, 14145.363458644282, 14259.0, 14284.428622855074, 14352.282385880664, 11423.314601123959, 11470.942645286721, 11595.130365222558, 11838.230515016496, 11857.0, 3309.973908088476, 3720.21875406165, 3744.8016634676915, 3758.32350176968, 3773.2453509297975, 86027.70958435691, 93226.13547729075, 99373.0, 102165.4891725544, 103672.0, 10176.949115254376, 10769.62306319275, 11018.11855051594, 11098.389588572545, 11226.326420414802, 22488.77511224867, 22594.435139121324, 22608.434789130075, 22631.773682263294, 22641.4339641507, 7762.262585054411, 7825.100113487022, 7892.0, 7976.202379762093, 8067.39494537912, 4106.507219133723, 4195.160967806451, 4205.768682722419, 4207.579242075829, 4209.831606735696, 3050.7559395248327, 3437.05480992724, 3505.2288496530723, 3518.20840310928, 3538.274653695986, 633.9270983836825, 642.6272761798145, 646.7606985415426, 652.6736631684158, 658.8897707363013, 546.6528754241085, 580.5181699189662, 616.202018386191, 626.7712285015937, 680.3264767879863, 8886.688965886164, 9001.819963600823, 9198.67804626835, 9258.120478554527, 9299.53502324881, 618.9725056406344, 645.5404907303097, 656.3174298729268, 661.3650895140622, 670.9563878347892, 4329.415528903621, 4350.651947844165, 4507.1745942893185, 4561.042181141997, 4632.050429661978, 105840.47079764552, 106934.93065069405, 113821.430892845, 113925.0, 121214.0, 66401.6679916607, 92178.61732074025, 93265.0, 99987.0, 109151.81696366187, 84777.57611211904, 86471.70292445619, 87611.12388876158, 87789.56105219433, 89784.10215897758, 7543.811404714926, 9528.61885524599, 9824.754381140528, 9899.505024748878, 10513.57945682164, 40930.38604420877, 44752.77623611861, 49104.0, 56820.431795681514, 58312.70843645755, 50191.24713129233, 75929.0, 79062.20937790548, 81618.59190704009, 83810.58094709732, 11411.60059397917, 11796.3512006837, 12941.676458088623, 13091.386225714523, 13665.385057672627, 14417.207053612152, 14526.636834079232, 14716.411343546566, 14740.336684848993, 14959.77560336574, 13109.541166059142, 13445.46218151263, 13529.661758456117, 13698.38357273945, 13955.701378322634, 13196.0, 13248.271345075727, 13406.664833379244, 13455.932720336335, 13677.0, 12831.739524146049, 13037.044393683658, 13070.477180912658, 13337.799933001206, 13882.791758123838, 10746.731331716457, 10831.51258193399, 11086.334819910831, 11178.552857885594, 11435.428228588371, 8717.607707653044, 9128.224100951227, 9914.504274786375, 11235.66293011211, 11299.548018079186], "value": 7472.680101482962, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 113.0, 119.0, 169.0, 170.0, 154.0, 127.0, 141.0, 151.0, 118.0], "value": 137.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 127.0, 115.0, 154.0, 113.0, 117.0, 129.0, 114.0, 142.0, 109.0], "value": 126.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [250.0, 205.0, 198.0, 202.0, 200.0, 199.0, 195.0, 197.0, 201.0, 196.0], "value": 204.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 66.0, 60.0, 86.0, 81.0, 60.0, 59.0, 59.0, 80.0, 61.0], "value": 69.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 88.0, 74.0, 69.0, 71.0, 86.0, 72.0, 88.0, 95.0], "value": 79.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 104.0, 90.0, 88.0, 92.0, 87.0, 92.0, 88.0, 89.0, 87.0], "value": 90.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 97.0, 95.0, 96.0, 97.0, 108.0, 153.0, 122.0, 96.0], "value": 105.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 116.0, 138.0, 138.0, 89.0, 88.0, 92.0, 87.0, 89.0, 88.0], "value": 99.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 57.0, 58.0, 59.0, 60.0, 60.0, 60.0, 59.0, 60.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 61.0, 75.0, 100.0, 111.0, 106.0, 100.0, 76.0, 69.0, 71.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 89.0, 89.0, 90.0, 89.0, 89.0, 94.0, 89.0, 85.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 177.0, 162.0, 161.0, 159.0, 150.0, 147.0, 151.0, 196.0, 166.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 179.0, 171.0, 174.0, 202.0, 227.0, 197.0, 177.0, 176.0, 175.0], "value": 185.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 69.0, 71.0, 73.0, 72.0, 69.0, 71.0, 71.0, 68.0], "value": 70.7, "unit": "score"}]}]} 10:24:43 INFO - Return code: 0 10:24:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:24:43 ERROR - # TBPL SUCCESS # 10:24:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:24:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:24:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:24:43 INFO - 2016-10-28 10:24:43.237 screenresolution[1666:10326] starting screenresolution argv=screenresolution get 10:24:43 INFO - 2016-10-28 10:24:43.240 screenresolution[1666:10326] Display 0: 1600x1200x32@60 10:24:43 INFO - 2016-10-28 10:24:43.246 screenresolution[1667:10329] starting screenresolution argv=screenresolution list 10:24:43 INFO - Available Modes on Display 0 10:24:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:24:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:24:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:24:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:24:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:24:43 INFO - Intel Iris: 10:24:43 INFO - Chipset Model: Intel Iris 10:24:43 INFO - Type: GPU 10:24:43 INFO - Bus: Built-In 10:24:43 INFO - VRAM (Dynamic, Max): 1536 MB 10:24:43 INFO - Vendor: Intel (0x8086) 10:24:43 INFO - Device ID: 0x0a2e 10:24:43 INFO - Revision ID: 0x0009 10:24:43 INFO - Displays: 10:24:43 INFO - Display: 10:24:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:24:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:24:43 INFO - Display Serial Number: 1600x1200 60 10:24:43 INFO - Main Display: Yes 10:24:43 INFO - Mirror: Off 10:24:43 INFO - Online: Yes 10:24:43 INFO - Rotation: Supported 10:24:43 INFO - Return code: 0 10:24:43 INFO - Running post-action listener: _resource_record_post_action 10:24:43 INFO - [mozharness: 2016-10-28 17:24:43.487738Z] Finished run-tests step (success) 10:24:43 INFO - Running post-run listener: _resource_record_post_run 10:24:43 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 34198016; Write bytes: 708616704; Read time: 399; Write time: 1841 10:24:43 INFO - TinderboxPrint: CPU usage
54.7% 10:24:43 INFO - TinderboxPrint: I/O read bytes / time
34,198,016 / 399 10:24:43 INFO - TinderboxPrint: I/O write bytes / time
708,616,704 / 1,841 10:24:43 INFO - TinderboxPrint: CPU idle
2,198.6 (45.3%) 10:24:43 INFO - TinderboxPrint: CPU system
980.8 (20.2%) 10:24:43 INFO - TinderboxPrint: CPU user
1,670.3 (34.4%) 10:24:43 INFO - TinderboxPrint: Swap in / out
434,909,184 / 0 10:24:43 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 389757440; Write bytes: 382879744; Read time: 29199; Write time: 672 10:24:43 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 33223168; Write bytes: 301955584; Read time: 321; Write time: 1117 10:24:43 INFO - Running post-run listener: _upload_blobber_files 10:24:43 INFO - Blob upload gear active. 10:24:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:24:43 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 10:24:43 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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:24:43 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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:24:44 INFO - (blobuploader) - INFO - Open directory for files ... 10:24:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:24:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:24:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:24:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:24:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:24:45 INFO - (blobuploader) - INFO - Done attempting. 10:24:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:24:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:24:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:24:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:24:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:24:46 INFO - (blobuploader) - INFO - Done attempting. 10:24:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:24:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:24:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:24:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:24:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:24:48 INFO - (blobuploader) - INFO - Done attempting. 10:24:48 INFO - (blobuploader) - INFO - Iteration through files over. 10:24:48 INFO - Return code: 0 10:24:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:24:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:24:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/857835e3738c091a6d8a42d22adbc51f824db9f499215b6f3fcd2c97be19687acc0a9b9f051062415f392044b0cda742821116733914995ac0e96c880d8968a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1885c7540e030ce2e2c2bbf971c64582af52115ef6d78178653116ec2977d6847ba25cb8c63d48e84ef76001fcf038e8fad33149a06d30793dd39fa2150e74fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/a23b9282d2ddba95add5e5d2d3a18124794aafb535f1c40ca0947b9a2e3dd1ea2f18d278ac49a5c4fce30bccf7754097a4b221fde161939003d69e04a5c1a5b1"} 10:24:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:24:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:24:48 INFO - Contents: 10:24:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/857835e3738c091a6d8a42d22adbc51f824db9f499215b6f3fcd2c97be19687acc0a9b9f051062415f392044b0cda742821116733914995ac0e96c880d8968a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1885c7540e030ce2e2c2bbf971c64582af52115ef6d78178653116ec2977d6847ba25cb8c63d48e84ef76001fcf038e8fad33149a06d30793dd39fa2150e74fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/a23b9282d2ddba95add5e5d2d3a18124794aafb535f1c40ca0947b9a2e3dd1ea2f18d278ac49a5c4fce30bccf7754097a4b221fde161939003d69e04a5c1a5b1"} 10:24:48 INFO - Running post-run listener: copy_logs_to_upload_dir 10:24:48 INFO - Copying logs to upload dir... 10:24:48 INFO - mkdir: /builds/slave/test/build/upload/logs 10:24:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.514659 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-28 10:24:48.816808) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-28 10:24:48.820858) ========= 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.rSjeB4LOvp/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.F5EyYT32Dg/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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/Cedar/sha512/857835e3738c091a6d8a42d22adbc51f824db9f499215b6f3fcd2c97be19687acc0a9b9f051062415f392044b0cda742821116733914995ac0e96c880d8968a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1885c7540e030ce2e2c2bbf971c64582af52115ef6d78178653116ec2977d6847ba25cb8c63d48e84ef76001fcf038e8fad33149a06d30793dd39fa2150e74fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/a23b9282d2ddba95add5e5d2d3a18124794aafb535f1c40ca0947b9a2e3dd1ea2f18d278ac49a5c4fce30bccf7754097a4b221fde161939003d69e04a5c1a5b1"} build_url:https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010938 build_url: 'https://queue.taskcluster.net/v1/task/b3fK1-8mS3m8y2490jO9Ig/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/857835e3738c091a6d8a42d22adbc51f824db9f499215b6f3fcd2c97be19687acc0a9b9f051062415f392044b0cda742821116733914995ac0e96c880d8968a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1885c7540e030ce2e2c2bbf971c64582af52115ef6d78178653116ec2977d6847ba25cb8c63d48e84ef76001fcf038e8fad33149a06d30793dd39fa2150e74fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/a23b9282d2ddba95add5e5d2d3a18124794aafb535f1c40ca0947b9a2e3dd1ea2f18d278ac49a5c4fce30bccf7754097a4b221fde161939003d69e04a5c1a5b1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-28 10:24:48.853717) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 10:24:48.854082) ========= 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.rSjeB4LOvp/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.F5EyYT32Dg/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.004794 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 10:24:48.902878) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-28 10:24:48.903268) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-28 10:24:48.903868) ========= ========= Total master_lag: 0.17 =========