builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0093 starttime: 1479958569.99 results: success (0) buildid: 20161123171736 builduid: 14d0c88e66214f2eb46e4946f265d08e revision: ef9b2a0ba51b16e65d42728d8aacd265b926bb9f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 19:36:09.991930) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 19:36:09.992375) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 19:36:09.992650) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 19:36:10.020175) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 19:36:10.020553) ========= 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.gnXzOh02UL/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.zrUDh3lTpS/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-23 19:36:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 166M=0s 2016-11-23 19:36:10 (166 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.194691 ========= master_lag: 1.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 19:36:11.330500) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 19:36:11.330821) ========= 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.gnXzOh02UL/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.zrUDh3lTpS/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.026301 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 19:36:11.381589) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 19:36:11.381904) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ef9b2a0ba51b16e65d42728d8aacd265b926bb9f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ef9b2a0ba51b16e65d42728d8aacd265b926bb9f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gnXzOh02UL/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.zrUDh3lTpS/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-23 19:36:11,469 truncating revision to first 12 chars 2016-11-23 19:36:11,469 Setting DEBUG logging. 2016-11-23 19:36:11,469 attempt 1/10 2016-11-23 19:36:11,469 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ef9b2a0ba51b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-23 19:36:12,578 unpacking tar archive at: mozilla-aurora-ef9b2a0ba51b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.399946 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 19:36:12.806075) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 19:36:12.806425) ========= script_repo_revision: ef9b2a0ba51b16e65d42728d8aacd265b926bb9f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 19:36:12.806834) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 19:36:12.807118) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 19:36:12.827175) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-23 19:36:12.827590) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gnXzOh02UL/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.zrUDh3lTpS/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 19:36:13 INFO - MultiFileLogger online at 20161123 19:36:13 in /builds/slave/test 19:36:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 19:36:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:36:13 INFO - {'append_to_log': False, 19:36:13 INFO - 'base_work_dir': '/builds/slave/test', 19:36:13 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:36:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:36:13 INFO - 'branch': 'Mozilla-Aurora', 19:36:13 INFO - 'buildbot_json_path': 'buildprops.json', 19:36:13 INFO - 'config_files': ('talos/mac_config.py',), 19:36:13 INFO - 'default_actions': ('clobber', 19:36:13 INFO - 'read-buildbot-config', 19:36:13 INFO - 'download-and-extract', 19:36:13 INFO - 'populate-webroot', 19:36:13 INFO - 'create-virtualenv', 19:36:13 INFO - 'install', 19:36:13 INFO - 'run-tests'), 19:36:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:36:13 INFO - 'download_minidump_stackwalk': True, 19:36:13 INFO - 'download_symbols': 'ondemand', 19:36:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:36:13 INFO - 'tooltool.py': '/tools/tooltool.py', 19:36:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:36:13 INFO - '/tools/misc-python/virtualenv.py')}, 19:36:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:36:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:36:13 INFO - 'installer_path': 'installer.exe', 19:36:13 INFO - 'log_level': 'info', 19:36:13 INFO - 'log_name': 'talos', 19:36:13 INFO - 'log_to_console': True, 19:36:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:36:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:36:13 INFO - 'opt_config_files': (), 19:36:13 INFO - 'pip_index': False, 19:36:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:36:13 INFO - 'cmd': ('bash', 19:36:13 INFO - '-c', 19:36:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:36:13 INFO - 'enabled': True, 19:36:13 INFO - 'halt_on_failure': False, 19:36:13 INFO - 'name': 'check_screen_resolution'},), 19:36:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:36:13 INFO - 'cmd': ('bash', 19:36:13 INFO - '-c', 19:36:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:36:13 INFO - 'enabled': True, 19:36:13 INFO - 'halt_on_failure': False, 19:36:13 INFO - 'name': 'check_screen_resolution'},), 19:36:13 INFO - 'run_cmd_checks_enabled': True, 19:36:13 INFO - 'sps_profile': False, 19:36:13 INFO - 'sps_profile_interval': 0, 19:36:13 INFO - 'suite': 'dromaeojs-e10s', 19:36:13 INFO - 'system_bits': '32', 19:36:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:36:13 INFO - 'title': 't-yosemite-r7-0093', 19:36:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:36:13 INFO - 'use_talos_json': True, 19:36:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:36:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:36:13 INFO - 'work_dir': 'build'} 19:36:13 INFO - [mozharness: 2016-11-24 03:36:13.028446Z] Running clobber step. 19:36:13 INFO - Running pre-action listener: _resource_record_pre_action 19:36:13 INFO - Running main action method: clobber 19:36:13 INFO - rmtree: /builds/slave/test/build 19:36:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:36:15 INFO - Running post-action listener: _resource_record_post_action 19:36:15 INFO - [mozharness: 2016-11-24 03:36:15.589763Z] Finished clobber step (success) 19:36:15 INFO - [mozharness: 2016-11-24 03:36:15.589976Z] Running read-buildbot-config step. 19:36:15 INFO - Running pre-action listener: _resource_record_pre_action 19:36:15 INFO - Running main action method: read_buildbot_config 19:36:15 INFO - Using buildbot properties: 19:36:15 INFO - { 19:36:15 INFO - "project": "", 19:36:15 INFO - "product": "firefox", 19:36:15 INFO - "script_repo_revision": "production", 19:36:15 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 19:36:15 INFO - "repository": "", 19:36:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 19:36:15 INFO - "buildid": "20161123171736", 19:36:15 INFO - "pgo_build": "False", 19:36:15 INFO - "basedir": "/builds/slave/test", 19:36:15 INFO - "buildnumber": 460, 19:36:15 INFO - "slavename": "t-yosemite-r7-0093", 19:36:15 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 19:36:15 INFO - "platform": "yosemite_r7", 19:36:15 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 19:36:15 INFO - "branch": "mozilla-aurora", 19:36:15 INFO - "repo_path": "releases/mozilla-aurora", 19:36:15 INFO - "revision": "ef9b2a0ba51b16e65d42728d8aacd265b926bb9f", 19:36:15 INFO - "stage_platform": "macosx64", 19:36:15 INFO - "builduid": "14d0c88e66214f2eb46e4946f265d08e", 19:36:15 INFO - "slavebuilddir": "test" 19:36:15 INFO - } 19:36:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 19:36:15 INFO - Running post-action listener: _resource_record_post_action 19:36:15 INFO - [mozharness: 2016-11-24 03:36:15.595673Z] Finished read-buildbot-config step (success) 19:36:15 INFO - [mozharness: 2016-11-24 03:36:15.595899Z] Running download-and-extract step. 19:36:15 INFO - Running pre-action listener: _resource_record_pre_action 19:36:15 INFO - Running main action method: download_and_extract 19:36:15 INFO - mkdir: /builds/slave/test/build/tests 19:36:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:36:15 INFO - trying https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 19:36:15 INFO - Downloading https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 19:36:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 19:36:16 INFO - Downloaded 1199 bytes. 19:36:16 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 19:36:16 INFO - Using the following test package requirements: 19:36:16 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 19:36:16 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:36:16 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 19:36:16 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:36:16 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 19:36:16 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:36:16 INFO - u'jsshell-mac.zip'], 19:36:16 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:36:16 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 19:36:16 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 19:36:16 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:36:16 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 19:36:16 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:36:16 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 19:36:16 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:36:16 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 19:36:16 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:36:16 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 19:36:16 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 19:36:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 19:36:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 19:36:16 INFO - Fetch https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 19:36:17 INFO - Http code: 200 19:36:17 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 19:36:17 INFO - x-amz-cf-id: LuU-hQprodq8_73WydfiglvgdO6ODvY_EcNc8RTf3hVdx5Ru76Ww_A== 19:36:17 INFO - x-amz-version-id: amQlPIPWSro0xzLDC6tFJ6pqisHi2GB3 19:36:17 INFO - Expected file size: 16278314 19:36:17 INFO - Obtained file size: 16278314 19:36:24 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 19:36:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 19:36:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 19:36:24 INFO - Fetch https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 19:36:24 INFO - Http code: 200 19:36:24 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 19:36:24 INFO - x-amz-cf-id: Q-7xzJzg6FPaN8XG8BQYE03IkoONnhYIbPO7CtcS2r8vG_657l5nng== 19:36:24 INFO - x-amz-version-id: amQlPIPWSro0xzLDC6tFJ6pqisHi2GB3 19:36:25 INFO - Expected file size: 16278314 19:36:25 INFO - Obtained file size: 16278314 19:36:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 19:36:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 19:36:31 INFO - Fetch https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 19:36:32 INFO - Http code: 200 19:36:32 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 19:36:32 INFO - x-amz-cf-id: gLA-cs6E6-TNnBbchuBR4gzzf2rFybQoW7HJKZy2p_jdXniv5-YwbA== 19:36:32 INFO - x-amz-version-id: SAOU3_6LDh4jRTRm69Qt2i23m4kgGeuH 19:36:32 INFO - Expected file size: 14409038 19:36:32 INFO - Obtained file size: 14409038 19:36:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:36:33 INFO - trying https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 19:36:33 INFO - Downloading https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 19:36:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 19:36:36 INFO - Downloaded 109693340 bytes. 19:36:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 19:36:36 INFO - mkdir: /builds/slave/test/properties 19:36:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:36:36 INFO - Writing to file /builds/slave/test/properties/build_url 19:36:36 INFO - Contents: 19:36:36 INFO - build_url:https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 19:36:36 INFO - Running post-action listener: _resource_record_post_action 19:36:36 INFO - Running post-action listener: set_extra_try_arguments 19:36:36 INFO - [mozharness: 2016-11-24 03:36:36.663426Z] Finished download-and-extract step (success) 19:36:36 INFO - [mozharness: 2016-11-24 03:36:36.663546Z] Running populate-webroot step. 19:36:36 INFO - Running pre-action listener: _resource_record_pre_action 19:36:36 INFO - Running main action method: populate_webroot 19:36:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:36:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:36:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:36:36 INFO - '--tppagecycles', 19:36:36 INFO - '7'], 19:36:36 INFO - 'tests': ['tsvgm']}}, 19:36:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:36:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:36:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:36:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:36:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:36:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:36:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:36:36 INFO - 'talos_options': ['--disable-e10s'], 19:36:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:36:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:36:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:36:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:36:36 INFO - 'talos_options': ['--disable-e10s'], 19:36:36 INFO - 'tests': ['damp', 'tps']}, 19:36:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:36:36 INFO - 'tests': ['damp', 'tps']}, 19:36:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:36:36 INFO - 'tests': ['dromaeo_dom']}, 19:36:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:36:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:36:36 INFO - 'tests': ['basic_compositor_video']}, 19:36:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:36:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:36:36 INFO - 'tests': ['a11yr', 19:36:36 INFO - 'ts_paint', 19:36:36 INFO - 'tpaint', 19:36:36 INFO - 'sessionrestore', 19:36:36 INFO - 'sessionrestore_no_auto_restore', 19:36:36 INFO - 'tabpaint']}, 19:36:36 INFO - 'other-e10s': {'tests': ['a11yr', 19:36:36 INFO - 'ts_paint', 19:36:36 INFO - 'tpaint', 19:36:36 INFO - 'sessionrestore', 19:36:36 INFO - 'sessionrestore_no_auto_restore', 19:36:36 INFO - 'tabpaint']}, 19:36:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:36:36 INFO - 'ts_paint', 19:36:36 INFO - 'tpaint', 19:36:36 INFO - 'sessionrestore', 19:36:36 INFO - 'sessionrestore_no_auto_restore', 19:36:36 INFO - 'tabpaint']}, 19:36:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:36:36 INFO - 'ts_paint', 19:36:36 INFO - 'tpaint', 19:36:36 INFO - 'sessionrestore', 19:36:36 INFO - 'sessionrestore_no_auto_restore', 19:36:36 INFO - 'tabpaint']}, 19:36:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:36:36 INFO - 'tests': ['a11yr', 19:36:36 INFO - 'ts_paint', 19:36:36 INFO - 'tpaint', 19:36:36 INFO - 'sessionrestore', 19:36:36 INFO - 'sessionrestore_no_auto_restore', 19:36:36 INFO - 'tabpaint']}, 19:36:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:36:36 INFO - 'tests': ['a11yr', 19:36:36 INFO - 'ts_paint', 19:36:36 INFO - 'tpaint', 19:36:36 INFO - 'sessionrestore', 19:36:36 INFO - 'sessionrestore_no_auto_restore', 19:36:36 INFO - 'tabpaint']}, 19:36:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:36:36 INFO - 'tests': ['tsvgx', 19:36:36 INFO - 'tsvgr_opacity', 19:36:36 INFO - 'tart', 19:36:36 INFO - 'tscrollx', 19:36:36 INFO - 'cart']}, 19:36:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:36:36 INFO - 'tsvgr_opacity', 19:36:36 INFO - 'tart', 19:36:36 INFO - 'tscrollx', 19:36:36 INFO - 'cart']}, 19:36:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:36:36 INFO - 'talos_options': ['--disable-e10s'], 19:36:36 INFO - 'tests': ['tp5o']}, 19:36:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:36:36 INFO - 'tests': ['tp5o']}, 19:36:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:36:36 INFO - 'talos_options': ['--disable-e10s', 19:36:36 INFO - '--xperf_path', 19:36:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:36:36 INFO - 'tests': ['tp5n']}, 19:36:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:36:36 INFO - 'talos_options': ['--xperf_path', 19:36:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:36:36 INFO - 'tests': ['tp5n']}}, 19:36:36 INFO - 'talos.zip': {'path': '', 19:36:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:36:36 INFO - Running post-action listener: _resource_record_post_action 19:36:36 INFO - [mozharness: 2016-11-24 03:36:36.682182Z] Finished populate-webroot step (success) 19:36:36 INFO - [mozharness: 2016-11-24 03:36:36.682263Z] Running create-virtualenv step. 19:36:36 INFO - Running pre-action listener: _resource_record_pre_action 19:36:36 INFO - Running main action method: create_virtualenv 19:36:36 INFO - Creating virtualenv /builds/slave/test/build/venv 19:36:36 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 19:36:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:36:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:36:36 INFO - Using real prefix '/tools/python27' 19:36:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:36:37 INFO - Installing distribute.............................................................................................................................................................................................done. 19:36:40 INFO - Installing pip.................done. 19:36:40 INFO - Return code: 0 19:36:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:36:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:36:40 INFO - Reading from file tmpfile_stdout 19:36:40 INFO - Output received: 19:36:40 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) 19:36:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:36:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:36:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:36:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:41 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 0x10eddb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed51570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feeb0c943a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebefd30>, '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 0x10ea55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe7b20>, '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.gnXzOh02UL/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.zrUDh3lTpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 19:36:41 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 19:36:41 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 19:36:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gnXzOh02UL/Render', 19:36:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:41 INFO - 'HOME': '/Users/cltbld', 19:36:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:41 INFO - 'LOGNAME': 'cltbld', 19:36:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:41 INFO - 'MOZ_NO_REMOTE': '1', 19:36:41 INFO - 'NO_EM_RESTART': '1', 19:36:41 INFO - 'PAGER': '/bin/cat', 19:36:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:41 INFO - 'PWD': '/builds/slave/test', 19:36:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:41 INFO - 'SHELL': '/bin/bash', 19:36:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrUDh3lTpS/Listeners', 19:36:41 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 19:36:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:41 INFO - 'USER': 'cltbld', 19:36:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:41 INFO - 'XPC_FLAGS': '0x0', 19:36:41 INFO - 'XPC_SERVICE_NAME': '0', 19:36:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:41 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 19:36:41 INFO - Cleaning up... 19:36:41 INFO - Return code: 0 19:36:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:36:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:36:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:42 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 0x10eddb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed51570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feeb0c943a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebefd30>, '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 0x10ea55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe7b20>, '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.gnXzOh02UL/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.zrUDh3lTpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 19:36:42 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 19:36:42 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 19:36:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gnXzOh02UL/Render', 19:36:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:42 INFO - 'HOME': '/Users/cltbld', 19:36:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:42 INFO - 'LOGNAME': 'cltbld', 19:36:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:42 INFO - 'MOZ_NO_REMOTE': '1', 19:36:42 INFO - 'NO_EM_RESTART': '1', 19:36:42 INFO - 'PAGER': '/bin/cat', 19:36:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:42 INFO - 'PWD': '/builds/slave/test', 19:36:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:42 INFO - 'SHELL': '/bin/bash', 19:36:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrUDh3lTpS/Listeners', 19:36:42 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 19:36:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:42 INFO - 'USER': 'cltbld', 19:36:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:42 INFO - 'XPC_FLAGS': '0x0', 19:36:42 INFO - 'XPC_SERVICE_NAME': '0', 19:36:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:42 INFO - Downloading/unpacking psutil>=3.1.1 19:36:42 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 19:36:42 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 19:36:42 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 19:36:42 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 19:36:42 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 19:36:42 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 19:36:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:36:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:36:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:36:46 INFO - Installing collected packages: psutil 19:36:46 INFO - Running setup.py install for psutil 19:36:46 INFO - building 'psutil._psutil_osx' extension 19:36:46 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 19:36:47 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 19:36:47 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 19:36:47 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 19:36:47 INFO - building 'psutil._psutil_posix' extension 19:36:47 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 19:36:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:36:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:36:47 INFO - ^ 19:36:47 INFO - 1 warning generated. 19:36:47 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 19:36:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:36:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:36:47 INFO - Successfully installed psutil 19:36:47 INFO - Cleaning up... 19:36:47 INFO - Return code: 0 19:36:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:36:47 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')]} 19:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:47 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')]} 19:36:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:47 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 0x10eddb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed51570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feeb0c943a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebefd30>, '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 0x10ea55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe7b20>, '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.gnXzOh02UL/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.zrUDh3lTpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 19:36:47 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 19:36:47 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 19:36:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gnXzOh02UL/Render', 19:36:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:47 INFO - 'HOME': '/Users/cltbld', 19:36:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:47 INFO - 'LOGNAME': 'cltbld', 19:36:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:47 INFO - 'MOZ_NO_REMOTE': '1', 19:36:47 INFO - 'NO_EM_RESTART': '1', 19:36:47 INFO - 'PAGER': '/bin/cat', 19:36:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:47 INFO - 'PWD': '/builds/slave/test', 19:36:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:47 INFO - 'SHELL': '/bin/bash', 19:36:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrUDh3lTpS/Listeners', 19:36:47 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 19:36:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:47 INFO - 'USER': 'cltbld', 19:36:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:47 INFO - 'XPC_FLAGS': '0x0', 19:36:47 INFO - 'XPC_SERVICE_NAME': '0', 19:36:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:36:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:36:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:36:50 INFO - Installing collected packages: mozsystemmonitor 19:36:50 INFO - Running setup.py install for mozsystemmonitor 19:36:50 INFO - Successfully installed mozsystemmonitor 19:36:50 INFO - Cleaning up... 19:36:50 INFO - Return code: 0 19:36:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:36:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:36:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:36:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:50 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 0x10eddb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed51570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feeb0c943a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebefd30>, '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 0x10ea55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe7b20>, '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.gnXzOh02UL/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.zrUDh3lTpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 19:36:50 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 19:36:50 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 19:36:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gnXzOh02UL/Render', 19:36:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:50 INFO - 'HOME': '/Users/cltbld', 19:36:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:50 INFO - 'LOGNAME': 'cltbld', 19:36:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:50 INFO - 'MOZ_NO_REMOTE': '1', 19:36:50 INFO - 'NO_EM_RESTART': '1', 19:36:50 INFO - 'PAGER': '/bin/cat', 19:36:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:50 INFO - 'PWD': '/builds/slave/test', 19:36:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:50 INFO - 'SHELL': '/bin/bash', 19:36:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrUDh3lTpS/Listeners', 19:36:50 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 19:36:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:50 INFO - 'USER': 'cltbld', 19:36:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:50 INFO - 'XPC_FLAGS': '0x0', 19:36:50 INFO - 'XPC_SERVICE_NAME': '0', 19:36:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:51 INFO - Downloading/unpacking jsonschema==2.5.1 19:36:51 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 19:36:51 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 19:36:51 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 19:36:51 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 19:36:51 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 19:36:51 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 19:36:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:36:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:36:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:54 INFO - Downloading functools32-3.2.3-2.tar.gz 19:36:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:36:54 INFO - warning: no files found matching '*.txt' 19:36:54 INFO - no previously-included directories found matching 'build' 19:36:54 INFO - no previously-included directories found matching 'dist' 19:36:54 INFO - no previously-included directories found matching '.git*' 19:36:54 INFO - Installing collected packages: jsonschema, functools32 19:36:54 INFO - Running setup.py install for functools32 19:36:54 INFO - warning: no files found matching '*.txt' 19:36:54 INFO - no previously-included directories found matching 'build' 19:36:54 INFO - no previously-included directories found matching 'dist' 19:36:54 INFO - no previously-included directories found matching '.git*' 19:36:54 INFO - Successfully installed jsonschema functools32 19:36:54 INFO - Cleaning up... 19:36:54 INFO - Return code: 0 19:36:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:36:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:36:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:36:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:54 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 0x10eddb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed51570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feeb0c943a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebefd30>, '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 0x10ea55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe7b20>, '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.gnXzOh02UL/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.zrUDh3lTpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 19:36:54 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 19:36:54 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 19:36:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gnXzOh02UL/Render', 19:36:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:54 INFO - 'HOME': '/Users/cltbld', 19:36:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:54 INFO - 'LOGNAME': 'cltbld', 19:36:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:54 INFO - 'MOZ_NO_REMOTE': '1', 19:36:54 INFO - 'NO_EM_RESTART': '1', 19:36:54 INFO - 'PAGER': '/bin/cat', 19:36:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:54 INFO - 'PWD': '/builds/slave/test', 19:36:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:54 INFO - 'SHELL': '/bin/bash', 19:36:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrUDh3lTpS/Listeners', 19:36:54 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 19:36:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:54 INFO - 'USER': 'cltbld', 19:36:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:54 INFO - 'XPC_FLAGS': '0x0', 19:36:54 INFO - 'XPC_SERVICE_NAME': '0', 19:36:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:36:54 INFO - Cleaning up... 19:36:54 INFO - Return code: 0 19:36:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:36:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:36:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:36:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:54 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 0x10eddb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed51570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feeb0c943a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebefd30>, '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 0x10ea55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe7b20>, '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.gnXzOh02UL/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.zrUDh3lTpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 19:36:54 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 19:36:54 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 19:36:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gnXzOh02UL/Render', 19:36:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:54 INFO - 'HOME': '/Users/cltbld', 19:36:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:54 INFO - 'LOGNAME': 'cltbld', 19:36:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:54 INFO - 'MOZ_NO_REMOTE': '1', 19:36:54 INFO - 'NO_EM_RESTART': '1', 19:36:54 INFO - 'PAGER': '/bin/cat', 19:36:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:54 INFO - 'PWD': '/builds/slave/test', 19:36:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:54 INFO - 'SHELL': '/bin/bash', 19:36:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrUDh3lTpS/Listeners', 19:36:54 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 19:36:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:54 INFO - 'USER': 'cltbld', 19:36:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:54 INFO - 'XPC_FLAGS': '0x0', 19:36:54 INFO - 'XPC_SERVICE_NAME': '0', 19:36:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:54 INFO - Downloading/unpacking blobuploader==1.2.4 19:36:54 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 19:36:54 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 19:36:54 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 19:36:54 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 19:36:54 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 19:36:54 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 19:36:57 INFO - Downloading blobuploader-1.2.4.tar.gz 19:36:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:36:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:36:57 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 19:36:57 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 19:36:57 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 19:36:57 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 19:36:57 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 19:36:57 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 19:36:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:36:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:36:58 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 19:36:58 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 19:36:58 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 19:36:58 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 19:36:58 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 19:36:58 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 19:36:58 INFO - Downloading docopt-0.6.1.tar.gz 19:36:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:36:58 INFO - Installing collected packages: blobuploader, requests, docopt 19:36:58 INFO - Running setup.py install for blobuploader 19:36:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:36:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:36:58 INFO - Running setup.py install for requests 19:36:59 INFO - Running setup.py install for docopt 19:36:59 INFO - Successfully installed blobuploader requests docopt 19:36:59 INFO - Cleaning up... 19:36:59 INFO - Return code: 0 19:36:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:36:59 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')]} 19:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:59 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')]} 19:36:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:59 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 0x10eddb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed51570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feeb0c943a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebefd30>, '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 0x10ea55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe7b20>, '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.gnXzOh02UL/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.zrUDh3lTpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 19:36:59 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 19:36:59 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 19:36:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gnXzOh02UL/Render', 19:36:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:59 INFO - 'HOME': '/Users/cltbld', 19:36:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:59 INFO - 'LOGNAME': 'cltbld', 19:36:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:59 INFO - 'MOZ_NO_REMOTE': '1', 19:36:59 INFO - 'NO_EM_RESTART': '1', 19:36:59 INFO - 'PAGER': '/bin/cat', 19:36:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:59 INFO - 'PWD': '/builds/slave/test', 19:36:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:59 INFO - 'SHELL': '/bin/bash', 19:36:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrUDh3lTpS/Listeners', 19:36:59 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 19:36:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:59 INFO - 'USER': 'cltbld', 19:36:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:59 INFO - 'XPC_FLAGS': '0x0', 19:36:59 INFO - 'XPC_SERVICE_NAME': '0', 19:36:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:36:59 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-FwmZaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:37:00 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-ose9f8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:37:00 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-aXcVQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:37:00 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-1b6_LV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:37:00 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-Kp5OFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:37:00 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-VoDycm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:37:00 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-VNkmI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:37:00 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-xeE8EW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:37:00 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-9vZgNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:37:00 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-dYjsuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:37:00 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-pfg8kW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:37:01 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-Vya8OD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:37:01 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-Q69uBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:37:01 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-ZGX716-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:37:01 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-KKrxN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:37:01 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-tF2ash-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:37:01 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-LGXZxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:37:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:37:01 INFO - Running setup.py install for manifestparser 19:37:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:37:01 INFO - Running setup.py install for mozcrash 19:37:01 INFO - Running setup.py install for mozdebug 19:37:02 INFO - Running setup.py install for mozdevice 19:37:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:37:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:37:02 INFO - Running setup.py install for mozfile 19:37:02 INFO - Running setup.py install for mozhttpd 19:37:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:37:02 INFO - Running setup.py install for mozinfo 19:37:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:37:02 INFO - Running setup.py install for mozInstall 19:37:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:37:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:37:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:37:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:37:02 INFO - Running setup.py install for mozleak 19:37:02 INFO - Running setup.py install for mozlog 19:37:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:37:03 INFO - Running setup.py install for moznetwork 19:37:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:37:03 INFO - Running setup.py install for mozprocess 19:37:03 INFO - Running setup.py install for mozprofile 19:37:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:37:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:37:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:37:03 INFO - Running setup.py install for mozrunner 19:37:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:37:03 INFO - Running setup.py install for mozscreenshot 19:37:03 INFO - Running setup.py install for moztest 19:37:04 INFO - Running setup.py install for mozversion 19:37:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:37:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:37:04 INFO - Cleaning up... 19:37:04 INFO - Return code: 0 19:37:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:37:04 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')]} 19:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:37:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:04 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')]} 19:37:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:37:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:04 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 0x10eddb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed51570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feeb0c943a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebefd30>, '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 0x10ea55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe7b20>, '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.gnXzOh02UL/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.zrUDh3lTpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 19:37:04 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 19:37:04 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 19:37:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gnXzOh02UL/Render', 19:37:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:37:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:37:04 INFO - 'HOME': '/Users/cltbld', 19:37:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:37:04 INFO - 'LOGNAME': 'cltbld', 19:37:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:37:04 INFO - 'MOZ_NO_REMOTE': '1', 19:37:04 INFO - 'NO_EM_RESTART': '1', 19:37:04 INFO - 'PAGER': '/bin/cat', 19:37:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:37:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:37:04 INFO - 'PWD': '/builds/slave/test', 19:37:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:37:04 INFO - 'SHELL': '/bin/bash', 19:37:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrUDh3lTpS/Listeners', 19:37:04 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 19:37:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:37:04 INFO - 'USER': 'cltbld', 19:37:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:37:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:37:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:37:04 INFO - 'XPC_FLAGS': '0x0', 19:37:04 INFO - 'XPC_SERVICE_NAME': '0', 19:37:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:37:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:37:04 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-aGIWGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:37:04 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)) 19:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:37:04 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-e305D0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:37:04 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-FmDkCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:37:04 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)) 19:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:37:04 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-hapm4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:37:04 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)) 19:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:37:04 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-WfREJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:37:05 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)) 19:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:37:05 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-Kjj0Hc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:37:05 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)) 19:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:37:05 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-0VVpYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:37:05 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)) 19:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:37:05 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-eRfft3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:37:05 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)) 19:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:37:05 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-2slfoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:37:05 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)) 19:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:37:05 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-p4wSuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:37:05 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)) 19:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:37:05 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-_0ZhlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:37:05 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)) 19:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:37:05 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-KECkIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:37:05 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)) 19:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:37:05 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-JTznsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:37:05 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)) 19:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:37:05 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-nZ1gbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:37:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:37:06 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-aqAaHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:37:06 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)) 19:37:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:37:06 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-HpmG5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:37:06 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)) 19:37:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:37:06 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-7AwKNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:37:06 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)) 19:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:37:06 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)) 19:37:06 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)) 19:37:06 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)) 19:37:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:37: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 19:37: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 19:37: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 19:37: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 19:37: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 19:37: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 19:37:08 INFO - Downloading blessings-1.6.tar.gz 19:37:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:37:08 INFO - Installing collected packages: blessings 19:37:08 INFO - Running setup.py install for blessings 19:37:09 INFO - Successfully installed blessings 19:37:09 INFO - Cleaning up... 19:37:09 INFO - Return code: 0 19:37:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:37:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:37:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:37:09 INFO - Reading from file tmpfile_stdout 19:37:09 INFO - Current package versions: 19:37:09 INFO - blessings == 1.6 19:37:09 INFO - blobuploader == 1.2.4 19:37:09 INFO - docopt == 0.6.1 19:37:09 INFO - functools32 == 3.2.3-2 19:37:09 INFO - jsonschema == 2.5.1 19:37:09 INFO - manifestparser == 1.1 19:37:09 INFO - mozInstall == 1.12 19:37:09 INFO - mozcrash == 1.0 19:37:09 INFO - mozdebug == 0.1 19:37:09 INFO - mozdevice == 0.48 19:37:09 INFO - mozfile == 1.2 19:37:09 INFO - mozhttpd == 0.7 19:37:09 INFO - mozinfo == 0.9 19:37:09 INFO - mozleak == 0.1 19:37:09 INFO - mozlog == 3.4 19:37:09 INFO - moznetwork == 0.27 19:37:09 INFO - mozprocess == 0.23 19:37:09 INFO - mozprofile == 0.28 19:37:09 INFO - mozrunner == 6.13 19:37:09 INFO - mozscreenshot == 0.1 19:37:09 INFO - mozsystemmonitor == 0.3 19:37:09 INFO - moztest == 0.7 19:37:09 INFO - mozversion == 1.4 19:37:09 INFO - psutil == 3.1.1 19:37:09 INFO - requests == 1.2.3 19:37:09 INFO - wsgiref == 0.1.2 19:37:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:37: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')]} 19:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:37:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37: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')]} 19:37:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:37:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:09 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 0x10eddb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed51570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feeb0c943a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebefd30>, '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 0x10ea55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe7b20>, '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.gnXzOh02UL/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.zrUDh3lTpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 19:37:09 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 19:37:09 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 19:37:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gnXzOh02UL/Render', 19:37:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:37:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:37:09 INFO - 'HOME': '/Users/cltbld', 19:37:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:37:09 INFO - 'LOGNAME': 'cltbld', 19:37:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:37:09 INFO - 'MOZ_NO_REMOTE': '1', 19:37:09 INFO - 'NO_EM_RESTART': '1', 19:37:09 INFO - 'PAGER': '/bin/cat', 19:37:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:37:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:37:09 INFO - 'PWD': '/builds/slave/test', 19:37:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:37:09 INFO - 'SHELL': '/bin/bash', 19:37:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrUDh3lTpS/Listeners', 19:37:09 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 19:37:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:37:09 INFO - 'USER': 'cltbld', 19:37:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:37:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:37:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:37:09 INFO - 'XPC_FLAGS': '0x0', 19:37:09 INFO - 'XPC_SERVICE_NAME': '0', 19:37:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:37:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:37:09 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)) 19:37:09 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)) 19:37:09 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)) 19:37:09 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)) 19:37:09 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)) 19:37:09 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)) 19:37:09 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)) 19:37:09 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)) 19:37:09 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)) 19:37:09 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)) 19:37:09 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)) 19:37:09 INFO - Cleaning up... 19:37:09 INFO - Return code: 0 19:37:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:37: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')]} 19:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:37:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37: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')]} 19:37:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:37:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eddb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed51570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feeb0c943a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebefd30>, '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 0x10ea55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe7b20>, '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.gnXzOh02UL/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.zrUDh3lTpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 19:37: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'] in /builds/slave/test/build 19:37: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 19:37:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gnXzOh02UL/Render', 19:37:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:37:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:37:09 INFO - 'HOME': '/Users/cltbld', 19:37:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:37:09 INFO - 'LOGNAME': 'cltbld', 19:37:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:37:09 INFO - 'MOZ_NO_REMOTE': '1', 19:37:09 INFO - 'NO_EM_RESTART': '1', 19:37:09 INFO - 'PAGER': '/bin/cat', 19:37:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:37:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:37:09 INFO - 'PWD': '/builds/slave/test', 19:37:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:37:09 INFO - 'SHELL': '/bin/bash', 19:37:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrUDh3lTpS/Listeners', 19:37:09 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 19:37:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:37:09 INFO - 'USER': 'cltbld', 19:37:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:37:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:37:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:37:09 INFO - 'XPC_FLAGS': '0x0', 19:37:09 INFO - 'XPC_SERVICE_NAME': '0', 19:37:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:37:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:37:09 INFO - Cleaning up... 19:37:09 INFO - Return code: 0 19:37:09 INFO - Running post-action listener: _resource_record_post_action 19:37:09 INFO - Running post-action listener: _start_resource_monitoring 19:37:10 INFO - Starting resource monitoring. 19:37:10 INFO - [mozharness: 2016-11-24 03:37:10.006061Z] Finished create-virtualenv step (success) 19:37:10 INFO - [mozharness: 2016-11-24 03:37:10.007076Z] Running install step. 19:37:10 INFO - Running pre-action listener: _resource_record_pre_action 19:37:10 INFO - Running main action method: install 19:37:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:37:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:37:10 INFO - Reading from file tmpfile_stdout 19:37:10 INFO - Detecting whether we're running mozinstall >=1.0... 19:37:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:37:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:37:10 INFO - Reading from file tmpfile_stdout 19:37:10 INFO - Output received: 19:37:10 INFO - Usage: mozinstall [options] installer 19:37:10 INFO - Options: 19:37:10 INFO - -h, --help show this help message and exit 19:37:10 INFO - -d DEST, --destination=DEST 19:37:10 INFO - Directory to install application into. [default: 19:37:10 INFO - "/builds/slave/test"] 19:37:10 INFO - --app=APP Application being installed. [default: firefox] 19:37:10 INFO - mkdir: /builds/slave/test/build/application 19:37:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:37:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 19:37:41 INFO - Reading from file tmpfile_stdout 19:37:41 INFO - Output received: 19:37:41 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 19:37:41 INFO - Running post-action listener: _resource_record_post_action 19:37:41 INFO - [mozharness: 2016-11-24 03:37:41.729138Z] Finished install step (success) 19:37:41 INFO - [mozharness: 2016-11-24 03:37:41.729306Z] Running run-tests step. 19:37:41 INFO - Running pre-action listener: _resource_record_pre_action 19:37:41 INFO - Running main action method: run_tests 19:37:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:37:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:37:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:37:41 INFO - 2016-11-23 19:37:41.743 screenresolution[1632:7463] starting screenresolution argv=screenresolution get 19:37:41 INFO - 2016-11-23 19:37:41.746 screenresolution[1632:7463] Display 0: 1600x1200x32@60 19:37:41 INFO - 2016-11-23 19:37:41.752 screenresolution[1633:7465] starting screenresolution argv=screenresolution list 19:37:41 INFO - Available Modes on Display 0 19:37:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:37:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:37:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:37:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:37:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:37:41 INFO - Intel Iris: 19:37:41 INFO - Chipset Model: Intel Iris 19:37:41 INFO - Type: GPU 19:37:41 INFO - Bus: Built-In 19:37:41 INFO - VRAM (Dynamic, Max): 1536 MB 19:37:41 INFO - Vendor: Intel (0x8086) 19:37:41 INFO - Device ID: 0x0a2e 19:37:41 INFO - Revision ID: 0x0009 19:37:41 INFO - Displays: 19:37:41 INFO - Display: 19:37:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:37:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:37:41 INFO - Display Serial Number: 1600x1200 60 19:37:41 INFO - Main Display: Yes 19:37:41 INFO - Mirror: Off 19:37:41 INFO - Online: Yes 19:37:41 INFO - Rotation: Supported 19:37:41 INFO - Return code: 0 19:37:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:37:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:37:41 INFO - Python 2.7.3 19:37:41 INFO - Return code: 0 19:37:41 INFO - grabbing minidump binary from tooltool 19:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:41 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 0x10ebefd30>, '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 0x10ea55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:37:41 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 19:37:41 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 19:37:41 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 19:37:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:37:42 INFO - Return code: 0 19:37:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:37:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:37:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:37:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:37:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:37:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0093', '--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 19:37:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0093 --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 19:37:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gnXzOh02UL/Render', 19:37:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:37:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:37:42 INFO - 'HOME': '/Users/cltbld', 19:37:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:37:42 INFO - 'LOGNAME': 'cltbld', 19:37:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:37:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:37:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:37:42 INFO - 'MOZ_NO_REMOTE': '1', 19:37:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:37:42 INFO - 'NO_EM_RESTART': '1', 19:37:42 INFO - 'PAGER': '/bin/cat', 19:37:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:37:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:37:42 INFO - 'PWD': '/builds/slave/test', 19:37:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:37:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:37:42 INFO - 'SHELL': '/bin/bash', 19:37:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrUDh3lTpS/Listeners', 19:37:42 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 19:37:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:37:42 INFO - 'USER': 'cltbld', 19:37:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:37:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:37:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:37:42 INFO - 'XPC_FLAGS': '0x0', 19:37:42 INFO - 'XPC_SERVICE_NAME': '0', 19:37:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:37:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0093', '--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 19:37:42 INFO - mozversion application_buildid: 20161123171736 19:37:42 INFO - mozversion application_changeset: ef9b2a0ba51b16e65d42728d8aacd265b926bb9f 19:37:42 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:37:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:37:42 INFO - mozversion application_name: Firefox 19:37:42 INFO - mozversion application_remotingname: firefox-dev 19:37:42 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:37:42 INFO - mozversion application_vendor: Mozilla 19:37:42 INFO - mozversion application_version: 52.0a2 19:37:42 INFO - mozversion platform_buildid: 20161123171736 19:37:42 INFO - mozversion platform_changeset: ef9b2a0ba51b16e65d42728d8aacd265b926bb9f 19:37:42 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:37:42 INFO - mozversion platform_version: 52.0a2 19:37:42 INFO - using testdate: 1479958662 19:37:42 INFO - actual date: 1479958662 19:37:42 INFO - starting webserver on 'localhost:49242' 19:37:42 INFO - SUITE-START | Running 2 tests 19:37:42 INFO - TEST-START | dromaeo_css 19:37:42 INFO - operating with platform_type : mac_ 19:37:42 INFO - Initialising browser for dromaeo_css test... 19:37:42 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmp1GZEn6/profile http://localhost:49242/getInfo.html) 19:37:44 ERROR - PROCESS | 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 19:37:45 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 19:37:45 INFO - PROCESS | 1643 | colorDepth:24 19:37:45 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 19:37:45 INFO - PROCESS | 1643 | __metrics 19:37:45 ERROR - PROCESS | 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 19:37:46 INFO - TEST-INFO | 1643: exit 0 19:37:46 INFO - Browser initialized. 19:37:46 INFO - Running cycle 1/1 for dromaeo_css test... 19:37:46 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmp1GZEn6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:37:58 INFO - PROCESS | 1646 | RSS: Main: 229650432 19:37:58 INFO - PROCESS | 1646 | 19:38:48 INFO - PROCESS | 1646 | 1479958728340 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] 19:41:02 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 19:41:03 INFO - PROCESS | 1646 | RSS: Main: 234225664 19:41:03 INFO - PROCESS | 1646 | 19:44:05 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 19:44:06 INFO - PROCESS | 1646 | RSS: Main: 243585024 19:44:06 INFO - PROCESS | 1646 | 19:47:08 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 19:47:09 INFO - PROCESS | 1646 | RSS: Main: 246562816 19:47:09 INFO - PROCESS | 1646 | 19:50:10 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 19:50:11 INFO - PROCESS | 1646 | RSS: Main: 245739520 19:50:11 INFO - PROCESS | 1646 | 19:53:14 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 19:53:14 INFO - PROCESS | 1646 | RSS: Main: 253181952 19:53:14 INFO - PROCESS | 1646 | 19:56:17 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 19:56:17 INFO - PROCESS | 1646 | RSS: Main: 254586880 19:56:17 INFO - PROCESS | 1646 | 19:56:17 INFO - PROCESS | 1646 | __start_tp_report 19:56:17 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 19:56:17 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 19:56:17 INFO - PROCESS | 1646 | |i|pagename|runs| 19:56:17 INFO - PROCESS | 1646 | |0;dojo.html;3085.6142982127235;3201.6798320167977;3267.2811981364093;3318.5022246662998;3333.3666603345346;4381.780910954448;4942.961977984619;5419.132938729804;5431.809886653968;5519.199716041172;5522.22688823564;5529.612927095105;5544.889102217953;5547.861303467415;5573.526250268722;4271.103068355649;5055.671381360218;5060.620453465986;5129.615278854082;5144.25408315794;4252.14957008598;5027.195648696209;5044.545990860822;5107;5162.199859021857;4903.166461701519;4925.138100832376;5010.997800439908;5072.442031376582;5095.566876815476;4946.060248552763;4964.776585053682;4981.153203955337;5006.5744411725045;5042.067217564762;4933.45731969483;4953.801847926079;5042.445331013586;5061.974690126563;5097.898042039138;5282.735863206863;5368.141097424433;5478.397376288605;5488.9725551371985;5506.394296627368;5936.347001829795;5969.164316995624;5996.820095397145;6009.849753756191;6035.3059398169335;5312.751503396698;5985.461308482258;6007.8197654070445;6013.188219590343;6029.246344206974;5476.726163691799;5483.588730845203;5492.588055895823;5506.614536982413;5591.965486385032;6107.725152368154;6127.34224287047;6132.7546898124465;6137.039921714258;6144.8771024579255;5925.170476133345;5971.223740913741;5990.850228744229;6016.789412370546;6048.667323297261;5774.249347584787;6047.758089676452;6095.420935011167;6097.817065488042;6097.939020609826;5014.548690617862;5254.7635356409055;5652.85867853307;5793.652380857077;5840.153177789197;5917.260342457193;5954.374790647006;5982.880342393128;6021.668808215591;6080;4119.908224320558;4776.5701086902345;4786.593139583105;4790.473047964721;4793.592544633745;3995.5604883462797;4251.5748425157235;4257.680673949466;4263;4318.892027699333;4576.313552967082;5576.275084239104;5596.104623260258;5644.435556444323;5681.57388195887;5937.584369094208;5939.584229104008;5989.251343582052;6138.570300079041;6142.938570614237;5669;5677.09166533361;5677.290338707661;5695.829125126253;5696.914546281809;5135.024345374367;5145.974270128625;5172.689638621619;5177.896442071213;5204.661696989683;6180.7527698891545;6218.31598524162;6225.750969961241;6278.215223097113;6321.652309123042;4658.650601204856;5855.677937713466;6082.9695851520455;6106.419890110433;6110.419510146529;5380.381256155592;6139.8601398601395;6203.875922481616;6223.128761973418;6268.686565671607;4899.412070551557;5988.041913293852;5989.431004054608;6000.339962604197;6039.184710064085;5930.911036334544;6028.819135425944;6035.124906888389;6081.422264884829;6082.2093127893095;5689.857753556194;5782.508486778671;5947;5977.163197152315;5986.341502434641;4899;5631.88736225265;5716.8570785729025;5817.7963771267805;5835.4748072673665;5467.125259958388;5522.66863988162;5604.775808967595;5643.943560564342;5663.8017669383025;5694.288213973254;5718.142278658068;5719.542436604978;5752.884942301214;5755.251817263729;5547.722613869371;5621.831345059655;5638.126090455986;5647.0964645656495;5670.262865827581 19:56:17 INFO - PROCESS | 1646 | |1;ext.html;471.4695967037083;515.5257163409664;524.2241482605746;541.7507946344681;548.8353493951813;37929.620703792956;39315.60684393155;39574.802125989365;41317.38023929636;41683.37474937878;40447.393289100684;41003.384949225714;41127;41237.38143927843;41255.793721031354;9256.25949924006;9444.291678124135;9454.43273403598;9465.148136667698;9477.95261023694;9360.859587106199;9408;9449.480278584675;9462.053794620551;9463.952680236589;9445;9464.1482266596;9477.241820654332;9494.098060684259;9522.666706665268;9452.054794520562;9475.47884866332;9476.00501947296;9481.66814161501;9488.857667134998;8961.372703910729;9380.812383752287;9438;9440.763980900463;9457.716268511957;38849.22301553953;39090;39108.80445597782;39134.60865391338;39217.411738823655;3218.6620404857385;3291.045596776956;3316.7346612270962;3340.9832950835334;3345.8996230113135;22739.976701048494;28950.42099158005;29263.561046584317;30304.54543181854;30486;3088.7837851350414;3165.889193878203;3234.75739319552;3338.6160591532043;3343.414902392096;478203;487469;488751;489813.5509322466;490637;57759.4224057754;57967.710161449766;58107;58263.126053109234;58432.4156758427;59806;60261.39738602645;60464.09303860446;60501.69749151226;60826.087608685906;15987;16094.919525402533;16200.75698864518;16218;16239.918800405922;12460.252384856938;12513.874861251454;12552.497900084078;12566.497340106293;12605.810912836314;13129;14060.367283472267;14170.220637865017;14961.551153465414;15853.68292634141;2732.221316924667;3281.917952051218;3302.966970330314;6872.347127022925;7005.229424763272;817.435969181263;875.2429148786254;939.6523286384082;977.237754551451;978.3298440568234;23263;28964;29278.925712800276;29766.40467190644;30557.624906879242;960.7502049467189;1035.358077991635;1056.0812093478723;1063.5107850388908;1109.3676604335612;12903.741925161632;15485.838562107887;15645.765313520305;15752.60618484547;15760.92119539418;679849;687635;688047;688554;689821;56915.430845691015;57128;57185.42814571801;57212;57226.42773572211;1312.6783937935268;1331.533963112922;1341.1282666267005;1341.4500054977411;1343.670800653846;22970.310890673307;28813.711862881104;29554.408911822073;30048.699513005464;30191.396172076875;49196;49471.50528494813;49521.50478495169;49533;49546.25680614721;627931;632515;632741.8362908156;634249;637575;25936;26233.606495902193;26962.865185673945;26966.865165674048;27926.86036569804;45246.095078098915;45277;45709.77145114253;46147.76926115348;46455.76772116118;36970.89087327384;37276.69531566382;37838.24323513459;38680;39050.804745976086;37895;38299.80850095732;38540.2291954165;39003.609963899995;39289;36518.45222321614;36794.816025919696;37316;37579;37870.62129378781;25944.870275649257;28037.457939813507;28844;32732.672673272962;40179.392824286704;22786.24027035587;23883.522329552965;25396.365090872878;25471.872640636677;25870 19:56:17 INFO - PROCESS | 1646 | |2;jquery.html;368.1403921842496;375.75951391109686;383.1608776778854;385.1315284034502;399.3111882003544;17723;17749;18419.35532256371;20039;20771;16690.83309166908;19350.12924418401;20040.899795501038;20293.594128117427;20372.388828335137;5495.120780675093;5584.21820945067;5623.887522249553;5889.116632505137;5895.469407753301;5699.800506982257;5937.643741375509;5942.078977758454;5943.55423343251;5958.106284057404;5595.692236926968;5738.3687794342795;5752.74112664929;5884.323302820188;5887.264091988502;5666.348369937448;5725.427457254283;5730.283714535683;5734.225879506255;5751.396103409144;4855.417349917997;5028.974855125701;5110.565601923841;5155.432902380735;5168.811173430132;24844.88198031093;24878;25084.87457562719;25222.369440763945;25278.241652750447;2213.236433430464;2320.709911261092;2380.1074597026113;2642.643243162187;2731.685856126551;14825.406983720639;14916.552503424915;14924.776128357975;15643.530694079196;15714.685706285925;1775.7070083436217;1876;1895.3546490624576;1901.8288354048202;1949.6393167264102;118059.4097029518;120481.19278210746;120996;121134.39432802779;121240;10373.533191006272;10641.89358106409;10746;10972.286801357886;11054.944725276322;13486.595402137951;13516.662083447796;13533.188008719311;13542.796858047137;13575.728485430434;8369.121242269595;8630.913690863137;8663.826723465496;8694.869666943383;8752.256058234994;8696.78258043554;8699.217070463688;8701.564921753887;8810.251128654008;8837.60230789616;6565.638889861103;6647.867042659216;6679.595345675553;6698.631575263407;6721.857284261687;1819.1722766141374;1839.7516335294831;1840.4386662068198;1846.2984066054814;1848.02978436322;868.5570359116896;906.6962567539948;912.3567884641312;916.4226537281603;931.7344556801414;20487.975601219878;21802.672959905613;21822.236221732164;21869.453263668216;22003;868.2923417414787;926.3098991251561;937.5780898595659;950.6435086842434;1040.1522758951583;9719.757006074904;11583.884161158281;11710.473028713557;11731.648050558497;11759.353235572125;566605;595122;602006;611092;621695;78234;90036;93524.53237733767;116742;120590.3970480142;874.7927859553902;931.7111695374456;978.9070038346346;998.1914649134225;999.1107913956585;17479.416719572713;17999.730004050212;18790.718139228196;19185.90407047956;19719.507012324233;51437;52238.21642675287;52910.47089529209;53637;54334.45665543294;71021;71590;73746;74328;77407;7048.577085374894;8032.59837008159;8134.959325203336;8193.549354785544;8208.507489550646;7165.570065796069;9152.313576481924;11027.724306892393;12862.485500579873;12898.161619494704;10501.579936802442;10798.244122911321;11072.560567126222;11085.722856928642;11229.21395502307;5283.947160528345;6975.825604359931;10946.616868409568;11190.776184476428;11386.829197562209;11408.372539510076;11474.827877581676;11484.88515114838;11519;11882.584109556125;8080.515169089874;8161;8202.507849529047;8379.874301885355;8409.2431681149;9750.366226195274;11131.610393636183;11584.652460426201;11591.420428978687;11592.362420066976 19:56:17 INFO - PROCESS | 1646 | |3;mootools.html;1483.3028476615991;1508.0499285450164;1685.511201751492;1780.12773740867;1798.8757026858214;7381.224971378008;7641.923580764191;7911.208879112093;8394.034686011102;8422.11567785383;6603.273639899619;8091.797705057376;8100.675972961089;8229.547374894393;8237.093919668847;2285.645724912637;2329.5457385809773;2329.906803727849;2335.801456876159;2336.976630233693;2326.232343326698;2345.3433038749176;2353.105819788478;2364.6334818103223;2374.8100151987887;2298.5517824024323;2304.2165663674346;2319.211468100845;2327.650852372149;2332;1934.4390126863264;2291.679164916913;2306.285051633999;2310.90756369745;2316.2587971849016;2088.55096154326;2140.518383363746;2322.210448447519;2328.499372634875;2339.578875802355;8176.3050140738105;8235.711750088718;8255.9680039995;8267.503949763033;8269.545175015372;5039.067772462106;5191.2472691459525;5203.219517072432;5213.921791173136;5241.659292145999;3264.608247010374;3344.3144155448076;3603.1712706077487;3607.8556857725657;3612.8735494257576;1043.306201376084;1063.191974099682;1071.201954543862;1080.2114456446839;1105.0551778229658;10686.51910664022;10696.411697356642;10717.62488312913;10784.94607526973;10823;4462.397576327155;4593.242115050979;4647.140279048387;4671.85984420468;4707.741074240881;9293.767655808524;9333.813323733488;9350;9356.06439356059;9373.62505499786;5182.274481572583;5193.454687257784;5201.245819356248;5203.453637368022;5221.477852214824;4264.061906380592;4321.774286242588;4639.536046395401;4723.527647235249;4773.9761301193275;3578.874739384109;3593.5867375252183;3666.450032495147;3667.8166091695844;3702.8148592570265;1073.4739977411011;1202.9879701203051;1203.3141109567637;1206.240068756695;1213.7269114449177;666.8632930249287;709.7835160276062;767.7735068154908;875;889.7642124836923;5096.770645320969;5329.786808527693;5358.544523715528;5602.467765562265;5640.182173584889;829.8879501468061;872.8559787634969;917.6146018672174;923.4551614547449;924.5377311344328;3232.2565809863395;3657.305112028759;3683.318586061587;3688.4467329900194;3719.516462859811;8224.465409748347;9298.72103836886;9654.22766178704;9899.65351212704;9911.306208565331;9668.371555848847;9807.019298070136;9863.309568330147;9871.605135794487;9891.752706182258;7592.734254301073;7805.102413222552;7819.257170568787;7821.491603045898;7831.765047048598;4138.8551400700835;5110.310108135354;5363.812266570651;5364.678119312854;5675.829725108253;9194.632214711604;9196.494192819191;9296.302777291596;9296.674616388394;9376.34365594429;9204.585793639168;9336.719898403058;9418.858717119112;9506.049395060438;9528.237740980843;6569.080328754074;6784.253732089367;7122.145342558926;7138.928610714034;7177.964110179416;7747.690092396467;7927.207279272026;7961.482503637477;7967.721129760663;7973.880391794244;6895.965520172367;7030.859382812418;7042.908349205882;7074.115735533059;7149.356557909813;6832.863342733217;6900.447964162955;6933.711166876923;6933.7226510939;6968.895466568107;6604.603723776589;6633.900491492535;6680.6993685283305;6688.699008544724;6706.429953454011;4129.864287321011;4390.275604525217;4490.820367185407;4511.187986162522;4570.657200710027;4557.886052848573;4642.767861606973;4699.953000470088;4831.492693267156;4945.629077819106 19:56:17 INFO - PROCESS | 1646 | |4;prototype.html;302.80923018498356;305.30847630117785;310.88031108023404;313.1888409020636;317.55859355495863;4144.98447880269;5170.741462926858;5321.840344789653;5463.535599474045;5472.6318420394955;4621.544213572723;4946.777395017224;4975.228839529878;5204.661696989693;5356.625036247464;3583.838727257273;4152.985801886083;4160.687948403866;4187.497500299968;4198.391233271173;4059.6955228357992;4125.938110928339;4175.770332631704;4187.853425130121;4194.909323575877;3885.805709714517;4083.8570650027254;4095.508538975327;4097.549269580344;4126.855560055399;3703.759255648388;4083.9183216335805;4089.243489954353;4109.445224894631;4120.484939382577;2928.282570770175;3151.2121969507625;3234.8867789627484;3364.074879408158;3437.724982001459;3848.499695039655;4607.44710634726;4803.591694705954;5106.795728170869;5141.74291285438;4080.062739456439;4593.425821772278;4599.3672246352735;4626.560476754703;4652.697574657636;5193.688378697252;5616.83751755577;5617.297837770278;5645.604807663465;5654.575906807006;3137.4195773781694;3814.351560234739;3829.9850539607028;3832;3853.364194907837;5875.441833025856;5893.351731309595;5897.292324920994;5922.437368449991;5957.642541447527;3314.4531152360073;3887.378019516864;3891.5135608048995;3899.883003509899;3905.6330284400233;3805.219929914361;3869.187470631199;3932.1545867638597;3942.231264903374;3956.3867600521967;3613.6205698401814;3915.941260881089;4377.92740778505;4482.887927801766;4560.840370587013;5624.775008999676;5643.661380317194;5650.5479561634975;5655.899472882127;5698.458646428583;1216.8014505711867;1239.2502535965791;1242.5526810348267;1265.1966001588873;1286.2796833773118;829.7593697827683;851.7649408357905;861.4572819124037;880.1330689271063;883.412530667112;4965.155923493015;5146.614003949719;5178.378594568676;5186.429492755794;5196;5232.555232805178;5529.723513824292;5552.585694372983;5571.721413929287;5598.356189038232;4095.610916962884;5084.77118529667;5096.770645320969;5098.898022039539;5120.820771272987;2344.710409274926;2539.504796564689;2710.376613378934;2894.9710502895123;2911.8398488082935;5009.924851127236;5457.5633949283965;5785.942140578624;5803.332616749043;5813.970930145322;5061.696298222045;5195.688258704414;5484.040292948718;5526.330250937951;5700.572457065737;4807.543283388072;5580.972095139498;5788.594798364074;5851.717830046286;5864.354920958691;4401.56406837549;5237.371515418175;5312.893742125213;5489.698066606375;5545.944540554704;4843.4430040545785;5271.920921186109;5432.864178395572;5450.373207081237;5533.612647114664;4719.150552900511;5011.674241174312;5198.532132108128;5478.616496845342;5505.366882808368;3290.7696461248443;3516.4901089342416;3633.7819730816236;3678.135638125092;3685.6314368564003;4900.765494773433;5361.892762144813;5458.536024437808;5479.561635069105;5533.37563895649;5008.649394542347;5023.6985780853265;5336.184627991005;5342.805897370546;5384.454012367268;4744.762761861824;5229.163333866715;5306.920396193983;5325.733713314241;5334.013207556614;4656.278276867091;5019.598432125495;5281.313429254326;5524.204633494211;5563.554915606661;4323.135372925491;4981.608827762767;5453.809116680897;5702.971485142548;5716.055302485369;4870.78081486341;4947.925781113214;5451.645643033349;5564.248826408383;5609.523190528687 19:56:17 INFO - PROCESS | 1646 | |5;yui.html;686.5846163071342;730.5069078372097;746.3581320064743;746.3730466408219;791.1771757372333;13686.794698079524;14005.929970350135;14144.646383840383;14287.357068931897;14603.853961460381;12625;13141.408636611352;13271.469141234338;13303.600891973232;13318.001149913747;763.965621547029;825.9297959673435;835.8955130608674;839.0183485322187;841.9747407577752;833.5457175839182;834.6202477872583;836.3685417509772;838.534613289624;840.3781201910573;862.1378621378622;870.0429527519741;873.7902903303225;876.5792419638577;883.4301875290433;855.9144085591422;862.017300277684;865.1305438034766;865.3769286114051;869.4565896314804;639.6194264412713;645.3868824616586;645.8869697802866;646.189032763883;647.7344288841552;12322.322272275022;12391.504339826397;12393.194442361219;12405.069619778462;12452.75094498105;11275.097992160607;11295.378754168518;11339.659810205707;11351.148663850245;11571.768564628745;9938.90061099387;9985.350952188084;10091.192704583616;10407.68776936693;10430.426326552037;3487.616362200156;3517.103138699641;3544.3620148373284;3585.175409655768;3610.205754733954;89700.55149724275;92584.53707731486;93739;94788;94989;8373.07896131425;8492.660293588187;8577.356698247657;8678;8764.78088047804;17820.643587128186;17976.910115449602;18009.819901801075;18184.90907545454;18241.270349185885;7199.964000179965;7301.722198615221;7426.18311985692;7533.221686006724;7614.124375696866;4105.055837157471;4132.359484279941;4240.745555266694;4241.957580424218;4330.328799036154;3280.3767284215924;3393.6606339365867;3420.9486857697157;3425.4519276915576;3428.879989200366;576.8730879206568;582.5136011430418;605.1618508365857;619.306376857922;626.6866566716642;639.3510586754448;647.2362612117652;659.6437923521245;660.8050625065617;716.8315645497805;7445;8935.687250946316;8954.328425368123;9199;9228.5847136879;554.464941331617;618.7277597856929;625.3090335179652;664.1465716554204;668.2415458454689;3651.9634803651625;4148.255655017773;4227.217964676544;4524.298733696282;4561.840335588238;78429;110979.44510277397;117776;120269.39865300618;123315;69906.65046674733;85740;91275.54362228278;96421.51789241009;105683.47158264313;69925.65037174782;73884.63057684785;79249.60375198087;79342.6032869832;83303;8603.483790972561;8900.73297801067;9012.143846334588;9043.32175086858;9047.457152570867;36517.634823651424;45237.77381113205;47794.52205477901;48213.517864820904;52317.7384113077;43802;59892;71338.64330678314;74934.32051588215;78864.60567697124;12006;12070.75858482843;12237.449314781034;12272.877271227173;12384.071194660257;13429.865701343253;13939.790903136258;14036.508722194674;14095.718085638026;14890.883183761045;12592.244465332109;12918.031147663774;13214.669633259246;13740.381682824496;13743.656408589466;12440.377981100728;12453;12830.358482076046;12915.741685166431;12992.480300787862;12731.936340318609;12843.486260549473;12901;13441.596752097452;13467.32663366808;10695.053600151934;11395;11412.942935285271;11413;11575.073994080285;9294.302927280616;9441.622335106518;9758.560864761244;9848.310618256939;9902.306838521234 19:56:17 INFO - PROCESS | 1646 | __end_tp_report 19:56:17 INFO - PROCESS | 1646 | __start_cc_report 19:56:17 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,371 19:56:17 INFO - PROCESS | 1646 | __end_cc_report 19:56:17 INFO - PROCESS | 1646 | __startTimestamp1479959777803__endTimestamp 19:56:17 INFO - PROCESS | 1646 | 19:56:17 INFO - PROCESS | 1646 | ------- Summary: start ------- 19:56:17 INFO - PROCESS | 1646 | Number of tests: 6 19:56:17 INFO - PROCESS | 1646 | 19:56:17 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:5474.07 Median:5643.94 stddev:656.31 (11.6%) stddev-sans-first:631.01 19:56:17 INFO - PROCESS | 1646 | Values: 3085.6 3201.7 3267.3 3318.5 3333.4 4381.8 4943.0 5419.1 5431.8 5519.2 5522.2 5529.6 5544.9 5547.9 5573.5 4271.1 5055.7 5060.6 5129.6 5144.3 4252.1 5027.2 5044.5 5107.0 5162.2 4903.2 4925.1 5011.0 5072.4 5095.6 4946.1 4964.8 4981.2 5006.6 5042.1 4933.5 4953.8 5042.4 5062.0 5097.9 5282.7 5368.1 5478.4 5489.0 5506.4 5936.3 5969.2 5996.8 6009.8 6035.3 5312.8 5985.5 6007.8 6013.2 6029.2 5476.7 5483.6 5492.6 5506.6 5592.0 6107.7 6127.3 6132.8 6137.0 6144.9 5925.2 5971.2 5990.9 6016.8 6048.7 5774.2 6047.8 6095.4 6097.8 6097.9 5014.5 5254.8 5652.9 5793.7 5840.2 5917.3 5954.4 5982.9 6021.7 6080.0 4119.9 4776.6 4786.6 4790.5 4793.6 3995.6 4251.6 4257.7 4263.0 4318.9 4576.3 5576.3 5596.1 5644.4 5681.6 5937.6 5939.6 5989.3 6138.6 6142.9 5669.0 5677.1 5677.3 5695.8 5696.9 5135.0 5146.0 5172.7 5177.9 5204.7 6180.8 6218.3 6225.8 6278.2 6321.7 4658.7 5855.7 6083.0 6106.4 6110.4 5380.4 6139.9 6203.9 6223.1 6268.7 4899.4 5988.0 5989.4 6000.3 6039.2 5930.9 6028.8 6035.1 6081.4 6082.2 5689.9 5782.5 5947.0 5977.2 5986.3 4899.0 5631.9 5716.9 5817.8 5835.5 5467.1 5522.7 5604.8 5643.9 5663.8 5694.3 5718.1 5719.5 5752.9 5755.3 5547.7 5621.8 5638.1 5647.1 5670.3 19:56:17 INFO - PROCESS | 1646 | 19:56:17 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:72207.33 Median:39253.21 stddev:163063.25 (415.4%) stddev-sans-first:163431.73 19:56:17 INFO - PROCESS | 1646 | Values: 471.5 515.5 524.2 541.8 548.8 37929.6 39315.6 39574.8 41317.4 41683.4 40447.4 41003.4 41127.0 41237.4 41255.8 9256.3 9444.3 9454.4 9465.1 9478.0 9360.9 9408.0 9449.5 9462.1 9464.0 9445.0 9464.1 9477.2 9494.1 9522.7 9452.1 9475.5 9476.0 9481.7 9488.9 8961.4 9380.8 9438.0 9440.8 9457.7 38849.2 39090.0 39108.8 39134.6 39217.4 3218.7 3291.0 3316.7 3341.0 3345.9 22740.0 28950.4 29263.6 30304.5 30486.0 3088.8 3165.9 3234.8 3338.6 3343.4 478203.0 487469.0 488751.0 489813.6 490637.0 57759.4 57967.7 58107.0 58263.1 58432.4 59806.0 60261.4 60464.1 60501.7 60826.1 15987.0 16094.9 16200.8 16218.0 16239.9 12460.3 12513.9 12552.5 12566.5 12605.8 13129.0 14060.4 14170.2 14961.6 15853.7 2732.2 3281.9 3303.0 6872.3 7005.2 817.4 875.2 939.7 977.2 978.3 23263.0 28964.0 29278.9 29766.4 30557.6 960.8 1035.4 1056.1 1063.5 1109.4 12903.7 15485.8 15645.8 15752.6 15760.9 679849.0 687635.0 688047.0 688554.0 689821.0 56915.4 57128.0 57185.4 57212.0 57226.4 1312.7 1331.5 1341.1 1341.5 1343.7 22970.3 28813.7 29554.4 30048.7 30191.4 49196.0 49471.5 49521.5 49533.0 49546.3 627931.0 632515.0 632741.8 634249.0 637575.0 25936.0 26233.6 26962.9 26966.9 27926.9 45246.1 45277.0 45709.8 46147.8 46455.8 36970.9 37276.7 37838.2 38680.0 39050.8 37895.0 38299.8 38540.2 39003.6 39289.0 36518.5 36794.8 37316.0 37579.0 37870.6 25944.9 28037.5 28844.0 32732.7 40179.4 22786.2 23883.5 25396.4 25471.9 25870.0 19:56:17 INFO - PROCESS | 1646 | 19:56:17 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:34209.48 Median:392.22 stddev:99475.44 (25362.1%) stddev-sans-first:99722.04 19:56:17 INFO - PROCESS | 1646 | Values: 368.1 375.8 383.2 385.1 399.3 17723.0 17749.0 18419.4 20039.0 20771.0 16690.8 19350.1 20040.9 20293.6 20372.4 5495.1 5584.2 5623.9 5889.1 5895.5 5699.8 5937.6 5942.1 5943.6 5958.1 5595.7 5738.4 5752.7 5884.3 5887.3 5666.3 5725.4 5730.3 5734.2 5751.4 4855.4 5029.0 5110.6 5155.4 5168.8 24844.9 24878.0 25084.9 25222.4 25278.2 2213.2 2320.7 2380.1 2642.6 2731.7 14825.4 14916.6 14924.8 15643.5 15714.7 1775.7 1876.0 1895.4 1901.8 1949.6 118059.4 120481.2 120996.0 121134.4 121240.0 10373.5 10641.9 10746.0 10972.3 11054.9 13486.6 13516.7 13533.2 13542.8 13575.7 8369.1 8630.9 8663.8 8694.9 8752.3 8696.8 8699.2 8701.6 8810.3 8837.6 6565.6 6647.9 6679.6 6698.6 6721.9 1819.2 1839.8 1840.4 1846.3 1848.0 868.6 906.7 912.4 916.4 931.7 20488.0 21802.7 21822.2 21869.5 22003.0 868.3 926.3 937.6 950.6 1040.2 9719.8 11583.9 11710.5 11731.6 11759.4 566605.0 595122.0 602006.0 611092.0 621695.0 78234.0 90036.0 93524.5 116742.0 120590.4 874.8 931.7 978.9 998.2 999.1 17479.4 17999.7 18790.7 19185.9 19719.5 51437.0 52238.2 52910.5 53637.0 54334.5 71021.0 71590.0 73746.0 74328.0 77407.0 7048.6 8032.6 8135.0 8193.5 8208.5 7165.6 9152.3 11027.7 12862.5 12898.2 10501.6 10798.2 11072.6 11085.7 11229.2 5283.9 6975.8 10946.6 11190.8 11386.8 11408.4 11474.8 11484.9 11519.0 11882.6 8080.5 8161.0 8202.5 8379.9 8409.2 9750.4 11131.6 11584.7 11591.4 11592.4 19:56:17 INFO - PROCESS | 1646 | 19:56:17 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:5341.15 Median:5217.70 stddev:2928.44 (56.1%) stddev-sans-first:2922.30 19:56:17 INFO - PROCESS | 1646 | Values: 1483.3 1508.0 1685.5 1780.1 1798.9 7381.2 7641.9 7911.2 8394.0 8422.1 6603.3 8091.8 8100.7 8229.5 8237.1 2285.6 2329.5 2329.9 2335.8 2337.0 2326.2 2345.3 2353.1 2364.6 2374.8 2298.6 2304.2 2319.2 2327.7 2332.0 1934.4 2291.7 2306.3 2310.9 2316.3 2088.6 2140.5 2322.2 2328.5 2339.6 8176.3 8235.7 8256.0 8267.5 8269.5 5039.1 5191.2 5203.2 5213.9 5241.7 3264.6 3344.3 3603.2 3607.9 3612.9 1043.3 1063.2 1071.2 1080.2 1105.1 10686.5 10696.4 10717.6 10784.9 10823.0 4462.4 4593.2 4647.1 4671.9 4707.7 9293.8 9333.8 9350.0 9356.1 9373.6 5182.3 5193.5 5201.2 5203.5 5221.5 4264.1 4321.8 4639.5 4723.5 4774.0 3578.9 3593.6 3666.5 3667.8 3702.8 1073.5 1203.0 1203.3 1206.2 1213.7 666.9 709.8 767.8 875.0 889.8 5096.8 5329.8 5358.5 5602.5 5640.2 829.9 872.9 917.6 923.5 924.5 3232.3 3657.3 3683.3 3688.4 3719.5 8224.5 9298.7 9654.2 9899.7 9911.3 9668.4 9807.0 9863.3 9871.6 9891.8 7592.7 7805.1 7819.3 7821.5 7831.8 4138.9 5110.3 5363.8 5364.7 5675.8 9194.6 9196.5 9296.3 9296.7 9376.3 9204.6 9336.7 9418.9 9506.0 9528.2 6569.1 6784.3 7122.1 7138.9 7178.0 7747.7 7927.2 7961.5 7967.7 7973.9 6896.0 7030.9 7042.9 7074.1 7149.4 6832.9 6900.4 6933.7 6933.7 6968.9 6604.6 6633.9 6680.7 6688.7 6706.4 4129.9 4390.3 4490.8 4511.2 4570.7 4557.9 4642.8 4700.0 4831.5 4945.6 19:56:17 INFO - PROCESS | 1646 | 19:56:17 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4428.99 Median:5009.29 stddev:1364.26 (27.2%) stddev-sans-first:1332.47 19:56:17 INFO - PROCESS | 1646 | Values: 302.8 305.3 310.9 313.2 317.6 4145.0 5170.7 5321.8 5463.5 5472.6 4621.5 4946.8 4975.2 5204.7 5356.6 3583.8 4153.0 4160.7 4187.5 4198.4 4059.7 4125.9 4175.8 4187.9 4194.9 3885.8 4083.9 4095.5 4097.5 4126.9 3703.8 4083.9 4089.2 4109.4 4120.5 2928.3 3151.2 3234.9 3364.1 3437.7 3848.5 4607.4 4803.6 5106.8 5141.7 4080.1 4593.4 4599.4 4626.6 4652.7 5193.7 5616.8 5617.3 5645.6 5654.6 3137.4 3814.4 3830.0 3832.0 3853.4 5875.4 5893.4 5897.3 5922.4 5957.6 3314.5 3887.4 3891.5 3899.9 3905.6 3805.2 3869.2 3932.2 3942.2 3956.4 3613.6 3915.9 4377.9 4482.9 4560.8 5624.8 5643.7 5650.5 5655.9 5698.5 1216.8 1239.3 1242.6 1265.2 1286.3 829.8 851.8 861.5 880.1 883.4 4965.2 5146.6 5178.4 5186.4 5196.0 5232.6 5529.7 5552.6 5571.7 5598.4 4095.6 5084.8 5096.8 5098.9 5120.8 2344.7 2539.5 2710.4 2895.0 2911.8 5009.9 5457.6 5785.9 5803.3 5814.0 5061.7 5195.7 5484.0 5526.3 5700.6 4807.5 5581.0 5788.6 5851.7 5864.4 4401.6 5237.4 5312.9 5489.7 5545.9 4843.4 5271.9 5432.9 5450.4 5533.6 4719.2 5011.7 5198.5 5478.6 5505.4 3290.8 3516.5 3633.8 3678.1 3685.6 4900.8 5361.9 5458.5 5479.6 5533.4 5008.6 5023.7 5336.2 5342.8 5384.5 4744.8 5229.2 5306.9 5325.7 5334.0 4656.3 5019.6 5281.3 5524.2 5563.6 4323.1 4981.6 5453.8 5703.0 5716.1 4870.8 4947.9 5451.6 5564.2 5609.5 19:56:17 INFO - PROCESS | 1646 | 19:56:17 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:19535.38 Median:579.69 stddev:29268.70 (5049.0%) stddev-sans-first:29316.59 19:56:17 INFO - PROCESS | 1646 | Values: 686.6 730.5 746.4 746.4 791.2 13686.8 14005.9 14144.6 14287.4 14603.9 12625.0 13141.4 13271.5 13303.6 13318.0 764.0 825.9 835.9 839.0 842.0 833.5 834.6 836.4 838.5 840.4 862.1 870.0 873.8 876.6 883.4 855.9 862.0 865.1 865.4 869.5 639.6 645.4 645.9 646.2 647.7 12322.3 12391.5 12393.2 12405.1 12452.8 11275.1 11295.4 11339.7 11351.1 11571.8 9938.9 9985.4 10091.2 10407.7 10430.4 3487.6 3517.1 3544.4 3585.2 3610.2 89700.6 92584.5 93739.0 94788.0 94989.0 8373.1 8492.7 8577.4 8678.0 8764.8 17820.6 17976.9 18009.8 18184.9 18241.3 7200.0 7301.7 7426.2 7533.2 7614.1 4105.1 4132.4 4240.7 4242.0 4330.3 3280.4 3393.7 3420.9 3425.5 3428.9 576.9 582.5 605.2 619.3 626.7 639.4 647.2 659.6 660.8 716.8 7445.0 8935.7 8954.3 9199.0 9228.6 554.5 618.7 625.3 664.1 668.2 3652.0 4148.3 4227.2 4524.3 4561.8 78429.0 110979.4 117776.0 120269.4 123315.0 69906.7 85740.0 91275.5 96421.5 105683.5 69925.7 73884.6 79249.6 79342.6 83303.0 8603.5 8900.7 9012.1 9043.3 9047.5 36517.6 45237.8 47794.5 48213.5 52317.7 43802.0 59892.0 71338.6 74934.3 78864.6 12006.0 12070.8 12237.4 12272.9 12384.1 13429.9 13939.8 14036.5 14095.7 14890.9 12592.2 12918.0 13214.7 13740.4 13743.7 12440.4 12453.0 12830.4 12915.7 12992.5 12731.9 12843.5 12901.0 13441.6 13467.3 10695.1 11395.0 11412.9 11413.0 11575.1 9294.3 9441.6 9758.6 9848.3 9902.3 19:56:17 INFO - PROCESS | 1646 | -------- Summary: end -------- 19:56:17 INFO - PROCESS | 1646 | 19:56:18 INFO - TEST-INFO | 1646: exit 0 19:56:18 INFO - TEST-OK | dromaeo_css | took 1116135ms 19:56:18 INFO - TEST-START | kraken 19:56:18 INFO - operating with platform_type : mac_ 19:56:18 INFO - Initialising browser for kraken test... 19:56:18 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpsTZJLK/profile http://localhost:49242/getInfo.html) 19:56:20 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 19:56:21 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 19:56:21 INFO - PROCESS | 1654 | colorDepth:24 19:56:21 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 19:56:21 INFO - PROCESS | 1654 | __metrics 19:56:21 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 19:56:22 INFO - TEST-INFO | 1654: exit 0 19:56:22 INFO - Browser initialized. 19:56:22 INFO - Running cycle 1/1 for kraken test... 19:56:22 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpsTZJLK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:56:34 INFO - PROCESS | 1656 | RSS: Main: 229949440 19:56:34 INFO - PROCESS | 1656 | 19:56:37 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 19:56:38 INFO - PROCESS | 1656 | RSS: Main: 241754112 19:56:38 INFO - PROCESS | 1656 | 19:56:40 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 19:56:41 INFO - PROCESS | 1656 | RSS: Main: 249397248 19:56:41 INFO - PROCESS | 1656 | 19:56:44 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 19:56:45 INFO - PROCESS | 1656 | RSS: Main: 253173760 19:56:45 INFO - PROCESS | 1656 | 19:56:47 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 19:56:48 INFO - PROCESS | 1656 | RSS: Main: 254664704 19:56:48 INFO - PROCESS | 1656 | 19:56:50 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:56:50 INFO - PROCESS | 1656 | RSS: Main: 254775296 19:56:50 INFO - PROCESS | 1656 | 19:56:53 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 19:56:54 INFO - PROCESS | 1656 | RSS: Main: 258744320 19:56:54 INFO - PROCESS | 1656 | 19:56:57 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 19:56:57 INFO - PROCESS | 1656 | RSS: Main: 259092480 19:56:57 INFO - PROCESS | 1656 | 19:57:00 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 19:57:01 INFO - PROCESS | 1656 | RSS: Main: 258277376 19:57:01 INFO - PROCESS | 1656 | 19:57:02 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:57:03 INFO - PROCESS | 1656 | RSS: Main: 257245184 19:57:03 INFO - PROCESS | 1656 | 19:57:05 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:57:06 INFO - PROCESS | 1656 | RSS: Main: 257331200 19:57:06 INFO - PROCESS | 1656 | 19:57:08 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:57:09 INFO - PROCESS | 1656 | RSS: Main: 257286144 19:57:09 INFO - PROCESS | 1656 | 19:57:12 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:57:12 INFO - PROCESS | 1656 | RSS: Main: 257736704 19:57:12 INFO - PROCESS | 1656 | 19:57:15 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:57:16 INFO - PROCESS | 1656 | RSS: Main: 257630208 19:57:16 INFO - PROCESS | 1656 | 19:57:18 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 19:57:18 INFO - PROCESS | 1656 | RSS: Main: 259416064 19:57:18 INFO - PROCESS | 1656 | 19:57:18 INFO - PROCESS | 1656 | __start_tp_report 19:57:18 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 19:57:18 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 19:57:18 INFO - PROCESS | 1656 | |i|pagename|runs| 19:57:18 INFO - PROCESS | 1656 | |0;ai-astar;85;119;175;174;118;118;120;120;120;118 19:57:18 INFO - PROCESS | 1656 | |1;audio-beat-detection;143;134;130;152;117;120;119;115;144;117 19:57:18 INFO - PROCESS | 1656 | |2;audio-dft;202;227;282;237;206;198;194;200;193;198 19:57:18 INFO - PROCESS | 1656 | |3;audio-fft;83;59;61;85;62;60;59;60;80;59 19:57:18 INFO - PROCESS | 1656 | |4;audio-oscillator;90;72;90;71;70;72;88;70;74;89 19:57:18 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;89;86;88;95;90;87;88;90;137;132 19:57:18 INFO - PROCESS | 1656 | |6;imaging-darkroom;132;100;98;103;97;98;99;97;98;98 19:57:18 INFO - PROCESS | 1656 | |7;imaging-desaturate;92;141;133;87;89;97;89;140;144;71 19:57:18 INFO - PROCESS | 1656 | |8;json-parse-financial;56;55;56;56;60;61;60;59;59;60 19:57:18 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;69;73;104;108;108;75;69;73;69;71 19:57:18 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;86;89;85;88;75;105;109;102;110;85 19:57:18 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;149;150;149;165;156;162;142;152;198;152 19:57:18 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;172;205;176;169;150;222;167;172;177;188 19:57:18 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;68;69;67;68;70;79;91;92;72;60 19:57:18 INFO - PROCESS | 1656 | __end_tp_report 19:57:18 INFO - PROCESS | 1656 | __start_cc_report 19:57:18 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,969 19:57:18 INFO - PROCESS | 1656 | __end_cc_report 19:57:18 INFO - PROCESS | 1656 | __startTimestamp1479959838500__endTimestamp 19:57:18 INFO - PROCESS | 1656 | 19:57:18 INFO - PROCESS | 1656 | ------- Summary: start ------- 19:57:18 INFO - PROCESS | 1656 | Number of tests: 14 19:57:18 INFO - PROCESS | 1656 | 19:57:18 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:126.70 Median:120.00 stddev:27.35 (22.8%) stddev-sans-first:24.49 19:57:18 INFO - PROCESS | 1656 | Values: 85.0 119.0 175.0 174.0 118.0 118.0 120.0 120.0 120.0 118.0 19:57:18 INFO - PROCESS | 1656 | 19:57:18 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:129.10 Median:132.00 stddev:13.50 (10.2%) stddev-sans-first:13.35 19:57:18 INFO - PROCESS | 1656 | Values: 143.0 134.0 130.0 152.0 117.0 120.0 119.0 115.0 144.0 117.0 19:57:18 INFO - PROCESS | 1656 | 19:57:18 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:213.70 Median:204.00 stddev:28.04 (13.7%) stddev-sans-first:29.42 19:57:18 INFO - PROCESS | 1656 | Values: 202.0 227.0 282.0 237.0 206.0 198.0 194.0 200.0 193.0 198.0 19:57:18 INFO - PROCESS | 1656 | 19:57:18 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:66.80 Median:61.50 stddev:11.05 (18.0%) stddev-sans-first:10.05 19:57:18 INFO - PROCESS | 1656 | Values: 83.0 59.0 61.0 85.0 62.0 60.0 59.0 60.0 80.0 59.0 19:57:18 INFO - PROCESS | 1656 | 19:57:18 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:78.60 Median:81.00 stddev:9.25 (11.4%) stddev-sans-first:8.85 19:57:18 INFO - PROCESS | 1656 | Values: 90.0 72.0 90.0 71.0 70.0 72.0 88.0 70.0 74.0 89.0 19:57:18 INFO - PROCESS | 1656 | 19:57:18 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:98.20 Median:88.50 stddev:19.32 (21.8%) stddev-sans-first:20.20 19:57:18 INFO - PROCESS | 1656 | Values: 89.0 86.0 88.0 95.0 90.0 87.0 88.0 90.0 137.0 132.0 19:57:18 INFO - PROCESS | 1656 | 19:57:18 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:102.00 Median:98.00 stddev:10.69 (10.9%) stddev-sans-first:1.87 19:57:18 INFO - PROCESS | 1656 | Values: 132.0 100.0 98.0 103.0 97.0 98.0 99.0 97.0 98.0 98.0 19:57:18 INFO - PROCESS | 1656 | 19:57:18 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:108.30 Median:88.00 stddev:27.77 (31.6%) stddev-sans-first:28.82 19:57:18 INFO - PROCESS | 1656 | Values: 92.0 141.0 133.0 87.0 89.0 97.0 89.0 140.0 144.0 71.0 19:57:18 INFO - PROCESS | 1656 | 19:57:18 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:58.20 Median:59.50 stddev:2.20 (3.7%) stddev-sans-first:2.19 19:57:18 INFO - PROCESS | 1656 | Values: 56.0 55.0 56.0 56.0 60.0 61.0 60.0 59.0 59.0 60.0 19:57:18 INFO - PROCESS | 1656 | 19:57:18 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:81.90 Median:70.00 stddev:17.24 (24.6%) stddev-sans-first:17.64 19:57:18 INFO - PROCESS | 1656 | Values: 69.0 73.0 104.0 108.0 108.0 75.0 69.0 73.0 69.0 71.0 19:57:18 INFO - PROCESS | 1656 | 19:57:18 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:93.40 Median:85.00 stddev:12.07 (14.2%) stddev-sans-first:12.50 19:57:18 INFO - PROCESS | 1656 | Values: 86.0 89.0 85.0 88.0 75.0 105.0 109.0 102.0 110.0 85.0 19:57:18 INFO - PROCESS | 1656 | 19:57:18 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:157.50 Median:154.00 stddev:15.71 (10.2%) stddev-sans-first:16.36 19:57:18 INFO - PROCESS | 1656 | Values: 149.0 150.0 149.0 165.0 156.0 162.0 142.0 152.0 198.0 152.0 19:57:18 INFO - PROCESS | 1656 | 19:57:18 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.80 Median:176.50 stddev:20.54 (11.6%) stddev-sans-first:21.59 19:57:18 INFO - PROCESS | 1656 | Values: 172.0 205.0 176.0 169.0 150.0 222.0 167.0 172.0 177.0 188.0 19:57:18 INFO - PROCESS | 1656 | 19:57:18 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.60 Median:71.00 stddev:10.53 (14.8%) stddev-sans-first:10.97 19:57:18 INFO - PROCESS | 1656 | Values: 68.0 69.0 67.0 68.0 70.0 79.0 91.0 92.0 72.0 60.0 19:57:18 INFO - PROCESS | 1656 | -------- Summary: end -------- 19:57:18 INFO - PROCESS | 1656 | 19:57:19 INFO - TEST-INFO | 1656: exit 0 19:57:19 INFO - TEST-OK | kraken | took 61089ms 19:57:19 INFO - SUITE-END | took 1177s 19:57:19 INFO - Completed test suite (00:19:37) 19:57:19 INFO - javascript benchmark 19:57:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7030.89005168047, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3085.6142982127235, 3201.6798320167977, 3267.2811981364093, 3318.5022246662998, 3333.3666603345346, 4381.780910954448, 4942.961977984619, 5419.132938729804, 5431.809886653968, 5519.199716041172, 5522.22688823564, 5529.612927095105, 5544.889102217953, 5547.861303467415, 5573.526250268722, 4271.103068355649, 5055.671381360218, 5060.620453465986, 5129.615278854082, 5144.25408315794, 4252.14957008598, 5027.195648696209, 5044.545990860822, 5107.0, 5162.199859021857, 4903.166461701519, 4925.138100832376, 5010.997800439908, 5072.442031376582, 5095.566876815476, 4946.060248552763, 4964.776585053682, 4981.153203955337, 5006.5744411725045, 5042.067217564762, 4933.45731969483, 4953.801847926079, 5042.445331013586, 5061.974690126563, 5097.898042039138, 5282.735863206863, 5368.141097424433, 5478.397376288605, 5488.9725551371985, 5506.394296627368, 5936.347001829795, 5969.164316995624, 5996.820095397145, 6009.849753756191, 6035.3059398169335, 5312.751503396698, 5985.461308482258, 6007.8197654070445, 6013.188219590343, 6029.246344206974, 5476.726163691799, 5483.588730845203, 5492.588055895823, 5506.614536982413, 5591.965486385032, 6107.725152368154, 6127.34224287047, 6132.7546898124465, 6137.039921714258, 6144.8771024579255, 5925.170476133345, 5971.223740913741, 5990.850228744229, 6016.789412370546, 6048.667323297261, 5774.249347584787, 6047.758089676452, 6095.420935011167, 6097.817065488042, 6097.939020609826, 5014.548690617862, 5254.7635356409055, 5652.85867853307, 5793.652380857077, 5840.153177789197, 5917.260342457193, 5954.374790647006, 5982.880342393128, 6021.668808215591, 6080.0, 4119.908224320558, 4776.5701086902345, 4786.593139583105, 4790.473047964721, 4793.592544633745, 3995.5604883462797, 4251.5748425157235, 4257.680673949466, 4263.0, 4318.892027699333, 4576.313552967082, 5576.275084239104, 5596.104623260258, 5644.435556444323, 5681.57388195887, 5937.584369094208, 5939.584229104008, 5989.251343582052, 6138.570300079041, 6142.938570614237, 5669.0, 5677.09166533361, 5677.290338707661, 5695.829125126253, 5696.914546281809, 5135.024345374367, 5145.974270128625, 5172.689638621619, 5177.896442071213, 5204.661696989683, 6180.7527698891545, 6218.31598524162, 6225.750969961241, 6278.215223097113, 6321.652309123042, 4658.650601204856, 5855.677937713466, 6082.9695851520455, 6106.419890110433, 6110.419510146529, 5380.381256155592, 6139.8601398601395, 6203.875922481616, 6223.128761973418, 6268.686565671607, 4899.412070551557, 5988.041913293852, 5989.431004054608, 6000.339962604197, 6039.184710064085, 5930.911036334544, 6028.819135425944, 6035.124906888389, 6081.422264884829, 6082.2093127893095, 5689.857753556194, 5782.508486778671, 5947.0, 5977.163197152315, 5986.341502434641, 4899.0, 5631.88736225265, 5716.8570785729025, 5817.7963771267805, 5835.4748072673665, 5467.125259958388, 5522.66863988162, 5604.775808967595, 5643.943560564342, 5663.8017669383025, 5694.288213973254, 5718.142278658068, 5719.542436604978, 5752.884942301214, 5755.251817263729, 5547.722613869371, 5621.831345059655, 5638.126090455986, 5647.0964645656495, 5670.262865827581], "value": 5433.6662015845, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.4695967037083, 515.5257163409664, 524.2241482605746, 541.7507946344681, 548.8353493951813, 37929.620703792956, 39315.60684393155, 39574.802125989365, 41317.38023929636, 41683.37474937878, 40447.393289100684, 41003.384949225714, 41127.0, 41237.38143927843, 41255.793721031354, 9256.25949924006, 9444.291678124135, 9454.43273403598, 9465.148136667698, 9477.95261023694, 9360.859587106199, 9408.0, 9449.480278584675, 9462.053794620551, 9463.952680236589, 9445.0, 9464.1482266596, 9477.241820654332, 9494.098060684259, 9522.666706665268, 9452.054794520562, 9475.47884866332, 9476.00501947296, 9481.66814161501, 9488.857667134998, 8961.372703910729, 9380.812383752287, 9438.0, 9440.763980900463, 9457.716268511957, 38849.22301553953, 39090.0, 39108.80445597782, 39134.60865391338, 39217.411738823655, 3218.6620404857385, 3291.045596776956, 3316.7346612270962, 3340.9832950835334, 3345.8996230113135, 22739.976701048494, 28950.42099158005, 29263.561046584317, 30304.54543181854, 30486.0, 3088.7837851350414, 3165.889193878203, 3234.75739319552, 3338.6160591532043, 3343.414902392096, 478203.0, 487469.0, 488751.0, 489813.5509322466, 490637.0, 57759.4224057754, 57967.710161449766, 58107.0, 58263.126053109234, 58432.4156758427, 59806.0, 60261.39738602645, 60464.09303860446, 60501.69749151226, 60826.087608685906, 15987.0, 16094.919525402533, 16200.75698864518, 16218.0, 16239.918800405922, 12460.252384856938, 12513.874861251454, 12552.497900084078, 12566.497340106293, 12605.810912836314, 13129.0, 14060.367283472267, 14170.220637865017, 14961.551153465414, 15853.68292634141, 2732.221316924667, 3281.917952051218, 3302.966970330314, 6872.347127022925, 7005.229424763272, 817.435969181263, 875.2429148786254, 939.6523286384082, 977.237754551451, 978.3298440568234, 23263.0, 28964.0, 29278.925712800276, 29766.40467190644, 30557.624906879242, 960.7502049467189, 1035.358077991635, 1056.0812093478723, 1063.5107850388908, 1109.3676604335612, 12903.741925161632, 15485.838562107887, 15645.765313520305, 15752.60618484547, 15760.92119539418, 679849.0, 687635.0, 688047.0, 688554.0, 689821.0, 56915.430845691015, 57128.0, 57185.42814571801, 57212.0, 57226.42773572211, 1312.6783937935268, 1331.533963112922, 1341.1282666267005, 1341.4500054977411, 1343.670800653846, 22970.310890673307, 28813.711862881104, 29554.408911822073, 30048.699513005464, 30191.396172076875, 49196.0, 49471.50528494813, 49521.50478495169, 49533.0, 49546.25680614721, 627931.0, 632515.0, 632741.8362908156, 634249.0, 637575.0, 25936.0, 26233.606495902193, 26962.865185673945, 26966.865165674048, 27926.86036569804, 45246.095078098915, 45277.0, 45709.77145114253, 46147.76926115348, 46455.76772116118, 36970.89087327384, 37276.69531566382, 37838.24323513459, 38680.0, 39050.804745976086, 37895.0, 38299.80850095732, 38540.2291954165, 39003.609963899995, 39289.0, 36518.45222321614, 36794.816025919696, 37316.0, 37579.0, 37870.62129378781, 25944.870275649257, 28037.457939813507, 28844.0, 32732.672673272962, 40179.392824286704, 22786.24027035587, 23883.522329552965, 25396.365090872878, 25471.872640636677, 25870.0], "value": 19244.097872135775, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.1403921842496, 375.75951391109686, 383.1608776778854, 385.1315284034502, 399.3111882003544, 17723.0, 17749.0, 18419.35532256371, 20039.0, 20771.0, 16690.83309166908, 19350.12924418401, 20040.899795501038, 20293.594128117427, 20372.388828335137, 5495.120780675093, 5584.21820945067, 5623.887522249553, 5889.116632505137, 5895.469407753301, 5699.800506982257, 5937.643741375509, 5942.078977758454, 5943.55423343251, 5958.106284057404, 5595.692236926968, 5738.3687794342795, 5752.74112664929, 5884.323302820188, 5887.264091988502, 5666.348369937448, 5725.427457254283, 5730.283714535683, 5734.225879506255, 5751.396103409144, 4855.417349917997, 5028.974855125701, 5110.565601923841, 5155.432902380735, 5168.811173430132, 24844.88198031093, 24878.0, 25084.87457562719, 25222.369440763945, 25278.241652750447, 2213.236433430464, 2320.709911261092, 2380.1074597026113, 2642.643243162187, 2731.685856126551, 14825.406983720639, 14916.552503424915, 14924.776128357975, 15643.530694079196, 15714.685706285925, 1775.7070083436217, 1876.0, 1895.3546490624576, 1901.8288354048202, 1949.6393167264102, 118059.4097029518, 120481.19278210746, 120996.0, 121134.39432802779, 121240.0, 10373.533191006272, 10641.89358106409, 10746.0, 10972.286801357886, 11054.944725276322, 13486.595402137951, 13516.662083447796, 13533.188008719311, 13542.796858047137, 13575.728485430434, 8369.121242269595, 8630.913690863137, 8663.826723465496, 8694.869666943383, 8752.256058234994, 8696.78258043554, 8699.217070463688, 8701.564921753887, 8810.251128654008, 8837.60230789616, 6565.638889861103, 6647.867042659216, 6679.595345675553, 6698.631575263407, 6721.857284261687, 1819.1722766141374, 1839.7516335294831, 1840.4386662068198, 1846.2984066054814, 1848.02978436322, 868.5570359116896, 906.6962567539948, 912.3567884641312, 916.4226537281603, 931.7344556801414, 20487.975601219878, 21802.672959905613, 21822.236221732164, 21869.453263668216, 22003.0, 868.2923417414787, 926.3098991251561, 937.5780898595659, 950.6435086842434, 1040.1522758951583, 9719.757006074904, 11583.884161158281, 11710.473028713557, 11731.648050558497, 11759.353235572125, 566605.0, 595122.0, 602006.0, 611092.0, 621695.0, 78234.0, 90036.0, 93524.53237733767, 116742.0, 120590.3970480142, 874.7927859553902, 931.7111695374456, 978.9070038346346, 998.1914649134225, 999.1107913956585, 17479.416719572713, 17999.730004050212, 18790.718139228196, 19185.90407047956, 19719.507012324233, 51437.0, 52238.21642675287, 52910.47089529209, 53637.0, 54334.45665543294, 71021.0, 71590.0, 73746.0, 74328.0, 77407.0, 7048.577085374894, 8032.59837008159, 8134.959325203336, 8193.549354785544, 8208.507489550646, 7165.570065796069, 9152.313576481924, 11027.724306892393, 12862.485500579873, 12898.161619494704, 10501.579936802442, 10798.244122911321, 11072.560567126222, 11085.722856928642, 11229.21395502307, 5283.947160528345, 6975.825604359931, 10946.616868409568, 11190.776184476428, 11386.829197562209, 11408.372539510076, 11474.827877581676, 11484.88515114838, 11519.0, 11882.584109556125, 8080.515169089874, 8161.0, 8202.507849529047, 8379.874301885355, 8409.2431681149, 9750.366226195274, 11131.610393636183, 11584.652460426201, 11591.420428978687, 11592.362420066976], "value": 9527.853046580276, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1483.3028476615991, 1508.0499285450164, 1685.511201751492, 1780.12773740867, 1798.8757026858214, 7381.224971378008, 7641.923580764191, 7911.208879112093, 8394.034686011102, 8422.11567785383, 6603.273639899619, 8091.797705057376, 8100.675972961089, 8229.547374894393, 8237.093919668847, 2285.645724912637, 2329.5457385809773, 2329.906803727849, 2335.801456876159, 2336.976630233693, 2326.232343326698, 2345.3433038749176, 2353.105819788478, 2364.6334818103223, 2374.8100151987887, 2298.5517824024323, 2304.2165663674346, 2319.211468100845, 2327.650852372149, 2332.0, 1934.4390126863264, 2291.679164916913, 2306.285051633999, 2310.90756369745, 2316.2587971849016, 2088.55096154326, 2140.518383363746, 2322.210448447519, 2328.499372634875, 2339.578875802355, 8176.3050140738105, 8235.711750088718, 8255.9680039995, 8267.503949763033, 8269.545175015372, 5039.067772462106, 5191.2472691459525, 5203.219517072432, 5213.921791173136, 5241.659292145999, 3264.608247010374, 3344.3144155448076, 3603.1712706077487, 3607.8556857725657, 3612.8735494257576, 1043.306201376084, 1063.191974099682, 1071.201954543862, 1080.2114456446839, 1105.0551778229658, 10686.51910664022, 10696.411697356642, 10717.62488312913, 10784.94607526973, 10823.0, 4462.397576327155, 4593.242115050979, 4647.140279048387, 4671.85984420468, 4707.741074240881, 9293.767655808524, 9333.813323733488, 9350.0, 9356.06439356059, 9373.62505499786, 5182.274481572583, 5193.454687257784, 5201.245819356248, 5203.453637368022, 5221.477852214824, 4264.061906380592, 4321.774286242588, 4639.536046395401, 4723.527647235249, 4773.9761301193275, 3578.874739384109, 3593.5867375252183, 3666.450032495147, 3667.8166091695844, 3702.8148592570265, 1073.4739977411011, 1202.9879701203051, 1203.3141109567637, 1206.240068756695, 1213.7269114449177, 666.8632930249287, 709.7835160276062, 767.7735068154908, 875.0, 889.7642124836923, 5096.770645320969, 5329.786808527693, 5358.544523715528, 5602.467765562265, 5640.182173584889, 829.8879501468061, 872.8559787634969, 917.6146018672174, 923.4551614547449, 924.5377311344328, 3232.2565809863395, 3657.305112028759, 3683.318586061587, 3688.4467329900194, 3719.516462859811, 8224.465409748347, 9298.72103836886, 9654.22766178704, 9899.65351212704, 9911.306208565331, 9668.371555848847, 9807.019298070136, 9863.309568330147, 9871.605135794487, 9891.752706182258, 7592.734254301073, 7805.102413222552, 7819.257170568787, 7821.491603045898, 7831.765047048598, 4138.8551400700835, 5110.310108135354, 5363.812266570651, 5364.678119312854, 5675.829725108253, 9194.632214711604, 9196.494192819191, 9296.302777291596, 9296.674616388394, 9376.34365594429, 9204.585793639168, 9336.719898403058, 9418.858717119112, 9506.049395060438, 9528.237740980843, 6569.080328754074, 6784.253732089367, 7122.145342558926, 7138.928610714034, 7177.964110179416, 7747.690092396467, 7927.207279272026, 7961.482503637477, 7967.721129760663, 7973.880391794244, 6895.965520172367, 7030.859382812418, 7042.908349205882, 7074.115735533059, 7149.356557909813, 6832.863342733217, 6900.447964162955, 6933.711166876923, 6933.7226510939, 6968.895466568107, 6604.603723776589, 6633.900491492535, 6680.6993685283305, 6688.699008544724, 6706.429953454011, 4129.864287321011, 4390.275604525217, 4490.820367185407, 4511.187986162522, 4570.657200710027, 4557.886052848573, 4642.767861606973, 4699.953000470088, 4831.492693267156, 4945.629077819106], "value": 4327.319218409371, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.80923018498356, 305.30847630117785, 310.88031108023404, 313.1888409020636, 317.55859355495863, 4144.98447880269, 5170.741462926858, 5321.840344789653, 5463.535599474045, 5472.6318420394955, 4621.544213572723, 4946.777395017224, 4975.228839529878, 5204.661696989693, 5356.625036247464, 3583.838727257273, 4152.985801886083, 4160.687948403866, 4187.497500299968, 4198.391233271173, 4059.6955228357992, 4125.938110928339, 4175.770332631704, 4187.853425130121, 4194.909323575877, 3885.805709714517, 4083.8570650027254, 4095.508538975327, 4097.549269580344, 4126.855560055399, 3703.759255648388, 4083.9183216335805, 4089.243489954353, 4109.445224894631, 4120.484939382577, 2928.282570770175, 3151.2121969507625, 3234.8867789627484, 3364.074879408158, 3437.724982001459, 3848.499695039655, 4607.44710634726, 4803.591694705954, 5106.795728170869, 5141.74291285438, 4080.062739456439, 4593.425821772278, 4599.3672246352735, 4626.560476754703, 4652.697574657636, 5193.688378697252, 5616.83751755577, 5617.297837770278, 5645.604807663465, 5654.575906807006, 3137.4195773781694, 3814.351560234739, 3829.9850539607028, 3832.0, 3853.364194907837, 5875.441833025856, 5893.351731309595, 5897.292324920994, 5922.437368449991, 5957.642541447527, 3314.4531152360073, 3887.378019516864, 3891.5135608048995, 3899.883003509899, 3905.6330284400233, 3805.219929914361, 3869.187470631199, 3932.1545867638597, 3942.231264903374, 3956.3867600521967, 3613.6205698401814, 3915.941260881089, 4377.92740778505, 4482.887927801766, 4560.840370587013, 5624.775008999676, 5643.661380317194, 5650.5479561634975, 5655.899472882127, 5698.458646428583, 1216.8014505711867, 1239.2502535965791, 1242.5526810348267, 1265.1966001588873, 1286.2796833773118, 829.7593697827683, 851.7649408357905, 861.4572819124037, 880.1330689271063, 883.412530667112, 4965.155923493015, 5146.614003949719, 5178.378594568676, 5186.429492755794, 5196.0, 5232.555232805178, 5529.723513824292, 5552.585694372983, 5571.721413929287, 5598.356189038232, 4095.610916962884, 5084.77118529667, 5096.770645320969, 5098.898022039539, 5120.820771272987, 2344.710409274926, 2539.504796564689, 2710.376613378934, 2894.9710502895123, 2911.8398488082935, 5009.924851127236, 5457.5633949283965, 5785.942140578624, 5803.332616749043, 5813.970930145322, 5061.696298222045, 5195.688258704414, 5484.040292948718, 5526.330250937951, 5700.572457065737, 4807.543283388072, 5580.972095139498, 5788.594798364074, 5851.717830046286, 5864.354920958691, 4401.56406837549, 5237.371515418175, 5312.893742125213, 5489.698066606375, 5545.944540554704, 4843.4430040545785, 5271.920921186109, 5432.864178395572, 5450.373207081237, 5533.612647114664, 4719.150552900511, 5011.674241174312, 5198.532132108128, 5478.616496845342, 5505.366882808368, 3290.7696461248443, 3516.4901089342416, 3633.7819730816236, 3678.135638125092, 3685.6314368564003, 4900.765494773433, 5361.892762144813, 5458.536024437808, 5479.561635069105, 5533.37563895649, 5008.649394542347, 5023.6985780853265, 5336.184627991005, 5342.805897370546, 5384.454012367268, 4744.762761861824, 5229.163333866715, 5306.920396193983, 5325.733713314241, 5334.013207556614, 4656.278276867091, 5019.598432125495, 5281.313429254326, 5524.204633494211, 5563.554915606661, 4323.135372925491, 4981.608827762767, 5453.809116680897, 5702.971485142548, 5716.055302485369, 4870.78081486341, 4947.925781113214, 5451.645643033349, 5564.248826408383, 5609.523190528687], "value": 3994.894418672167, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.5846163071342, 730.5069078372097, 746.3581320064743, 746.3730466408219, 791.1771757372333, 13686.794698079524, 14005.929970350135, 14144.646383840383, 14287.357068931897, 14603.853961460381, 12625.0, 13141.408636611352, 13271.469141234338, 13303.600891973232, 13318.001149913747, 763.965621547029, 825.9297959673435, 835.8955130608674, 839.0183485322187, 841.9747407577752, 833.5457175839182, 834.6202477872583, 836.3685417509772, 838.534613289624, 840.3781201910573, 862.1378621378622, 870.0429527519741, 873.7902903303225, 876.5792419638577, 883.4301875290433, 855.9144085591422, 862.017300277684, 865.1305438034766, 865.3769286114051, 869.4565896314804, 639.6194264412713, 645.3868824616586, 645.8869697802866, 646.189032763883, 647.7344288841552, 12322.322272275022, 12391.504339826397, 12393.194442361219, 12405.069619778462, 12452.75094498105, 11275.097992160607, 11295.378754168518, 11339.659810205707, 11351.148663850245, 11571.768564628745, 9938.90061099387, 9985.350952188084, 10091.192704583616, 10407.68776936693, 10430.426326552037, 3487.616362200156, 3517.103138699641, 3544.3620148373284, 3585.175409655768, 3610.205754733954, 89700.55149724275, 92584.53707731486, 93739.0, 94788.0, 94989.0, 8373.07896131425, 8492.660293588187, 8577.356698247657, 8678.0, 8764.78088047804, 17820.643587128186, 17976.910115449602, 18009.819901801075, 18184.90907545454, 18241.270349185885, 7199.964000179965, 7301.722198615221, 7426.18311985692, 7533.221686006724, 7614.124375696866, 4105.055837157471, 4132.359484279941, 4240.745555266694, 4241.957580424218, 4330.328799036154, 3280.3767284215924, 3393.6606339365867, 3420.9486857697157, 3425.4519276915576, 3428.879989200366, 576.8730879206568, 582.5136011430418, 605.1618508365857, 619.306376857922, 626.6866566716642, 639.3510586754448, 647.2362612117652, 659.6437923521245, 660.8050625065617, 716.8315645497805, 7445.0, 8935.687250946316, 8954.328425368123, 9199.0, 9228.5847136879, 554.464941331617, 618.7277597856929, 625.3090335179652, 664.1465716554204, 668.2415458454689, 3651.9634803651625, 4148.255655017773, 4227.217964676544, 4524.298733696282, 4561.840335588238, 78429.0, 110979.44510277397, 117776.0, 120269.39865300618, 123315.0, 69906.65046674733, 85740.0, 91275.54362228278, 96421.51789241009, 105683.47158264313, 69925.65037174782, 73884.63057684785, 79249.60375198087, 79342.6032869832, 83303.0, 8603.483790972561, 8900.73297801067, 9012.143846334588, 9043.32175086858, 9047.457152570867, 36517.634823651424, 45237.77381113205, 47794.52205477901, 48213.517864820904, 52317.7384113077, 43802.0, 59892.0, 71338.64330678314, 74934.32051588215, 78864.60567697124, 12006.0, 12070.75858482843, 12237.449314781034, 12272.877271227173, 12384.071194660257, 13429.865701343253, 13939.790903136258, 14036.508722194674, 14095.718085638026, 14890.883183761045, 12592.244465332109, 12918.031147663774, 13214.669633259246, 13740.381682824496, 13743.656408589466, 12440.377981100728, 12453.0, 12830.358482076046, 12915.741685166431, 12992.480300787862, 12731.936340318609, 12843.486260549473, 12901.0, 13441.596752097452, 13467.32663366808, 10695.053600151934, 11395.0, 11412.942935285271, 11413.0, 11575.073994080285, 9294.302927280616, 9441.622335106518, 9758.560864761244, 9848.310618256939, 9902.306838521234], "value": 7012.996002904233, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1567.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 119.0, 175.0, 174.0, 118.0, 118.0, 120.0, 120.0, 120.0, 118.0], "value": 126.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 134.0, 130.0, 152.0, 117.0, 120.0, 119.0, 115.0, 144.0, 117.0], "value": 129.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 227.0, 282.0, 237.0, 206.0, 198.0, 194.0, 200.0, 193.0, 198.0], "value": 213.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 61.0, 85.0, 62.0, 60.0, 59.0, 60.0, 80.0, 59.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 72.0, 90.0, 71.0, 70.0, 72.0, 88.0, 70.0, 74.0, 89.0], "value": 78.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 95.0, 90.0, 87.0, 88.0, 90.0, 137.0, 132.0], "value": 98.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 100.0, 98.0, 103.0, 97.0, 98.0, 99.0, 97.0, 98.0, 98.0], "value": 102.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 141.0, 133.0, 87.0, 89.0, 97.0, 89.0, 140.0, 144.0, 71.0], "value": 108.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 56.0, 60.0, 61.0, 60.0, 59.0, 59.0, 60.0], "value": 58.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 104.0, 108.0, 108.0, 75.0, 69.0, 73.0, 69.0, 71.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 85.0, 88.0, 75.0, 105.0, 109.0, 102.0, 110.0, 85.0], "value": 93.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 149.0, 165.0, 156.0, 162.0, 142.0, 152.0, 198.0, 152.0], "value": 157.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 205.0, 176.0, 169.0, 150.0, 222.0, 167.0, 172.0, 177.0, 188.0], "value": 179.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 67.0, 68.0, 70.0, 79.0, 91.0, 92.0, 72.0, 60.0], "value": 73.6, "unit": "score"}]}]} 19:57:19 INFO - Return code: 0 19:57:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:57:19 ERROR - # TBPL SUCCESS # 19:57:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:57:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:57:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:57:19 INFO - 2016-11-23 19:57:19.822 screenresolution[1661:10509] starting screenresolution argv=screenresolution get 19:57:19 INFO - 2016-11-23 19:57:19.825 screenresolution[1661:10509] Display 0: 1600x1200x32@60 19:57:19 INFO - 2016-11-23 19:57:19.831 screenresolution[1662:10512] starting screenresolution argv=screenresolution list 19:57:19 INFO - Available Modes on Display 0 19:57:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:57:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:57:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:57:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:57:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:57:20 INFO - Intel Iris: 19:57:20 INFO - Chipset Model: Intel Iris 19:57:20 INFO - Type: GPU 19:57:20 INFO - Bus: Built-In 19:57:20 INFO - VRAM (Dynamic, Max): 1536 MB 19:57:20 INFO - Vendor: Intel (0x8086) 19:57:20 INFO - Device ID: 0x0a2e 19:57:20 INFO - Revision ID: 0x0009 19:57:20 INFO - Displays: 19:57:20 INFO - Display: 19:57:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:57:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:57:20 INFO - Display Serial Number: 1600x1200 60 19:57:20 INFO - Main Display: Yes 19:57:20 INFO - Mirror: Off 19:57:20 INFO - Online: Yes 19:57:20 INFO - Rotation: Supported 19:57:20 INFO - Return code: 0 19:57:20 INFO - Running post-action listener: _resource_record_post_action 19:57:20 INFO - [mozharness: 2016-11-24 03:57:20.074651Z] Finished run-tests step (success) 19:57:20 INFO - Running post-run listener: _resource_record_post_run 19:57:20 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 39011328; Write bytes: 660868096; Read time: 397; Write time: 1937 19:57:20 INFO - TinderboxPrint: CPU usage
54.3% 19:57:20 INFO - TinderboxPrint: I/O read bytes / time
39,011,328 / 397 19:57:20 INFO - TinderboxPrint: I/O write bytes / time
660,868,096 / 1,937 19:57:20 INFO - TinderboxPrint: CPU idle
2,209.3 (45.7%) 19:57:20 INFO - TinderboxPrint: CPU system
973.3 (20.1%) 19:57:20 INFO - TinderboxPrint: CPU user
1,655.1 (34.2%) 19:57:20 INFO - TinderboxPrint: Swap in / out
386,347,008 / 0 19:57:20 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345709056; Write bytes: 334452736; Read time: 27015; Write time: 661 19:57:20 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 32533504; Write bytes: 309728256; Read time: 300; Write time: 1225 19:57:20 INFO - Running post-run listener: _upload_blobber_files 19:57:20 INFO - Blob upload gear active. 19:57:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:57:20 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 19:57:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:57:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:57:20 INFO - (blobuploader) - INFO - Open directory for files ... 19:57:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:57:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:57:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:57:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:57:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:57:21 INFO - (blobuploader) - INFO - Done attempting. 19:57:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:57:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:57:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:57:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:57:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:57:22 INFO - (blobuploader) - INFO - Done attempting. 19:57:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:57:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:57:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:57:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:57:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:57:25 INFO - (blobuploader) - INFO - Done attempting. 19:57:25 INFO - (blobuploader) - INFO - Iteration through files over. 19:57:25 INFO - Return code: 0 19:57:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:57:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:57:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7549d6cd79ea649d1498d0e14bf4097435bd0967c4b5148c4fe1b08f2be66957e7d1e35db7f2880f510b591e3f3ea66b92030a5be864a9c042cb5ee5f6ecd54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/999df3d85d8015da5371d2b10743cadaac4bdc8aa0668a63153464241ce3f32fa0034697b1d22390a9090753ef8a66c5b3b84d3eb49cd725fe0a117193fd6319", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eed5c48e18236a8cd5592e020661a1c3bd44fe919e80be8e9135a9bc0325d6ee742cbb298536d9ad07efb34157f0a7a3abfdc1c5aa4436f5c06c367325ae4f1e"} 19:57:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:57:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:57:25 INFO - Contents: 19:57:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7549d6cd79ea649d1498d0e14bf4097435bd0967c4b5148c4fe1b08f2be66957e7d1e35db7f2880f510b591e3f3ea66b92030a5be864a9c042cb5ee5f6ecd54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/999df3d85d8015da5371d2b10743cadaac4bdc8aa0668a63153464241ce3f32fa0034697b1d22390a9090753ef8a66c5b3b84d3eb49cd725fe0a117193fd6319", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eed5c48e18236a8cd5592e020661a1c3bd44fe919e80be8e9135a9bc0325d6ee742cbb298536d9ad07efb34157f0a7a3abfdc1c5aa4436f5c06c367325ae4f1e"} 19:57:25 INFO - Running post-run listener: copy_logs_to_upload_dir 19:57:25 INFO - Copying logs to upload dir... 19:57:25 INFO - mkdir: /builds/slave/test/build/upload/logs 19:57:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.692312 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-23 19:57:25.563529) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 19:57:25.567958) ========= 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.gnXzOh02UL/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.zrUDh3lTpS/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7549d6cd79ea649d1498d0e14bf4097435bd0967c4b5148c4fe1b08f2be66957e7d1e35db7f2880f510b591e3f3ea66b92030a5be864a9c042cb5ee5f6ecd54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/999df3d85d8015da5371d2b10743cadaac4bdc8aa0668a63153464241ce3f32fa0034697b1d22390a9090753ef8a66c5b3b84d3eb49cd725fe0a117193fd6319", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eed5c48e18236a8cd5592e020661a1c3bd44fe919e80be8e9135a9bc0325d6ee742cbb298536d9ad07efb34157f0a7a3abfdc1c5aa4436f5c06c367325ae4f1e"} build_url:https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010474 build_url: 'https://queue.taskcluster.net/v1/task/RY5WesRDQfqb3KAa9UMxPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7549d6cd79ea649d1498d0e14bf4097435bd0967c4b5148c4fe1b08f2be66957e7d1e35db7f2880f510b591e3f3ea66b92030a5be864a9c042cb5ee5f6ecd54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/999df3d85d8015da5371d2b10743cadaac4bdc8aa0668a63153464241ce3f32fa0034697b1d22390a9090753ef8a66c5b3b84d3eb49cd725fe0a117193fd6319", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eed5c48e18236a8cd5592e020661a1c3bd44fe919e80be8e9135a9bc0325d6ee742cbb298536d9ad07efb34157f0a7a3abfdc1c5aa4436f5c06c367325ae4f1e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 19:57:25.607703) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 19:57:25.608364) ========= 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.gnXzOh02UL/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.zrUDh3lTpS/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.005206 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 19:57:25.658302) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 19:57:25.658672) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 19:57:25.659215) ========= ========= Total master_lag: 1.28 =========