builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0245 starttime: 1481972243.18 results: success (0) buildid: 20161217004004 builduid: c71f79c3f630442590cffef7a1eaec91 revision: 4a3e7356c7d2b271422f3ec4a92af35dde6eaaf8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:23.179154) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:23.187175) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:23.187607) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:23.247684) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:23.248031) ========= 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.mzyp8fHBgk/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.Z2CXzebsE5/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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-12-17 02:57:23-- 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% 171M=0s 2016-12-17 02:57:23 (171 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131335 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:23.403299) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:23.403619) ========= 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.mzyp8fHBgk/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.Z2CXzebsE5/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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.024877 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:23.452378) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-17 02:57:23.452737) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4a3e7356c7d2b271422f3ec4a92af35dde6eaaf8 --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 4a3e7356c7d2b271422f3ec4a92af35dde6eaaf8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mzyp8fHBgk/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.Z2CXzebsE5/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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-12-17 02:57:23,552 truncating revision to first 12 chars 2016-12-17 02:57:23,552 Setting DEBUG logging. 2016-12-17 02:57:23,552 attempt 1/10 2016-12-17 02:57:23,552 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/4a3e7356c7d2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-17 02:57:24,667 unpacking tar archive at: mozilla-aurora-4a3e7356c7d2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.399081 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-17 02:57:24.874956) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:24.875291) ========= script_repo_revision: 4a3e7356c7d2b271422f3ec4a92af35dde6eaaf8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:24.875699) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:24.875972) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-17 02:57:24.896477) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-17 02:57:24.896874) ========= /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.mzyp8fHBgk/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.Z2CXzebsE5/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:57:25 INFO - MultiFileLogger online at 20161217 02:57:25 in /builds/slave/test 02:57:25 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 02:57:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:57:25 INFO - {'append_to_log': False, 02:57:25 INFO - 'base_work_dir': '/builds/slave/test', 02:57:25 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:57:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:57:25 INFO - 'branch': 'Mozilla-Aurora', 02:57:25 INFO - 'buildbot_json_path': 'buildprops.json', 02:57:25 INFO - 'config_files': ('talos/mac_config.py',), 02:57:25 INFO - 'default_actions': ('clobber', 02:57:25 INFO - 'read-buildbot-config', 02:57:25 INFO - 'download-and-extract', 02:57:25 INFO - 'populate-webroot', 02:57:25 INFO - 'create-virtualenv', 02:57:25 INFO - 'install', 02:57:25 INFO - 'run-tests'), 02:57:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:57:25 INFO - 'download_minidump_stackwalk': True, 02:57:25 INFO - 'download_symbols': 'ondemand', 02:57:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:57:25 INFO - 'tooltool.py': '/tools/tooltool.py', 02:57:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:57:25 INFO - '/tools/misc-python/virtualenv.py')}, 02:57:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:57:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:57:25 INFO - 'installer_path': 'installer.exe', 02:57:25 INFO - 'log_level': 'info', 02:57:25 INFO - 'log_name': 'talos', 02:57:25 INFO - 'log_to_console': True, 02:57:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:57:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:57:25 INFO - 'opt_config_files': (), 02:57:25 INFO - 'pip_index': False, 02:57:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:57:25 INFO - 'cmd': ('bash', 02:57:25 INFO - '-c', 02:57:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:57:25 INFO - 'enabled': True, 02:57:25 INFO - 'halt_on_failure': False, 02:57:25 INFO - 'name': 'check_screen_resolution'},), 02:57:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:57:25 INFO - 'cmd': ('bash', 02:57:25 INFO - '-c', 02:57:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:57:25 INFO - 'enabled': True, 02:57:25 INFO - 'halt_on_failure': False, 02:57:25 INFO - 'name': 'check_screen_resolution'},), 02:57:25 INFO - 'run_cmd_checks_enabled': True, 02:57:25 INFO - 'sps_profile': False, 02:57:25 INFO - 'sps_profile_interval': 0, 02:57:25 INFO - 'suite': 'dromaeojs-e10s', 02:57:25 INFO - 'system_bits': '32', 02:57:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:57:25 INFO - 'title': 't-yosemite-r7-0245', 02:57:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:57:25 INFO - 'use_talos_json': True, 02:57:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:57:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:57:25 INFO - 'work_dir': 'build'} 02:57:25 INFO - [mozharness: 2016-12-17 10:57:25.101889Z] Running clobber step. 02:57:25 INFO - Running pre-action listener: _resource_record_pre_action 02:57:25 INFO - Running main action method: clobber 02:57:25 INFO - rmtree: /builds/slave/test/build 02:57:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:57:27 INFO - Running post-action listener: _resource_record_post_action 02:57:27 INFO - [mozharness: 2016-12-17 10:57:27.478984Z] Finished clobber step (success) 02:57:27 INFO - [mozharness: 2016-12-17 10:57:27.479111Z] Running read-buildbot-config step. 02:57:27 INFO - Running pre-action listener: _resource_record_pre_action 02:57:27 INFO - Running main action method: read_buildbot_config 02:57:27 INFO - Using buildbot properties: 02:57:27 INFO - { 02:57:27 INFO - "project": "", 02:57:27 INFO - "product": "firefox", 02:57:27 INFO - "script_repo_revision": "production", 02:57:27 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:57:27 INFO - "repository": "", 02:57:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:57:27 INFO - "buildid": "20161217004004", 02:57:27 INFO - "stage_platform": "macosx64", 02:57:27 INFO - "basedir": "/builds/slave/test", 02:57:27 INFO - "nightly_build": "True", 02:57:27 INFO - "buildnumber": 48, 02:57:27 INFO - "slavename": "t-yosemite-r7-0245", 02:57:27 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 02:57:27 INFO - "platform": "yosemite_r7", 02:57:27 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:57:27 INFO - "branch": "mozilla-aurora", 02:57:27 INFO - "repo_path": "releases/mozilla-aurora", 02:57:27 INFO - "revision": "4a3e7356c7d2b271422f3ec4a92af35dde6eaaf8", 02:57:27 INFO - "pgo_build": "False", 02:57:27 INFO - "builduid": "c71f79c3f630442590cffef7a1eaec91", 02:57:27 INFO - "slavebuilddir": "test" 02:57:27 INFO - } 02:57:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:57:27 INFO - Running post-action listener: _resource_record_post_action 02:57:27 INFO - [mozharness: 2016-12-17 10:57:27.482495Z] Finished read-buildbot-config step (success) 02:57:27 INFO - [mozharness: 2016-12-17 10:57:27.482625Z] Running download-and-extract step. 02:57:27 INFO - Running pre-action listener: _resource_record_pre_action 02:57:27 INFO - Running main action method: download_and_extract 02:57:27 INFO - mkdir: /builds/slave/test/build/tests 02:57:27 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')]} 02:57:27 INFO - trying https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:57:27 INFO - Downloading https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/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 02:57:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/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 02:57:29 INFO - Downloaded 1199 bytes. 02:57:29 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:57:29 INFO - Using the following test package requirements: 02:57:29 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:57:29 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:57:29 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:57:29 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:57:29 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:57:29 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:57:29 INFO - u'jsshell-mac.zip'], 02:57:29 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:57:29 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:57:29 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:57:29 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:57:29 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:57:29 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:57:29 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:57:29 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:57:29 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:57:29 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:57:29 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:57:29 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:57:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:57:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:57:29 INFO - Fetch https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:57:29 INFO - Http code: 200 02:57:29 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 02:57:29 INFO - x-amz-cf-id: LRleAtxlKNXm_fejHMGJJm9ZIOvq2Vagg5payL0HVy8lwiD5dPPU2A== 02:57:29 INFO - x-amz-version-id: mxzthTWKBX_H_DtdBl6PPeK3enn8Ezsc 02:57:29 INFO - Expected file size: 16280458 02:57:29 INFO - Obtained file size: 16280458 02:57:36 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 02:57:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:57:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:57:36 INFO - Fetch https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:57:37 INFO - Http code: 200 02:57:37 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 02:57:37 INFO - x-amz-cf-id: id-T3yeShMmBLp6Kv7e9n1AC11mmmyaL-bSksrQ8tEoFUex_GE3aAw== 02:57:37 INFO - x-amz-version-id: mxzthTWKBX_H_DtdBl6PPeK3enn8Ezsc 02:57:37 INFO - Expected file size: 16280458 02:57:37 INFO - Obtained file size: 16280458 02:57:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:57:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:57:44 INFO - Fetch https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:57:44 INFO - Http code: 200 02:57:44 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 02:57:44 INFO - x-amz-cf-id: f16nX93q3iZb7OFjQNx6OqcVRQGvYIhlQiSLaPZoU7usZBV6crtarQ== 02:57:44 INFO - x-amz-version-id: MCCf875cY1BsheeyFQ2nMdMKAmGRwvZT 02:57:45 INFO - Expected file size: 14409038 02:57:45 INFO - Obtained file size: 14409038 02:57:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:45 INFO - trying https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:57:45 INFO - Downloading https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:57:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/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 02:57:48 INFO - Downloaded 109831895 bytes. 02:57:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:57:48 INFO - mkdir: /builds/slave/test/properties 02:57:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:57:48 INFO - Writing to file /builds/slave/test/properties/build_url 02:57:48 INFO - Contents: 02:57:48 INFO - build_url:https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:57:49 INFO - Running post-action listener: _resource_record_post_action 02:57:49 INFO - Running post-action listener: set_extra_try_arguments 02:57:49 INFO - [mozharness: 2016-12-17 10:57:49.553715Z] Finished download-and-extract step (success) 02:57:49 INFO - [mozharness: 2016-12-17 10:57:49.553824Z] Running populate-webroot step. 02:57:49 INFO - Running pre-action listener: _resource_record_pre_action 02:57:49 INFO - Running main action method: populate_webroot 02:57:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:57:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:57:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:57:49 INFO - '--tppagecycles', 02:57:49 INFO - '7'], 02:57:49 INFO - 'tests': ['tsvgm']}}, 02:57:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:57:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:57:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:57:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:57:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:49 INFO - 'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:57:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:57:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:49 INFO - 'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['damp', 'tps']}, 02:57:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:49 INFO - 'tests': ['damp', 'tps']}, 02:57:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['dromaeo_dom']}, 02:57:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:57:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['basic_compositor_video']}, 02:57:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:57:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['a11yr', 02:57:49 INFO - 'ts_paint', 02:57:49 INFO - 'tpaint', 02:57:49 INFO - 'sessionrestore', 02:57:49 INFO - 'sessionrestore_no_auto_restore', 02:57:49 INFO - 'tabpaint']}, 02:57:49 INFO - 'other-e10s': {'tests': ['a11yr', 02:57:49 INFO - 'ts_paint', 02:57:49 INFO - 'tpaint', 02:57:49 INFO - 'sessionrestore', 02:57:49 INFO - 'sessionrestore_no_auto_restore', 02:57:49 INFO - 'tabpaint']}, 02:57:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:57:49 INFO - 'ts_paint', 02:57:49 INFO - 'tpaint', 02:57:49 INFO - 'sessionrestore', 02:57:49 INFO - 'sessionrestore_no_auto_restore', 02:57:49 INFO - 'tabpaint']}, 02:57:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:57:49 INFO - 'ts_paint', 02:57:49 INFO - 'tpaint', 02:57:49 INFO - 'sessionrestore', 02:57:49 INFO - 'sessionrestore_no_auto_restore', 02:57:49 INFO - 'tabpaint']}, 02:57:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['a11yr', 02:57:49 INFO - 'ts_paint', 02:57:49 INFO - 'tpaint', 02:57:49 INFO - 'sessionrestore', 02:57:49 INFO - 'sessionrestore_no_auto_restore', 02:57:49 INFO - 'tabpaint']}, 02:57:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['a11yr', 02:57:49 INFO - 'ts_paint', 02:57:49 INFO - 'tpaint', 02:57:49 INFO - 'sessionrestore', 02:57:49 INFO - 'sessionrestore_no_auto_restore', 02:57:49 INFO - 'tabpaint']}, 02:57:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['tsvgx', 02:57:49 INFO - 'tsvgr_opacity', 02:57:49 INFO - 'tart', 02:57:49 INFO - 'tscrollx', 02:57:49 INFO - 'cart']}, 02:57:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:57:49 INFO - 'tsvgr_opacity', 02:57:49 INFO - 'tart', 02:57:49 INFO - 'tscrollx', 02:57:49 INFO - 'cart']}, 02:57:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:49 INFO - 'talos_options': ['--disable-e10s'], 02:57:49 INFO - 'tests': ['tp5o']}, 02:57:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:49 INFO - 'tests': ['tp5o']}, 02:57:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:49 INFO - 'talos_options': ['--disable-e10s', 02:57:49 INFO - '--xperf_path', 02:57:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:57:49 INFO - 'tests': ['tp5n']}, 02:57:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:49 INFO - 'talos_options': ['--xperf_path', 02:57:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:57:49 INFO - 'tests': ['tp5n']}}, 02:57:49 INFO - 'talos.zip': {'path': '', 02:57:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:57:49 INFO - Running post-action listener: _resource_record_post_action 02:57:49 INFO - [mozharness: 2016-12-17 10:57:49.572281Z] Finished populate-webroot step (success) 02:57:49 INFO - [mozharness: 2016-12-17 10:57:49.572393Z] Running create-virtualenv step. 02:57:49 INFO - Running pre-action listener: _resource_record_pre_action 02:57:49 INFO - Running main action method: create_virtualenv 02:57:49 INFO - Creating virtualenv /builds/slave/test/build/venv 02:57:49 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 02:57:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:57:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:57:49 INFO - Using real prefix '/tools/python27' 02:57:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:57:50 INFO - Installing distribute.............................................................................................................................................................................................done. 02:57:53 INFO - Installing pip.................done. 02:57:53 INFO - Return code: 0 02:57:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:57:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:57:53 INFO - Reading from file tmpfile_stdout 02:57:53 INFO - Output received: 02:57:53 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) 02:57:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:57:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dccb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcffbc7f1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadfd30>, '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 0x10d945db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dad7b20>, '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.mzyp8fHBgk/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.Z2CXzebsE5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:57:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:57:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:57:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mzyp8fHBgk/Render', 02:57:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:53 INFO - 'HOME': '/Users/cltbld', 02:57:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:53 INFO - 'LOGNAME': 'cltbld', 02:57:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:53 INFO - 'MOZ_NO_REMOTE': '1', 02:57:53 INFO - 'NO_EM_RESTART': '1', 02:57:53 INFO - 'PAGER': '/bin/cat', 02:57:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:53 INFO - 'PWD': '/builds/slave/test', 02:57:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:53 INFO - 'SHELL': '/bin/bash', 02:57:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2CXzebsE5/Listeners', 02:57:53 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:57:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:53 INFO - 'USER': 'cltbld', 02:57:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:53 INFO - 'XPC_FLAGS': '0x0', 02:57:53 INFO - 'XPC_SERVICE_NAME': '0', 02:57:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:53 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 02:57:53 INFO - Cleaning up... 02:57:53 INFO - Return code: 0 02:57:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:57:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10dccb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcffbc7f1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadfd30>, '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 0x10d945db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dad7b20>, '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.mzyp8fHBgk/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.Z2CXzebsE5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:57:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:57:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:57:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mzyp8fHBgk/Render', 02:57:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:53 INFO - 'HOME': '/Users/cltbld', 02:57:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:53 INFO - 'LOGNAME': 'cltbld', 02:57:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:53 INFO - 'MOZ_NO_REMOTE': '1', 02:57:53 INFO - 'NO_EM_RESTART': '1', 02:57:53 INFO - 'PAGER': '/bin/cat', 02:57:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:53 INFO - 'PWD': '/builds/slave/test', 02:57:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:53 INFO - 'SHELL': '/bin/bash', 02:57:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2CXzebsE5/Listeners', 02:57:53 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:57:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:53 INFO - 'USER': 'cltbld', 02:57:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:53 INFO - 'XPC_FLAGS': '0x0', 02:57:53 INFO - 'XPC_SERVICE_NAME': '0', 02:57:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:54 INFO - Downloading/unpacking psutil>=3.1.1 02:57: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 02:57: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 02:57: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 02:57: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 02:57: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 02:57: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 02:57:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:57:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:57:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:57:58 INFO - Installing collected packages: psutil 02:57:58 INFO - Running setup.py install for psutil 02:57:58 INFO - building 'psutil._psutil_osx' extension 02:57:58 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 02:57:58 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 02:57:58 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 02:57:58 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 02:57:58 INFO - building 'psutil._psutil_posix' extension 02:57:58 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 02:57:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:57:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:57:59 INFO - ^ 02:57:59 INFO - 1 warning generated. 02:57:59 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 02:57:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:57:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:57:59 INFO - Successfully installed psutil 02:57:59 INFO - Cleaning up... 02:57:59 INFO - Return code: 0 02:57:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:57: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')]} 02:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57: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')]} 02:57:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:59 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 0x10dccb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcffbc7f1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadfd30>, '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 0x10d945db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dad7b20>, '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.mzyp8fHBgk/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.Z2CXzebsE5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:57:59 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 02:57:59 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 02:57:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mzyp8fHBgk/Render', 02:57:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:59 INFO - 'HOME': '/Users/cltbld', 02:57:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:59 INFO - 'LOGNAME': 'cltbld', 02:57:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:59 INFO - 'MOZ_NO_REMOTE': '1', 02:57:59 INFO - 'NO_EM_RESTART': '1', 02:57:59 INFO - 'PAGER': '/bin/cat', 02:57:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:59 INFO - 'PWD': '/builds/slave/test', 02:57:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:59 INFO - 'SHELL': '/bin/bash', 02:57:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2CXzebsE5/Listeners', 02:57:59 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:57:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:59 INFO - 'USER': 'cltbld', 02:57:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:59 INFO - 'XPC_FLAGS': '0x0', 02:57:59 INFO - 'XPC_SERVICE_NAME': '0', 02:57:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:58:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:58:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:58:02 INFO - Installing collected packages: mozsystemmonitor 02:58:02 INFO - Running setup.py install for mozsystemmonitor 02:58:02 INFO - Successfully installed mozsystemmonitor 02:58:02 INFO - Cleaning up... 02:58:02 INFO - Return code: 0 02:58:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:02 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 0x10dccb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcffbc7f1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadfd30>, '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 0x10d945db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dad7b20>, '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.mzyp8fHBgk/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.Z2CXzebsE5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:58:02 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 02:58:02 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 02:58:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mzyp8fHBgk/Render', 02:58:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:02 INFO - 'HOME': '/Users/cltbld', 02:58:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:02 INFO - 'LOGNAME': 'cltbld', 02:58:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:02 INFO - 'MOZ_NO_REMOTE': '1', 02:58:02 INFO - 'NO_EM_RESTART': '1', 02:58:02 INFO - 'PAGER': '/bin/cat', 02:58:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:02 INFO - 'PWD': '/builds/slave/test', 02:58:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:02 INFO - 'SHELL': '/bin/bash', 02:58:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2CXzebsE5/Listeners', 02:58:02 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:58:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:02 INFO - 'USER': 'cltbld', 02:58:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:02 INFO - 'XPC_FLAGS': '0x0', 02:58:02 INFO - 'XPC_SERVICE_NAME': '0', 02:58:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:02 INFO - Downloading/unpacking jsonschema==2.5.1 02:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:58:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:58:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:58:05 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 02:58:05 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 02:58:05 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 02:58:05 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 02:58:05 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 02:58:05 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 02:58:05 INFO - Downloading functools32-3.2.3-2.tar.gz 02:58:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:58:05 INFO - warning: no files found matching '*.txt' 02:58:05 INFO - no previously-included directories found matching 'build' 02:58:05 INFO - no previously-included directories found matching 'dist' 02:58:05 INFO - no previously-included directories found matching '.git*' 02:58:05 INFO - Installing collected packages: jsonschema, functools32 02:58:05 INFO - Running setup.py install for functools32 02:58:05 INFO - warning: no files found matching '*.txt' 02:58:05 INFO - no previously-included directories found matching 'build' 02:58:05 INFO - no previously-included directories found matching 'dist' 02:58:05 INFO - no previously-included directories found matching '.git*' 02:58:05 INFO - Successfully installed jsonschema functools32 02:58:05 INFO - Cleaning up... 02:58:06 INFO - Return code: 0 02:58:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:58:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10dccb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcffbc7f1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadfd30>, '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 0x10d945db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dad7b20>, '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.mzyp8fHBgk/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.Z2CXzebsE5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:58:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:58:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:58:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mzyp8fHBgk/Render', 02:58:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:06 INFO - 'HOME': '/Users/cltbld', 02:58:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:06 INFO - 'LOGNAME': 'cltbld', 02:58:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:06 INFO - 'MOZ_NO_REMOTE': '1', 02:58:06 INFO - 'NO_EM_RESTART': '1', 02:58:06 INFO - 'PAGER': '/bin/cat', 02:58:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:06 INFO - 'PWD': '/builds/slave/test', 02:58:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:06 INFO - 'SHELL': '/bin/bash', 02:58:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2CXzebsE5/Listeners', 02:58:06 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:58:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:06 INFO - 'USER': 'cltbld', 02:58:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:06 INFO - 'XPC_FLAGS': '0x0', 02:58:06 INFO - 'XPC_SERVICE_NAME': '0', 02:58:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:58:06 INFO - Cleaning up... 02:58:06 INFO - Return code: 0 02:58:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:58:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10dccb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcffbc7f1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadfd30>, '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 0x10d945db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dad7b20>, '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.mzyp8fHBgk/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.Z2CXzebsE5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:58:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:58:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:58:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mzyp8fHBgk/Render', 02:58:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:06 INFO - 'HOME': '/Users/cltbld', 02:58:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:06 INFO - 'LOGNAME': 'cltbld', 02:58:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:06 INFO - 'MOZ_NO_REMOTE': '1', 02:58:06 INFO - 'NO_EM_RESTART': '1', 02:58:06 INFO - 'PAGER': '/bin/cat', 02:58:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:06 INFO - 'PWD': '/builds/slave/test', 02:58:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:06 INFO - 'SHELL': '/bin/bash', 02:58:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2CXzebsE5/Listeners', 02:58:06 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:58:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:06 INFO - 'USER': 'cltbld', 02:58:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:06 INFO - 'XPC_FLAGS': '0x0', 02:58:06 INFO - 'XPC_SERVICE_NAME': '0', 02:58:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:06 INFO - Downloading/unpacking blobuploader==1.2.4 02:58: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 02:58: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 02:58: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 02:58: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 02:58: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 02:58: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 02:58:09 INFO - Downloading blobuploader-1.2.4.tar.gz 02:58:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:58:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:58:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:58:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:58:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:58:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:58:10 INFO - Downloading docopt-0.6.1.tar.gz 02:58:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:58:10 INFO - Installing collected packages: blobuploader, requests, docopt 02:58:10 INFO - Running setup.py install for blobuploader 02:58:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:58:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:58:10 INFO - Running setup.py install for requests 02:58:11 INFO - Running setup.py install for docopt 02:58:11 INFO - Successfully installed blobuploader requests docopt 02:58:11 INFO - Cleaning up... 02:58:11 INFO - Return code: 0 02:58:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:58:11 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')]} 02:58:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:11 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')]} 02:58:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:11 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 0x10dccb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcffbc7f1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadfd30>, '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 0x10d945db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dad7b20>, '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.mzyp8fHBgk/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.Z2CXzebsE5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:58:11 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 02:58:11 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 02:58:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mzyp8fHBgk/Render', 02:58:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:11 INFO - 'HOME': '/Users/cltbld', 02:58:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:11 INFO - 'LOGNAME': 'cltbld', 02:58:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:11 INFO - 'MOZ_NO_REMOTE': '1', 02:58:11 INFO - 'NO_EM_RESTART': '1', 02:58:11 INFO - 'PAGER': '/bin/cat', 02:58:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:11 INFO - 'PWD': '/builds/slave/test', 02:58:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:11 INFO - 'SHELL': '/bin/bash', 02:58:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2CXzebsE5/Listeners', 02:58:11 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:58:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:11 INFO - 'USER': 'cltbld', 02:58:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:11 INFO - 'XPC_FLAGS': '0x0', 02:58:11 INFO - 'XPC_SERVICE_NAME': '0', 02:58:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:58:11 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-Ovn1n3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:58:11 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-I9naCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:58:11 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-Mpj9Ge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:58:12 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-uuebjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:58:12 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-CzRQO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:58:12 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-_cqypR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:58:12 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-8QrIxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:58:12 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-N2fJjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:58:12 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-nAMelF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:58:12 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-IG59nj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:58:12 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-VkMngi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:58:12 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-8aaBz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:58:12 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-OIH0Iy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:58:13 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-0tN5ZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:58:13 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-uV5iRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:58:13 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-_soRPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:58:13 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-zhWiM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:58:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:58:13 INFO - Running setup.py install for manifestparser 02:58:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:58:13 INFO - Running setup.py install for mozcrash 02:58:13 INFO - Running setup.py install for mozdebug 02:58:13 INFO - Running setup.py install for mozdevice 02:58:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:58:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:58:14 INFO - Running setup.py install for mozfile 02:58:14 INFO - Running setup.py install for mozhttpd 02:58:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:58:14 INFO - Running setup.py install for mozinfo 02:58:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:58:14 INFO - Running setup.py install for mozInstall 02:58:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:58:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:58:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:58:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:58:14 INFO - Running setup.py install for mozleak 02:58:14 INFO - Running setup.py install for mozlog 02:58:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:58:14 INFO - Running setup.py install for moznetwork 02:58:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:58:15 INFO - Running setup.py install for mozprocess 02:58:15 INFO - Running setup.py install for mozprofile 02:58:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:58:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:58:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:58:15 INFO - Running setup.py install for mozrunner 02:58:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:58:15 INFO - Running setup.py install for mozscreenshot 02:58:15 INFO - Running setup.py install for moztest 02:58:15 INFO - Running setup.py install for mozversion 02:58:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:58:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:58:15 INFO - Cleaning up... 02:58:16 INFO - Return code: 0 02:58:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:58:16 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')]} 02:58:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:16 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')]} 02:58:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:16 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 0x10dccb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcffbc7f1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadfd30>, '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 0x10d945db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dad7b20>, '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.mzyp8fHBgk/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.Z2CXzebsE5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:58:16 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 02:58:16 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 02:58:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mzyp8fHBgk/Render', 02:58:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:16 INFO - 'HOME': '/Users/cltbld', 02:58:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:16 INFO - 'LOGNAME': 'cltbld', 02:58:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:16 INFO - 'MOZ_NO_REMOTE': '1', 02:58:16 INFO - 'NO_EM_RESTART': '1', 02:58:16 INFO - 'PAGER': '/bin/cat', 02:58:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:16 INFO - 'PWD': '/builds/slave/test', 02:58:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:16 INFO - 'SHELL': '/bin/bash', 02:58:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2CXzebsE5/Listeners', 02:58:16 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:58:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:16 INFO - 'USER': 'cltbld', 02:58:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:16 INFO - 'XPC_FLAGS': '0x0', 02:58:16 INFO - 'XPC_SERVICE_NAME': '0', 02:58:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:58:16 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-WOouoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:58:16 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)) 02:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:58:16 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-kYIrhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:58:16 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)) 02:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:58:16 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-IlJPfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:58:16 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)) 02:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:58:16 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-9pC3jV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:58:16 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)) 02:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:58:16 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-rmVare-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:58:16 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)) 02:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:58:16 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-YtTh54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:58:16 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)) 02:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:58:16 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-ItYhLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:58:17 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)) 02:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:58:17 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-JA34Ad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:58:17 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)) 02:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:58:17 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-XlHkP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:58:17 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)) 02:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:58:17 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-mi4xxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:58:17 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)) 02:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:58:17 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-m3Qf6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:58:17 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)) 02:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:58:17 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-aX0UEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:58:17 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)) 02:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:58:17 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-DDrobe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:58:17 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)) 02:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:58:17 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-sKi4EI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:58:17 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)) 02:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:58:17 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-hN_4Vx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:58:17 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)) 02:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:58:17 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-Vfx5yh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:58:17 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)) 02:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:58:17 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-jcr6ie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:58:18 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)) 02:58:18 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)) 02:58:18 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)) 02:58:18 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)) 02:58:18 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)) 02:58:18 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)) 02:58:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:58:18 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 02:58:18 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 02:58:18 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 02:58:18 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 02:58:18 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 02:58:18 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 02:58:20 INFO - Downloading blessings-1.6.tar.gz 02:58:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:58:20 INFO - Installing collected packages: blessings 02:58:20 INFO - Running setup.py install for blessings 02:58:20 INFO - Successfully installed blessings 02:58:20 INFO - Cleaning up... 02:58:21 INFO - Return code: 0 02:58:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:58:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:58:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:58:21 INFO - Reading from file tmpfile_stdout 02:58:21 INFO - Current package versions: 02:58:21 INFO - blessings == 1.6 02:58:21 INFO - blobuploader == 1.2.4 02:58:21 INFO - docopt == 0.6.1 02:58:21 INFO - functools32 == 3.2.3-2 02:58:21 INFO - jsonschema == 2.5.1 02:58:21 INFO - manifestparser == 1.1 02:58:21 INFO - mozInstall == 1.12 02:58:21 INFO - mozcrash == 1.0 02:58:21 INFO - mozdebug == 0.1 02:58:21 INFO - mozdevice == 0.48 02:58:21 INFO - mozfile == 1.2 02:58:21 INFO - mozhttpd == 0.7 02:58:21 INFO - mozinfo == 0.9 02:58:21 INFO - mozleak == 0.1 02:58:21 INFO - mozlog == 3.4 02:58:21 INFO - moznetwork == 0.27 02:58:21 INFO - mozprocess == 0.23 02:58:21 INFO - mozprofile == 0.28 02:58:21 INFO - mozrunner == 6.13 02:58:21 INFO - mozscreenshot == 0.1 02:58:21 INFO - mozsystemmonitor == 0.3 02:58:21 INFO - moztest == 0.7 02:58:21 INFO - mozversion == 1.4 02:58:21 INFO - psutil == 3.1.1 02:58:21 INFO - requests == 1.2.3 02:58:21 INFO - wsgiref == 0.1.2 02:58:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:58:21 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')]} 02:58:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:21 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')]} 02:58:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:21 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 0x10dccb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcffbc7f1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadfd30>, '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 0x10d945db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dad7b20>, '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.mzyp8fHBgk/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.Z2CXzebsE5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:58:21 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 02:58:21 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 02:58:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mzyp8fHBgk/Render', 02:58:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:21 INFO - 'HOME': '/Users/cltbld', 02:58:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:21 INFO - 'LOGNAME': 'cltbld', 02:58:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:21 INFO - 'MOZ_NO_REMOTE': '1', 02:58:21 INFO - 'NO_EM_RESTART': '1', 02:58:21 INFO - 'PAGER': '/bin/cat', 02:58:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:21 INFO - 'PWD': '/builds/slave/test', 02:58:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:21 INFO - 'SHELL': '/bin/bash', 02:58:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2CXzebsE5/Listeners', 02:58:21 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:58:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:21 INFO - 'USER': 'cltbld', 02:58:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:21 INFO - 'XPC_FLAGS': '0x0', 02:58:21 INFO - 'XPC_SERVICE_NAME': '0', 02:58:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:21 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)) 02:58:21 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)) 02:58:21 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)) 02:58:21 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)) 02:58:21 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)) 02:58:21 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)) 02:58:21 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)) 02:58:21 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)) 02:58:21 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)) 02:58:21 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)) 02:58:21 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)) 02:58:21 INFO - Cleaning up... 02:58:21 INFO - Return code: 0 02:58:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:58:21 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')]} 02:58:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:21 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')]} 02:58:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:58:21 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 0x10dccb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcffbc7f1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadfd30>, '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 0x10d945db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dad7b20>, '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.mzyp8fHBgk/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.Z2CXzebsE5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:58:21 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 02:58:21 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 02:58:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mzyp8fHBgk/Render', 02:58:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:21 INFO - 'HOME': '/Users/cltbld', 02:58:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:21 INFO - 'LOGNAME': 'cltbld', 02:58:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:21 INFO - 'MOZ_NO_REMOTE': '1', 02:58:21 INFO - 'NO_EM_RESTART': '1', 02:58:21 INFO - 'PAGER': '/bin/cat', 02:58:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:21 INFO - 'PWD': '/builds/slave/test', 02:58:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:21 INFO - 'SHELL': '/bin/bash', 02:58:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2CXzebsE5/Listeners', 02:58:21 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:58:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:21 INFO - 'USER': 'cltbld', 02:58:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:21 INFO - 'XPC_FLAGS': '0x0', 02:58:21 INFO - 'XPC_SERVICE_NAME': '0', 02:58:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:58:21 INFO - Cleaning up... 02:58:21 INFO - Return code: 0 02:58:21 INFO - Running post-action listener: _resource_record_post_action 02:58:21 INFO - Running post-action listener: _start_resource_monitoring 02:58:21 INFO - Starting resource monitoring. 02:58:21 INFO - [mozharness: 2016-12-17 10:58:21.870221Z] Finished create-virtualenv step (success) 02:58:21 INFO - [mozharness: 2016-12-17 10:58:21.871218Z] Running install step. 02:58:21 INFO - Running pre-action listener: _resource_record_pre_action 02:58:21 INFO - Running main action method: install 02:58:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:58:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:58:22 INFO - Reading from file tmpfile_stdout 02:58:22 INFO - Detecting whether we're running mozinstall >=1.0... 02:58:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:58:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:58:22 INFO - Reading from file tmpfile_stdout 02:58:22 INFO - Output received: 02:58:22 INFO - Usage: mozinstall [options] installer 02:58:22 INFO - Options: 02:58:22 INFO - -h, --help show this help message and exit 02:58:22 INFO - -d DEST, --destination=DEST 02:58:22 INFO - Directory to install application into. [default: 02:58:22 INFO - "/builds/slave/test"] 02:58:22 INFO - --app=APP Application being installed. [default: firefox] 02:58:22 INFO - mkdir: /builds/slave/test/build/application 02:58:22 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'] 02:58:22 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 02:58:53 INFO - Reading from file tmpfile_stdout 02:58:53 INFO - Output received: 02:58:53 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:58:53 INFO - Running post-action listener: _resource_record_post_action 02:58:53 INFO - [mozharness: 2016-12-17 10:58:53.111652Z] Finished install step (success) 02:58:53 INFO - [mozharness: 2016-12-17 10:58:53.111798Z] Running run-tests step. 02:58:53 INFO - Running pre-action listener: _resource_record_pre_action 02:58:53 INFO - Running main action method: run_tests 02:58:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:58:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:58:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:58:53 INFO - 2016-12-17 02:58:53.126 screenresolution[1647:8812] starting screenresolution argv=screenresolution get 02:58:53 INFO - 2016-12-17 02:58:53.128 screenresolution[1647:8812] Display 0: 1600x1200x32@60 02:58:53 INFO - 2016-12-17 02:58:53.134 screenresolution[1648:8814] starting screenresolution argv=screenresolution list 02:58:53 INFO - Available Modes on Display 0 02:58:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:58:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:58:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:58:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:58:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:58:53 INFO - Intel Iris: 02:58:53 INFO - Chipset Model: Intel Iris 02:58:53 INFO - Type: GPU 02:58:53 INFO - Bus: Built-In 02:58:53 INFO - VRAM (Dynamic, Max): 1536 MB 02:58:53 INFO - Vendor: Intel (0x8086) 02:58:53 INFO - Device ID: 0x0a2e 02:58:53 INFO - Revision ID: 0x0009 02:58:53 INFO - Displays: 02:58:53 INFO - Display: 02:58:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:58:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:58:53 INFO - Display Serial Number: 1600x1200 60 02:58:53 INFO - Main Display: Yes 02:58:53 INFO - Mirror: Off 02:58:53 INFO - Online: Yes 02:58:53 INFO - Rotation: Supported 02:58:53 INFO - Return code: 0 02:58:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:58:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:58:53 INFO - Python 2.7.3 02:58:53 INFO - Return code: 0 02:58:53 INFO - grabbing minidump binary from tooltool 02:58:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:53 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 0x10dadfd30>, '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 0x10d945db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dad7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:58:53 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 02:58:53 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 02:58:53 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 02:58:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:58:53 INFO - Return code: 0 02:58:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:58:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:58:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:58:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:58:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:58:53 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/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0245', '--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 02:58:53 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/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0245 --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 02:58:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mzyp8fHBgk/Render', 02:58:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:53 INFO - 'HOME': '/Users/cltbld', 02:58:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:53 INFO - 'LOGNAME': 'cltbld', 02:58:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:58:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:58:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:53 INFO - 'MOZ_NO_REMOTE': '1', 02:58:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:58:53 INFO - 'NO_EM_RESTART': '1', 02:58:53 INFO - 'PAGER': '/bin/cat', 02:58:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:53 INFO - 'PWD': '/builds/slave/test', 02:58:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:58:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:53 INFO - 'SHELL': '/bin/bash', 02:58:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2CXzebsE5/Listeners', 02:58:53 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:58:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:53 INFO - 'USER': 'cltbld', 02:58:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:53 INFO - 'XPC_FLAGS': '0x0', 02:58:53 INFO - 'XPC_SERVICE_NAME': '0', 02:58:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:53 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/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0245', '--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 02:58:53 INFO - mozversion application_buildid: 20161217004004 02:58:53 INFO - mozversion application_changeset: 4a3e7356c7d2b271422f3ec4a92af35dde6eaaf8 02:58:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:58:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:58:53 INFO - mozversion application_name: Firefox 02:58:53 INFO - mozversion application_remotingname: firefox-dev 02:58:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:58:53 INFO - mozversion application_vendor: Mozilla 02:58:53 INFO - mozversion application_version: 52.0a2 02:58:53 INFO - mozversion platform_buildid: 20161217004004 02:58:53 INFO - mozversion platform_changeset: 4a3e7356c7d2b271422f3ec4a92af35dde6eaaf8 02:58:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:58:53 INFO - mozversion platform_version: 52.0a2 02:58:53 INFO - using testdate: 1481972333 02:58:53 INFO - actual date: 1481972333 02:58:53 INFO - starting webserver on 'localhost:49243' 02:58:53 INFO - SUITE-START | Running 2 tests 02:58:53 INFO - TEST-START | dromaeo_css 02:58:53 INFO - operating with platform_type : mac_ 02:58:53 INFO - Initialising browser for dromaeo_css test... 02:58:53 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpGaKpC6/profile http://localhost:49243/getInfo.html) 02:58:56 INFO - PROCESS | 1658 | 2016-12-17 02:58:56.941 plugin-container[1661:8998] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e4f, name = 'com.apple.tsm.portname' 02:58:56 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:58:56 INFO - PROCESS | 1658 | 2016-12-17 02:58:56.941 plugin-container[1661:8998] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 02:58:56 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:58:56 INFO - PROCESS | 1658 | 2016-12-17 02:58:56.941 plugin-container[1661:8998] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:58:57 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 02:58:57 INFO - PROCESS | 1658 | colorDepth:24 02:58:57 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 02:58:57 INFO - PROCESS | 1658 | __metrics 02:58:57 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 02:58:58 INFO - TEST-INFO | 1658: exit 0 02:58:58 INFO - Browser initialized. 02:58:58 INFO - Running cycle 1/1 for dromaeo_css test... 02:58:58 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpGaKpC6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:59:00 INFO - PROCESS | 1663 | 2016-12-17 02:59:00.830 plugin-container[1664:9137] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 02:59:00 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:59:00 INFO - PROCESS | 1663 | 2016-12-17 02:59:00.831 plugin-container[1664:9137] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c03, name = 'com.apple.CFPasteboardClient' 02:59:00 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:59:00 INFO - PROCESS | 1663 | 2016-12-17 02:59:00.831 plugin-container[1664:9137] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:59:10 INFO - PROCESS | 1663 | RSS: Main: 228737024 02:59:10 INFO - PROCESS | 1663 | 03:00:00 INFO - PROCESS | 1663 | 1481972400068 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] 03:02:14 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 03:02:16 INFO - PROCESS | 1663 | RSS: Main: 233893888 03:02:16 INFO - PROCESS | 1663 | 03:05:18 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 03:05:19 INFO - PROCESS | 1663 | RSS: Main: 243097600 03:05:19 INFO - PROCESS | 1663 | 03:08:21 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 03:08:21 INFO - PROCESS | 1663 | RSS: Main: 246038528 03:08:21 INFO - PROCESS | 1663 | 03:11:23 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 03:11:24 INFO - PROCESS | 1663 | RSS: Main: 245239808 03:11:24 INFO - PROCESS | 1663 | 03:14:26 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 03:14:27 INFO - PROCESS | 1663 | RSS: Main: 248647680 03:14:27 INFO - PROCESS | 1663 | 03:17:30 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 03:17:30 INFO - PROCESS | 1663 | RSS: Main: 250306560 03:17:30 INFO - PROCESS | 1663 | 03:17:30 INFO - PROCESS | 1663 | __start_tp_report 03:17:30 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 03:17:30 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 03:17:30 INFO - PROCESS | 1663 | |i|pagename|runs| 03:17:30 INFO - PROCESS | 1663 | |0;dojo.html;2930.428566429546;3413.6586341365883;3436.656334366562;3457.654234576543;3487.686108250258;5786.739596718147;5844;5890.5582081343855;5897.616654917438;5914.970425147868;5776.480116789488;5881.8823623527505;5911.083782013784;5916.970415147929;5938.7624495020145;5280.023195708796;5327.680339179642;5354.196870469441;5363.678179309254;5368.463153684639;5344.518993290603;5373.516383525482;5383.892322153554;5429.809956651518;5430.294061771964;5205.921911171316;5278.683279003253;5364.302640656709;5370.892582148354;5375.274337964383;5269.578433725293;5292.655977361478;5316.494932981361;5337.2527846101575;5343.984642917833;5245.606579506552;5248.921266181011;5335.253064570963;5336.866578335534;5374.677519348813;5948.821535353946;5959;5966.194563733884;5975.581709280352;6005.939940600625;6131.3766666500715;6143.631382117087;6219.87560248797;6225.7509699611965;6227;6187.783427580013;6195.690215489208;6214.067889816563;6253.093301471306;6279.529035322324;5970.701464926779;6027.186329845458;6036.154938308552;6044.123602077674;6047.818565443044;5703.600747947604;6173.660448675276;6334.850672809317;6347.1114044033875;6394.936050639434;5106.4893510648635;5302.39022512416;5931.851703707442;6191.5665903387235;6194.907076393858;5171.3018742469285;6297.653629050446;6341.682915854189;6342.746290148435;6349.936500634934;5174.379074511083;6127.06736185181;6224.06639004153;6228.034654628449;6266.248050234001;4542.591166795004;4558.886027849264;5914.260717410324;6175.96912015446;6185.381461853779;4984.37695288089;5042.520960508746;5068.265101560327;5069.670471419346;5085.211792172219;4506.211413002711;4508.301213311942;4526.366308716782;4535.591796738309;4556.635469162459;5523.861903452365;5924.259467566554;5940.55445841555;5995.880082398327;6017.909731354033;6204.844878878064;6229.937700623026;6238.688065596702;6252.531060170505;6317.241930968313;5800.955827951009;5970.761169553256;5981.401859814071;6000.549958753111;6010.759569617254;5384.380796208487;5418.431064738224;5438.891222175614;5497.092979658391;5497.47773961473;6465.256495502889;6525.380088891643;6528.314526974599;6533.738650454024;6554.770583029571;6132.569672417391;6195.628262304276;6215.06773983906;6224.253089629274;6251.187345645037;6490.221173459215;6495.090687303876;6497.480201583957;6510.3489651034515;6520.152380190545;6189.84525386869;6222.502199823913;6226.906596401148;6229.56393052482;6246.281677607133;5985.820425387245;6206;6239.282482514569;6244.313125556095;6284.685765711788;6146.6004709693725;6151.753929842936;6177.722002509988;6177.7837775677635;6219.595726277777;6110.847228819137;6186.319504854372;6188.938110618837;6190.938090619216;6191.349908259568;5585.720713964367;5973.954557952254;6077.453029227391;6143.508519318354;6168.537359697951;6096.573239873166;6115.1438798569125;6129.601575897731;6132.233470816148;6138.2044803098925;6098.176746139215;6117.6822132024845;6137.9286238596105;6143.6928153593035;6149.938500614937 03:17:30 INFO - PROCESS | 1663 | |1;ext.html;440.5704438172782;518.4970578538816;519.8277883372994;521.1089037745454;545.9535939445148;37767.622323776755;39317;39525.40711889324;39620.80189599052;40912;32930.50604240938;40248.79253622389;40615.99999999993;40625.796871015606;40728.981775455555;9248.907510924888;9368.812623747555;9406.623735050624;9424.905750942471;9442.38624489406;9318.254539636846;9393.20157786589;9405.341626086176;9441.952790236039;9446.952765236165;9351.95324023379;9364.578593963288;9367.578458969363;9376;9582.80834383312;8037.35701143907;9350.90649093507;9420.199283060949;9436.245100391952;9446.197073248748;9210.769730756718;9271.953640231823;9276.93315268746;9283.767905802342;9304.441733496013;38796.22407551833;39183;39183.824485265206;39241.80379098115;39314;2806.7894907881787;3147.728627638004;3182.0612919188893;3192.776505644606;3662.5604927408617;28663.140105796858;29119.41761164786;29398.971036013638;29485.115446536638;29988.55017174744;3149.4331020416316;3203.855826487814;3205.086550333167;3213.501907204387;3235.902922912316;479042;486137;490466.54766726296;490614;491173;55706.60733481695;55904.440955589926;56284;56290.718546407006;56317;58109.54726131796;58519;58550.41449585535;58760;58763.412365875796;15712.371505139896;15802.972806767753;15982.600434988986;15991.760123598155;16003.59991000211;12536.874631253571;12619.369031548385;12619.369031548385;12626.494940202472;12656.873431265754;14490.203038832966;14732.631684207981;14910.850891491164;14937.879659025617;15103.54689359321;3144.9371012579622;3232.7575431842706;3245.724113450336;5885.705714714247;6887.242403335629;824.431142511665;916.2074805293506;932.2914584915513;932.3007744191856;1025.2771795883884;27305.453890922072;28322;29713.55429668557;29809.85095074511;29989.850050750043;927.8608208768738;979.9020097990144;1087.211771465681;1102.2725001498973;1110.2006555280186;14987.475438359605;15389.076655400711;15455.61360965985;15678.529644110695;15792.289346979414;665767;669345;670608;671012;671755.6412218006;54546.45453545414;55391.446085539435;55859.72070139705;55954.44045559574;55961.440385595626;1145.6505765741363;1345.5290648273026;1390.9026368154334;1392.9094608850392;1393.0457636519018;24294.27117186487;28644.710988005172;28893.27766805847;28970.565441517974;29211.415771684024;47378.76310618425;47430;47550.28674569954;47920.04159916852;47931;478456;479271.01822454715;481369;482930;483217;23107.869382988567;23577.056917723785;25590.744092558834;25756;25952.870235649458;43269;44696.32955505605;44723.77638111789;45161.548384515736;45188.322175166744;29809.701902980694;35424;36575.817120915286;36694;37327.626723732414;37009.81495092507;37069.07327316839;37255.882323530335;37643.49426022929;37677.62322376741;27676;32373.51439728453;35098.82450587731;36332.63667363292;38071;28989.855050725455;32530.02409927706;33217.667823321455;33271.00186994394;33381.49927751037;24674.259772206864;24937.75062249427;25083;25332.620010700222;25413.856376463475 03:17:30 INFO - PROCESS | 1663 | |2;jquery.html;372.97948612826286;373.41746874875196;387.15406836063204;396.87895191966436;403.11113993644017;14940.402383904659;15398.538043858702;18306;19418.737782044176;19667.21331146754;12311.692207694812;19524.414267571996;19875.00624968753;19945.401637950847;20080.59838803223;5778.393268706778;6024.427679370453;6031.909521357183;6037.396260373971;6064;3707.073231692077;4962.280469331945;5049.84850454487;5467.261919640838;5988.281406231258;5922.200502932092;5928.792492262772;5933.614315069528;5945.078512830518;5952.70236488176;5729.427057294279;6054.969725151368;6105.084237364409;6133.417325354084;6135.601185922901;5760.53915686744;5849;5863.208466856962;5910.320313163998;5928.7332070056955;12749.553765618251;12834.165779224413;12990.935045324808;12998.480060797558;13053.738925221442;1897.3169658922777;2328.941776455585;2424.9815077667245;2779.4024284778666;2904.2013446302226;9347.158755712018;10580.312279701795;12837;15608.765868511982;15616.609584760357;1421.3035612549777;1819.6360727854349;1829.4420201838434;1883.3973128598716;1920.3662791278844;107889.46055269752;108107.45946270219;114175.42912285385;114329;114379;8680.435771674822;11466.598669046543;11467.197296189186;11576.24754390962;11780.705482362839;14570.344334504973;14579.708405831823;14592.854071459362;14601.415943362359;14624;8340.29107525867;8406.831863362699;8447.704330348532;8471.322294216448;8576.785580360542;8305.584720763938;8330.958345208235;8473.449225800427;8486.151384861465;8511;4360.542143074995;4524.954750452519;4558.680892337571;4601.44782626087;4638.628909687217;1884.9905750471435;1952.2483843719986;2005.4986253436641;2006.287767842424;2014.6977953306828;806.479820515764;839.6053854688287;917.8347897378402;930.0885132570119;960.9759756006156;19532.414027579194;20449.795502044788;20556.17775288972;20585.69121463179;20986.790132098482;870.0472982084607;873.3580818098692;913.7852604637905;932.1750251027889;958.5351104714208;8797.956010219908;9299.233145702296;9802;10569.048785609331;10577.206709496819;489192;537917;599334;601683;606275;95866;127554;129817;129982;130357.39285214242;943.5423819447562;947.6920000999564;963.2486660404895;965.7295957132014;976.6923419123095;14244;17208.31166753316;17616.383426580007;17767;18017.279308828023;49673;52018;52546.47453525416;57984.840303192854;58593;67394.66302668455;71530.64234678794;74235.25764742284;75058.62470687611;75930.62034689791;7614.8197029460525;7620.314171724572;7980.6408711606955;8063.798405039921;11141.944290278496;11368.204225704121;12042.879571204176;12880.806787898002;12902.612921612366;13046.02154838372;11030.889691102986;11118.722031949266;11289.37908415045;11664.125190610908;11678.766424671288;11111.499982500645;11204.159688023268;11317.377544235145;11369.31784092957;11572.479238434122;11392.430378480876;11505.367204803817;11577.826332605186;11644.88355116472;11926.582569610022;9904.158146557624;11849.644510664693;12000.75998480043;12321;12352.876471235531;11325.660230193107;11327.830082548604;11465.770684586429;11698.883011169779;11710.355930423908 03:17:30 INFO - PROCESS | 1663 | |3;mootools.html;1437.3675582743595;1467.5083846911286;1605.919704014799;1743.058748275931;1781.8129096444882;7510.849783004337;7786.3381612563;7929.603519824007;8424.283935865458;8448.535330556817;8041.236082572161;8271.586420678972;8321.209485098922;8383.622736976835;8414.83170336593;1953.4042117154263;2290.209877592228;2307.1694190091553;2320.373499155227;2331.3472227776165;2324.337563794319;2347.9882600586975;2359.398353419884;2368.27767530903;2369.57347677418;2310.699609050821;2320.5010922651627;2332.9385129766;2333.6499525071204;2333.9183128590503;2288.7367952685404;2299.6320588705894;2309.515001849614;2315.2591170825344;2317.629179331307;1909.484439201408;2321.814254859607;2338.8830558472177;2339.7660233976467;2344.753800850903;8325.377506085564;8356.916430835674;8403.41176117672;8416.87374689374;8451.619677114482;5040.143175660147;5153.871153221162;5202.141646628302;5207.427183009866;5221.477852214786;3269.7711160218796;3299.9835000825083;3562.9667396454997;3564.215872508044;3568.107973006748;1010.6664800615779;1019.6788011776267;1047.0524175621076;1069.3049517813406;1084.951177197028;10298.867124616212;10916.181286403551;10951.507182176822;11020.669379918616;11034;3833.8083095845654;4079.3676980068135;4177.227212965611;4665;4763.309320148559;7866.960665196638;9417.293702972305;9431.104045115702;9433.48115853621;9452.716418507456;5190.740462976836;5191.92212116811;5204.9219261711105;5217.060929032735;5236.659617124875;4242.639375653104;4568.771561421916;4743.762811859462;4784.904301914012;4806.95193048072;3122.469180239365;3517.2086280586664;3836.6930645548846;3887.630675085862;3936.980315098406;1083.2254937719567;1255.2656695832857;1280.795072788368;1287.1118927939692;1291.593148158346;652.8204743695427;747.8653842308328;885.9025507194203;894.1996912763041;908.5548081440047;5493.505584497414;5538.86152846174;5586.608937374426;5622.353429355595;5730.1404789281105;372.87695060629926;439.8944253379166;452.96271538177206;455.83362072843175;459.3844248706749;3812.1803812180515;3835.463035175078;3853.246772718406;3869.187470631199;3928.7839168845417;9793.853092203623;10081.546330415149;10144.644937427154;10197.082262596401;10203.7449063774;10043.648472303434;10156.796864062677;10167.23745719074;10179.541920613443;10182.032706892833;7699.422543309274;7734.839774033828;7840.960795196102;7911.643976021093;7924.643391047417;5482.040642887399;5535.308086489189;5560.165975103767;5590.692511911721;5631.88736225265;9297.86053209216;9338.066193380608;9492.667756628762;9514;9553.283503737108;9425.19885809714;9459;9473.526323683926;9569.856452153084;9617.663381781604;6507;6733.562318449481;6888.242293347627;7113.359797618239;7186.640667966685;6927.1687397510605;7561.130469996021;8013.559254241072;8016.759497215093;8036.316913062456;7043.577385356895;7213.422926165789;7224.385927196037;7232.855342893218;7300.379467745089;5851.297844258716;6781.355771201728;6963.61700106499;7037.683304251424;7052.294770522906;6551.5413921026975;6604.471642268511;6682.498812588978;6707.631080290631;6746.696398661974;4813;4816.759162041954;4849.466558678472;4879;4891.486393928586;4777.808887644455;4799.328094066903;4899.681520701143;4950.356733024028;4964.6524743269065 03:17:30 INFO - PROCESS | 1663 | |4;prototype.html;305.4517141730593;306.66267106183216;313.32634835104534;314.60831265074984;316.2788841441514;4686.367340409044;4777.665563410558;4894.486078961707;5344.839654810352;5674.716264186794;3862.478565393671;4633.721976681401;5293.57651387889;5512.862178445541;5541.420695101897;4187.455630767996;4190.497140343148;4194.895127621819;4212.894677633053;4235.915281694364;3307.065366760448;3511.5610548681416;4005.9799701001452;4097.795110244476;4227.450431443908;3169.857356418967;4157.376393540978;4162.542120366757;4164.625183733463;4172.937405938914;3767.05823544114;3993.5008123984503;4135.710500264968;4137.97931010346;4143.606357396043;2967.896123635662;3419.991102624732;3629.2922880038404;3664.1572438339063;3665.560132784083;5123.410807757118;5206.427292997805;5293.020791153649;5334.946650533484;5366.624336296461;3747.5877653458097;4222.91554168918;4700.4041907940455;4793.9280910786365;4837.492063333335;4758.857234282942;5080.669756465818;5597.870331986468;5758.62568933018;5852.648841069549;3919.6085389686823;3945.6251656092627;4041.6968727345566;4062.289099407592;4063.8780836574656;5294.285271488338;5557.972210138963;6040.942835003857;6080.331163572004;6114.357992410777;3349.882754103595;4044.988752811797;4090.363854458181;4103.261412945699;4128.277551428488;3985;4105.014796448891;4134.565870583546;4137.710360274812;4154.688398370135;3989.401589761559;4800.135975524369;4805.639577031667;4824.324594556765;4828.03439312139;5594.468525490072;5794.449527294901;5833.50415214703;5856.950067476434;5878.3540608629655;1111.9388433636227;1160.7504386556761;1278.7889998150388;1314.6450458376185;1317.8798021681685;842.6966292134827;888.6223355074068;918.2195134136065;922.3773952582114;924.4746168821498;4650.395448591729;4981.651284410056;4991.401031876198;5023.844515761395;5217.426083130853;5419.430959749169;5695.241140847021;5706.087026075808;5710.486056254957;5718.485336319668;4444.799984000728;5026.766809156693;5059.418166910778;5204.661696989683;5216.295800067019;2373.4303767096117;2916.606258155122;2936.7518804508427;2937.6621688506098;2966.8664910079096;5032.7483625819295;5657.60396772222;5778.826635200867;5834.358220595731;5853.560982926298;5218;5390.4879036491475;5646.350669672958;5786.768529258867;6110.663913484801;5334.786608535693;6318.241810982711;6349.746010159542;6366.694827560339;6376.489880809524;5608.943910561005;6154.507639388928;6155.799619074238;6188.781833446673;6212.844678883064;5434.456554344534;6036.396360363929;6071.574989750675;6074.90887636677;6081.27024757032;5016.874578135576;6224.813255602339;6328.240611126695;6343.938220078585;6408.871822563429;3642.726795490296;3897.103666156743;3899.220155968874;3948.3485224937563;4009.699272554512;5784.855378615568;5980.970095149496;6081.817545473643;6130.762271770861;6131.877362452637;4743.905121897612;5159.742012899265;5348.154886564129;5726.055200891807;5911.438413350725;5027.371578552681;5607.523360514402;5937.1204965592515;5941.346451890375;5963.719954025182;5442.346918369821;5672.7163641816915;5714.999875021937;5762.884742305214;5842.152887831156;5856.443637854397;5887;5887.264091988502;5919.289685237799;5929.99190137669;5208.427073021889;5523.917141242804;5585.944140558542;5789.334226563999;5817.389174136655 03:17:30 INFO - PROCESS | 1663 | |5;yui.html;631.4190944331216;700.4676445901117;723.3056265984654;746.8692978728725;784.6900474312648;13796;14315.069520481162;14717.337719802608;14765;14805.925970370134;13316.20102793833;13492.392842322095;13504.864951350484;13518.053736238468;13760.724785504284;668.6924014953103;819.6106849246605;839.2991851803721;840.9159084091573;843.5149788871393;680.6766785776741;814.0313027497289;831.8752187171942;839.630562552475;845.3997661660229;827.0695467598952;876.5310558851016;877.2499512916428;886.6630680341461;899.3299991506337;729.5258082246529;854.6196942360652;859.9914000859974;870.9390342676015;871.7907702151501;606.2755007765721;627.1721327847243;639.833643252753;643.4498503779248;653.0595941843777;11537.826932596017;13776.972688908336;13841.515546955809;13967.511137110254;13996.370163342674;10903.618373356974;12602.306873121976;13403.664908377272;13550.390232439564;13684.52104176349;10825.350478971208;10849.67450976472;11154.609588664358;11238.438078096062;11582.594609188722;3483.7332695031096;3602.3695853225845;3690.4833323334756;3690.907727306812;3761.887143385703;89891.55054224753;92210.61684074745;92328.53835730845;92754;96930.03069969281;10180.389176649425;10382.948085259677;10493.370397776158;10502.579896804195;10572.629957951589;20245.08897099634;20797.89601051985;21126;21295.574088518144;21334.35996920095;7897.921020789719;7903.446758726834;7918.643661035152;8089.514629122272;8103.270705636521;3493.9650603494147;4282.807273672693;4291.055967687166;4297.505786834554;4306.849260275875;3442.208292092833;3580.373434648926;3582.2119133790525;3589.407747721649;3598.5321908151773;649.5226008883467;659.1957811469999;659.3571268013649;660.7753363856312;669.5380187670589;548.1271496397034;565.7143142712904;585.1281590430314;626.2078470734524;630.1713247080075;6620.602763834186;7611.619419029026;9392.765180870396;9413.482258475713;9422.858657120149;620.2742790934617;625.8310256230792;633.4330773957374;647.9481641468672;663.4559661077966;3872.457855900176;4322.178786030644;4540.123640845213;4549.977250113794;4585.9541404585525;97339.51330243303;97583;100347.49826250822;100980.49509752404;115494;93884.53057734668;95543.04456955341;99831.00168998216;101014;108672;90089;90548.09451905396;90911;91611;92273.53863230642;9350.485723285285;9686.806263874541;9977.650782222587;10003.099721025143;10003.949980250052;47616.7619161902;52326.73836630792;56604.71697641486;57673;58141;61320.69339653273;74646.88029679668;77631;78351;80236;12424.689382765142;12962;13084.934575327063;13458.932705336802;13487.595372138852;13935.512257070954;13973.161610303414;13979.930100349433;14235.359408826835;14380.784288235476;12259.877401226231;13109;13159.276239806903;13782.86217137856;13873.306334683024;12943.482260709467;13087.738245235232;13141;13254.734905302033;13697.863021369658;12796.232226066466;13134.671633209246;13350.532731354357;13366.264855433044;13467.797983030458;8203.83592328162;8614.069513723412;9346.0653934606;10864.619738309122;11449.942750286194;9045.412048216846;9728.610855565697;11425.257358271685;11454.770904582028;11481.885181148416 03:17:30 INFO - PROCESS | 1663 | __end_tp_report 03:17:30 INFO - PROCESS | 1663 | __start_cc_report 03:17:30 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,363 03:17:30 INFO - PROCESS | 1663 | __end_cc_report 03:17:30 INFO - PROCESS | 1663 | __startTimestamp1481973450617__endTimestamp 03:17:30 INFO - PROCESS | 1663 | 03:17:30 INFO - PROCESS | 1663 | ------- Summary: start ------- 03:17:30 INFO - PROCESS | 1663 | Number of tests: 6 03:17:30 INFO - PROCESS | 1663 | 03:17:30 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5788.93 Median:6017.91 stddev:651.45 (10.8%) stddev-sans-first:613.65 03:17:30 INFO - PROCESS | 1663 | Values: 2930.4 3413.7 3436.7 3457.7 3487.7 5786.7 5844.0 5890.6 5897.6 5915.0 5776.5 5881.9 5911.1 5917.0 5938.8 5280.0 5327.7 5354.2 5363.7 5368.5 5344.5 5373.5 5383.9 5429.8 5430.3 5205.9 5278.7 5364.3 5370.9 5375.3 5269.6 5292.7 5316.5 5337.3 5344.0 5245.6 5248.9 5335.3 5336.9 5374.7 5948.8 5959.0 5966.2 5975.6 6005.9 6131.4 6143.6 6219.9 6225.8 6227.0 6187.8 6195.7 6214.1 6253.1 6279.5 5970.7 6027.2 6036.2 6044.1 6047.8 5703.6 6173.7 6334.9 6347.1 6394.9 5106.5 5302.4 5931.9 6191.6 6194.9 5171.3 6297.7 6341.7 6342.7 6349.9 5174.4 6127.1 6224.1 6228.0 6266.2 4542.6 4558.9 5914.3 6176.0 6185.4 4984.4 5042.5 5068.3 5069.7 5085.2 4506.2 4508.3 4526.4 4535.6 4556.6 5523.9 5924.3 5940.6 5995.9 6017.9 6204.8 6229.9 6238.7 6252.5 6317.2 5801.0 5970.8 5981.4 6000.5 6010.8 5384.4 5418.4 5438.9 5497.1 5497.5 6465.3 6525.4 6528.3 6533.7 6554.8 6132.6 6195.6 6215.1 6224.3 6251.2 6490.2 6495.1 6497.5 6510.3 6520.2 6189.8 6222.5 6226.9 6229.6 6246.3 5985.8 6206.0 6239.3 6244.3 6284.7 6146.6 6151.8 6177.7 6177.8 6219.6 6110.8 6186.3 6188.9 6190.9 6191.3 5585.7 5974.0 6077.5 6143.5 6168.5 6096.6 6115.1 6129.6 6132.2 6138.2 6098.2 6117.7 6137.9 6143.7 6149.9 03:17:30 INFO - PROCESS | 1663 | 03:17:30 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:67035.92 Median:39183.41 stddev:148010.46 (377.7%) stddev-sans-first:148341.21 03:17:30 INFO - PROCESS | 1663 | Values: 440.6 518.5 519.8 521.1 546.0 37767.6 39317.0 39525.4 39620.8 40912.0 32930.5 40248.8 40616.0 40625.8 40729.0 9248.9 9368.8 9406.6 9424.9 9442.4 9318.3 9393.2 9405.3 9442.0 9447.0 9352.0 9364.6 9367.6 9376.0 9582.8 8037.4 9350.9 9420.2 9436.2 9446.2 9210.8 9272.0 9276.9 9283.8 9304.4 38796.2 39183.0 39183.8 39241.8 39314.0 2806.8 3147.7 3182.1 3192.8 3662.6 28663.1 29119.4 29399.0 29485.1 29988.6 3149.4 3203.9 3205.1 3213.5 3235.9 479042.0 486137.0 490466.5 490614.0 491173.0 55706.6 55904.4 56284.0 56290.7 56317.0 58109.5 58519.0 58550.4 58760.0 58763.4 15712.4 15803.0 15982.6 15991.8 16003.6 12536.9 12619.4 12619.4 12626.5 12656.9 14490.2 14732.6 14910.9 14937.9 15103.5 3144.9 3232.8 3245.7 5885.7 6887.2 824.4 916.2 932.3 932.3 1025.3 27305.5 28322.0 29713.6 29809.9 29989.9 927.9 979.9 1087.2 1102.3 1110.2 14987.5 15389.1 15455.6 15678.5 15792.3 665767.0 669345.0 670608.0 671012.0 671755.6 54546.5 55391.4 55859.7 55954.4 55961.4 1145.7 1345.5 1390.9 1392.9 1393.0 24294.3 28644.7 28893.3 28970.6 29211.4 47378.8 47430.0 47550.3 47920.0 47931.0 478456.0 479271.0 481369.0 482930.0 483217.0 23107.9 23577.1 25590.7 25756.0 25952.9 43269.0 44696.3 44723.8 45161.5 45188.3 29809.7 35424.0 36575.8 36694.0 37327.6 37009.8 37069.1 37255.9 37643.5 37677.6 27676.0 32373.5 35098.8 36332.6 38071.0 28989.9 32530.0 33217.7 33271.0 33381.5 24674.3 24937.8 25083.0 25332.6 25413.9 03:17:30 INFO - PROCESS | 1663 | 03:17:30 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:33355.46 Median:2376.96 stddev:94958.57 (3995.0%) stddev-sans-first:95192.66 03:17:30 INFO - PROCESS | 1663 | Values: 373.0 373.4 387.2 396.9 403.1 14940.4 15398.5 18306.0 19418.7 19667.2 12311.7 19524.4 19875.0 19945.4 20080.6 5778.4 6024.4 6031.9 6037.4 6064.0 3707.1 4962.3 5049.8 5467.3 5988.3 5922.2 5928.8 5933.6 5945.1 5952.7 5729.4 6055.0 6105.1 6133.4 6135.6 5760.5 5849.0 5863.2 5910.3 5928.7 12749.6 12834.2 12990.9 12998.5 13053.7 1897.3 2328.9 2425.0 2779.4 2904.2 9347.2 10580.3 12837.0 15608.8 15616.6 1421.3 1819.6 1829.4 1883.4 1920.4 107889.5 108107.5 114175.4 114329.0 114379.0 8680.4 11466.6 11467.2 11576.2 11780.7 14570.3 14579.7 14592.9 14601.4 14624.0 8340.3 8406.8 8447.7 8471.3 8576.8 8305.6 8331.0 8473.4 8486.2 8511.0 4360.5 4525.0 4558.7 4601.4 4638.6 1885.0 1952.2 2005.5 2006.3 2014.7 806.5 839.6 917.8 930.1 961.0 19532.4 20449.8 20556.2 20585.7 20986.8 870.0 873.4 913.8 932.2 958.5 8798.0 9299.2 9802.0 10569.0 10577.2 489192.0 537917.0 599334.0 601683.0 606275.0 95866.0 127554.0 129817.0 129982.0 130357.4 943.5 947.7 963.2 965.7 976.7 14244.0 17208.3 17616.4 17767.0 18017.3 49673.0 52018.0 52546.5 57984.8 58593.0 67394.7 71530.6 74235.3 75058.6 75930.6 7614.8 7620.3 7980.6 8063.8 11141.9 11368.2 12042.9 12880.8 12902.6 13046.0 11030.9 11118.7 11289.4 11664.1 11678.8 11111.5 11204.2 11317.4 11369.3 11572.5 11392.4 11505.4 11577.8 11644.9 11926.6 9904.2 11849.6 12000.8 12321.0 12352.9 11325.7 11327.8 11465.8 11698.9 11710.4 03:17:30 INFO - PROCESS | 1663 | 03:17:30 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5406.80 Median:4895.58 stddev:3003.95 (61.4%) stddev-sans-first:2997.57 03:17:30 INFO - PROCESS | 1663 | Values: 1437.4 1467.5 1605.9 1743.1 1781.8 7510.8 7786.3 7929.6 8424.3 8448.5 8041.2 8271.6 8321.2 8383.6 8414.8 1953.4 2290.2 2307.2 2320.4 2331.3 2324.3 2348.0 2359.4 2368.3 2369.6 2310.7 2320.5 2332.9 2333.6 2333.9 2288.7 2299.6 2309.5 2315.3 2317.6 1909.5 2321.8 2338.9 2339.8 2344.8 8325.4 8356.9 8403.4 8416.9 8451.6 5040.1 5153.9 5202.1 5207.4 5221.5 3269.8 3300.0 3563.0 3564.2 3568.1 1010.7 1019.7 1047.1 1069.3 1085.0 10298.9 10916.2 10951.5 11020.7 11034.0 3833.8 4079.4 4177.2 4665.0 4763.3 7867.0 9417.3 9431.1 9433.5 9452.7 5190.7 5191.9 5204.9 5217.1 5236.7 4242.6 4568.8 4743.8 4784.9 4807.0 3122.5 3517.2 3836.7 3887.6 3937.0 1083.2 1255.3 1280.8 1287.1 1291.6 652.8 747.9 885.9 894.2 908.6 5493.5 5538.9 5586.6 5622.4 5730.1 372.9 439.9 453.0 455.8 459.4 3812.2 3835.5 3853.2 3869.2 3928.8 9793.9 10081.5 10144.6 10197.1 10203.7 10043.6 10156.8 10167.2 10179.5 10182.0 7699.4 7734.8 7841.0 7911.6 7924.6 5482.0 5535.3 5560.2 5590.7 5631.9 9297.9 9338.1 9492.7 9514.0 9553.3 9425.2 9459.0 9473.5 9569.9 9617.7 6507.0 6733.6 6888.2 7113.4 7186.6 6927.2 7561.1 8013.6 8016.8 8036.3 7043.6 7213.4 7224.4 7232.9 7300.4 5851.3 6781.4 6963.6 7037.7 7052.3 6551.5 6604.5 6682.5 6707.6 6746.7 4813.0 4816.8 4849.5 4879.0 4891.5 4777.8 4799.3 4899.7 4950.4 4964.7 03:17:30 INFO - PROCESS | 1663 | 03:17:30 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4652.93 Median:5205.54 stddev:1473.65 (28.3%) stddev-sans-first:1441.20 03:17:30 INFO - PROCESS | 1663 | Values: 305.5 306.7 313.3 314.6 316.3 4686.4 4777.7 4894.5 5344.8 5674.7 3862.5 4633.7 5293.6 5512.9 5541.4 4187.5 4190.5 4194.9 4212.9 4235.9 3307.1 3511.6 4006.0 4097.8 4227.5 3169.9 4157.4 4162.5 4164.6 4172.9 3767.1 3993.5 4135.7 4138.0 4143.6 2967.9 3420.0 3629.3 3664.2 3665.6 5123.4 5206.4 5293.0 5334.9 5366.6 3747.6 4222.9 4700.4 4793.9 4837.5 4758.9 5080.7 5597.9 5758.6 5852.6 3919.6 3945.6 4041.7 4062.3 4063.9 5294.3 5558.0 6040.9 6080.3 6114.4 3349.9 4045.0 4090.4 4103.3 4128.3 3985.0 4105.0 4134.6 4137.7 4154.7 3989.4 4800.1 4805.6 4824.3 4828.0 5594.5 5794.4 5833.5 5857.0 5878.4 1111.9 1160.8 1278.8 1314.6 1317.9 842.7 888.6 918.2 922.4 924.5 4650.4 4981.7 4991.4 5023.8 5217.4 5419.4 5695.2 5706.1 5710.5 5718.5 4444.8 5026.8 5059.4 5204.7 5216.3 2373.4 2916.6 2936.8 2937.7 2966.9 5032.7 5657.6 5778.8 5834.4 5853.6 5218.0 5390.5 5646.4 5786.8 6110.7 5334.8 6318.2 6349.7 6366.7 6376.5 5608.9 6154.5 6155.8 6188.8 6212.8 5434.5 6036.4 6071.6 6074.9 6081.3 5016.9 6224.8 6328.2 6343.9 6408.9 3642.7 3897.1 3899.2 3948.3 4009.7 5784.9 5981.0 6081.8 6130.8 6131.9 4743.9 5159.7 5348.2 5726.1 5911.4 5027.4 5607.5 5937.1 5941.3 5963.7 5442.3 5672.7 5715.0 5762.9 5842.2 5856.4 5887.0 5887.3 5919.3 5930.0 5208.4 5523.9 5585.9 5789.3 5817.4 03:17:30 INFO - PROCESS | 1663 | 03:17:30 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:20762.91 Median:4567.97 stddev:30494.95 (667.6%) stddev-sans-first:30543.04 03:17:30 INFO - PROCESS | 1663 | Values: 631.4 700.5 723.3 746.9 784.7 13796.0 14315.1 14717.3 14765.0 14805.9 13316.2 13492.4 13504.9 13518.1 13760.7 668.7 819.6 839.3 840.9 843.5 680.7 814.0 831.9 839.6 845.4 827.1 876.5 877.2 886.7 899.3 729.5 854.6 860.0 870.9 871.8 606.3 627.2 639.8 643.4 653.1 11537.8 13777.0 13841.5 13967.5 13996.4 10903.6 12602.3 13403.7 13550.4 13684.5 10825.4 10849.7 11154.6 11238.4 11582.6 3483.7 3602.4 3690.5 3690.9 3761.9 89891.6 92210.6 92328.5 92754.0 96930.0 10180.4 10382.9 10493.4 10502.6 10572.6 20245.1 20797.9 21126.0 21295.6 21334.4 7897.9 7903.4 7918.6 8089.5 8103.3 3494.0 4282.8 4291.1 4297.5 4306.8 3442.2 3580.4 3582.2 3589.4 3598.5 649.5 659.2 659.4 660.8 669.5 548.1 565.7 585.1 626.2 630.2 6620.6 7611.6 9392.8 9413.5 9422.9 620.3 625.8 633.4 647.9 663.5 3872.5 4322.2 4540.1 4550.0 4586.0 97339.5 97583.0 100347.5 100980.5 115494.0 93884.5 95543.0 99831.0 101014.0 108672.0 90089.0 90548.1 90911.0 91611.0 92273.5 9350.5 9686.8 9977.7 10003.1 10003.9 47616.8 52326.7 56604.7 57673.0 58141.0 61320.7 74646.9 77631.0 78351.0 80236.0 12424.7 12962.0 13084.9 13458.9 13487.6 13935.5 13973.2 13979.9 14235.4 14380.8 12259.9 13109.0 13159.3 13782.9 13873.3 12943.5 13087.7 13141.0 13254.7 13697.9 12796.2 13134.7 13350.5 13366.3 13467.8 8203.8 8614.1 9346.1 10864.6 11449.9 9045.4 9728.6 11425.3 11454.8 11481.9 03:17:30 INFO - PROCESS | 1663 | -------- Summary: end -------- 03:17:30 INFO - PROCESS | 1663 | 03:17:31 INFO - TEST-INFO | 1663: exit 0 03:17:31 INFO - TEST-OK | dromaeo_css | took 1117668ms 03:17:31 INFO - TEST-START | kraken 03:17:31 INFO - operating with platform_type : mac_ 03:17:31 INFO - Initialising browser for kraken test... 03:17:31 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpdRBkj3/profile http://localhost:49243/getInfo.html) 03:17:34 INFO - PROCESS | 1672 | 2016-12-17 03:17:34.271 plugin-container[1674:11487] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 03:17:34 INFO - PROCESS | 1672 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:17:34 INFO - PROCESS | 1672 | 2016-12-17 03:17:34.272 plugin-container[1674:11487] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 03:17:34 INFO - PROCESS | 1672 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:17:34 INFO - PROCESS | 1672 | 2016-12-17 03:17:34.272 plugin-container[1674:11487] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:17:34 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 03:17:34 INFO - PROCESS | 1672 | colorDepth:24 03:17:34 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 03:17:34 INFO - PROCESS | 1672 | __metrics 03:17:34 ERROR - PROCESS | 1672 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 03:17:35 INFO - TEST-INFO | 1672: exit 0 03:17:35 INFO - Browser initialized. 03:17:35 INFO - Running cycle 1/1 for kraken test... 03:17:35 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpdRBkj3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:17:38 INFO - PROCESS | 1675 | 2016-12-17 03:17:38.197 plugin-container[1676:11612] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 03:17:38 INFO - PROCESS | 1675 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:17:38 INFO - PROCESS | 1675 | 2016-12-17 03:17:38.198 plugin-container[1676:11612] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a17, name = 'com.apple.CFPasteboardClient' 03:17:38 INFO - PROCESS | 1675 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:17:38 INFO - PROCESS | 1675 | 2016-12-17 03:17:38.198 plugin-container[1676:11612] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:17:47 INFO - PROCESS | 1675 | RSS: Main: 229224448 03:17:47 INFO - PROCESS | 1675 | 03:17:50 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 03:17:51 INFO - PROCESS | 1675 | RSS: Main: 241344512 03:17:51 INFO - PROCESS | 1675 | 03:17:53 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 03:17:54 INFO - PROCESS | 1675 | RSS: Main: 249102336 03:17:54 INFO - PROCESS | 1675 | 03:17:57 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 03:17:58 INFO - PROCESS | 1675 | RSS: Main: 252846080 03:17:58 INFO - PROCESS | 1675 | 03:18:00 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 03:18:01 INFO - PROCESS | 1675 | RSS: Main: 254181376 03:18:01 INFO - PROCESS | 1675 | 03:18:03 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:18:03 INFO - PROCESS | 1675 | RSS: Main: 254259200 03:18:03 INFO - PROCESS | 1675 | 03:18:06 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 03:18:07 INFO - PROCESS | 1675 | RSS: Main: 258596864 03:18:07 INFO - PROCESS | 1675 | 03:18:10 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 03:18:10 INFO - PROCESS | 1675 | RSS: Main: 258256896 03:18:10 INFO - PROCESS | 1675 | 03:18:13 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 03:18:14 INFO - PROCESS | 1675 | RSS: Main: 258023424 03:18:14 INFO - PROCESS | 1675 | 03:18:15 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:18:16 INFO - PROCESS | 1675 | RSS: Main: 256897024 03:18:16 INFO - PROCESS | 1675 | 03:18:18 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:18:19 INFO - PROCESS | 1675 | RSS: Main: 256917504 03:18:19 INFO - PROCESS | 1675 | 03:18:21 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:18:21 INFO - PROCESS | 1675 | RSS: Main: 257110016 03:18:21 INFO - PROCESS | 1675 | 03:18:25 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:18:25 INFO - PROCESS | 1675 | RSS: Main: 257142784 03:18:25 INFO - PROCESS | 1675 | 03:18:28 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:18:29 INFO - PROCESS | 1675 | RSS: Main: 257564672 03:18:29 INFO - PROCESS | 1675 | 03:18:31 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 03:18:31 INFO - PROCESS | 1675 | RSS: Main: 259375104 03:18:31 INFO - PROCESS | 1675 | 03:18:31 INFO - PROCESS | 1675 | __start_tp_report 03:18:31 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 03:18:31 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 03:18:31 INFO - PROCESS | 1675 | |i|pagename|runs| 03:18:31 INFO - PROCESS | 1675 | |0;ai-astar;115;118;115;117;118;117;118;115;120;117 03:18:31 INFO - PROCESS | 1675 | |1;audio-beat-detection;145;111;113;154;104;171;148;112;137;110 03:18:31 INFO - PROCESS | 1675 | |2;audio-dft;194;232;199;216;227;198;221;283;206;198 03:18:31 INFO - PROCESS | 1675 | |3;audio-fft;81;60;61;85;60;59;64;50;84;77 03:18:31 INFO - PROCESS | 1675 | |4;audio-oscillator;86;72;91;74;70;95;87;70;70;89 03:18:31 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;138;133;87;85;89;86;89;93;89;75 03:18:31 INFO - PROCESS | 1675 | |6;imaging-darkroom;96;105;102;170;172;98;99;100;99;99 03:18:31 INFO - PROCESS | 1675 | |7;imaging-desaturate;94;91;102;132;89;88;90;90;88;88 03:18:31 INFO - PROCESS | 1675 | |8;json-parse-financial;57;57;58;60;62;59;60;60;58;58 03:18:31 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;70;77;72;82;74;72;59;105;109;109 03:18:31 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;83;87;85;83;83;85;83;84;84;81 03:18:31 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;153;161;141;135;173;158;135;172;268;172 03:18:31 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;167;167;174;144;217;203;165;166;169;166 03:18:31 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;80;91;93;88;71;66;68;68;68;68 03:18:31 INFO - PROCESS | 1675 | __end_tp_report 03:18:31 INFO - PROCESS | 1675 | __start_cc_report 03:18:31 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,961 03:18:31 INFO - PROCESS | 1675 | __end_cc_report 03:18:31 INFO - PROCESS | 1675 | __startTimestamp1481973511462__endTimestamp 03:18:31 INFO - PROCESS | 1675 | 03:18:31 INFO - PROCESS | 1675 | ------- Summary: start ------- 03:18:31 INFO - PROCESS | 1675 | Number of tests: 14 03:18:31 INFO - PROCESS | 1675 | 03:18:31 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:117.00 Median:117.50 stddev:1.63 (1.4%) stddev-sans-first:1.56 03:18:31 INFO - PROCESS | 1675 | Values: 115.0 118.0 115.0 117.0 118.0 117.0 118.0 115.0 120.0 117.0 03:18:31 INFO - PROCESS | 1675 | 03:18:31 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:130.50 Median:141.00 stddev:23.34 (16.6%) stddev-sans-first:24.16 03:18:31 INFO - PROCESS | 1675 | Values: 145.0 111.0 113.0 154.0 104.0 171.0 148.0 112.0 137.0 110.0 03:18:31 INFO - PROCESS | 1675 | 03:18:31 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:217.40 Median:218.50 stddev:26.65 (12.2%) stddev-sans-first:26.89 03:18:31 INFO - PROCESS | 1675 | Values: 194.0 232.0 199.0 216.0 227.0 198.0 221.0 283.0 206.0 198.0 03:18:31 INFO - PROCESS | 1675 | 03:18:31 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:68.10 Median:70.50 stddev:12.44 (17.6%) stddev-sans-first:12.29 03:18:31 INFO - PROCESS | 1675 | Values: 81.0 60.0 61.0 85.0 60.0 59.0 64.0 50.0 84.0 77.0 03:18:31 INFO - PROCESS | 1675 | 03:18:31 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:80.40 Median:86.50 stddev:10.06 (11.6%) stddev-sans-first:10.46 03:18:31 INFO - PROCESS | 1675 | Values: 86.0 72.0 91.0 74.0 70.0 95.0 87.0 70.0 70.0 89.0 03:18:31 INFO - PROCESS | 1675 | 03:18:31 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:96.40 Median:88.00 stddev:21.16 (24.0%) stddev-sans-first:16.23 03:18:31 INFO - PROCESS | 1675 | Values: 138.0 133.0 87.0 85.0 89.0 86.0 89.0 93.0 89.0 75.0 03:18:31 INFO - PROCESS | 1675 | 03:18:31 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:114.00 Median:97.00 stddev:30.14 (31.1%) stddev-sans-first:31.26 03:18:31 INFO - PROCESS | 1675 | Values: 96.0 105.0 102.0 170.0 172.0 98.0 99.0 100.0 99.0 99.0 03:18:31 INFO - PROCESS | 1675 | 03:18:31 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:89.50 stddev:13.61 (15.2%) stddev-sans-first:14.43 03:18:31 INFO - PROCESS | 1675 | Values: 94.0 91.0 102.0 132.0 89.0 88.0 90.0 90.0 88.0 88.0 03:18:31 INFO - PROCESS | 1675 | 03:18:31 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:58.90 Median:59.50 stddev:1.60 (2.7%) stddev-sans-first:1.54 03:18:31 INFO - PROCESS | 1675 | Values: 57.0 57.0 58.0 60.0 62.0 59.0 60.0 60.0 58.0 58.0 03:18:31 INFO - PROCESS | 1675 | 03:18:31 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:82.90 Median:72.00 stddev:18.08 (25.1%) stddev-sans-first:18.56 03:18:31 INFO - PROCESS | 1675 | Values: 70.0 77.0 72.0 82.0 74.0 72.0 59.0 105.0 109.0 109.0 03:18:31 INFO - PROCESS | 1675 | 03:18:31 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:84.00 stddev:1.62 (1.9%) stddev-sans-first:1.69 03:18:31 INFO - PROCESS | 1675 | Values: 83.0 87.0 85.0 83.0 83.0 85.0 83.0 84.0 84.0 81.0 03:18:31 INFO - PROCESS | 1675 | 03:18:31 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:166.80 Median:166.50 stddev:38.48 (23.1%) stddev-sans-first:40.48 03:18:31 INFO - PROCESS | 1675 | Values: 153.0 161.0 141.0 135.0 173.0 158.0 135.0 172.0 268.0 172.0 03:18:31 INFO - PROCESS | 1675 | 03:18:31 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.80 Median:168.00 stddev:20.87 (12.4%) stddev-sans-first:21.99 03:18:31 INFO - PROCESS | 1675 | Values: 167.0 167.0 174.0 144.0 217.0 203.0 165.0 166.0 169.0 166.0 03:18:31 INFO - PROCESS | 1675 | 03:18:31 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.10 Median:75.50 stddev:10.83 (14.3%) stddev-sans-first:11.39 03:18:31 INFO - PROCESS | 1675 | Values: 80.0 91.0 93.0 88.0 71.0 66.0 68.0 68.0 68.0 68.0 03:18:31 INFO - PROCESS | 1675 | -------- Summary: end -------- 03:18:31 INFO - PROCESS | 1675 | 03:18:32 INFO - TEST-INFO | 1675: exit 0 03:18:32 INFO - TEST-OK | kraken | took 61099ms 03:18:32 INFO - SUITE-END | took 1178s 03:18:32 INFO - Completed test suite (00:19:38) 03:18:32 INFO - javascript benchmark 03:18:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7140.936165097362, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2930.428566429546, 3413.6586341365883, 3436.656334366562, 3457.654234576543, 3487.686108250258, 5786.739596718147, 5844.0, 5890.5582081343855, 5897.616654917438, 5914.970425147868, 5776.480116789488, 5881.8823623527505, 5911.083782013784, 5916.970415147929, 5938.7624495020145, 5280.023195708796, 5327.680339179642, 5354.196870469441, 5363.678179309254, 5368.463153684639, 5344.518993290603, 5373.516383525482, 5383.892322153554, 5429.809956651518, 5430.294061771964, 5205.921911171316, 5278.683279003253, 5364.302640656709, 5370.892582148354, 5375.274337964383, 5269.578433725293, 5292.655977361478, 5316.494932981361, 5337.2527846101575, 5343.984642917833, 5245.606579506552, 5248.921266181011, 5335.253064570963, 5336.866578335534, 5374.677519348813, 5948.821535353946, 5959.0, 5966.194563733884, 5975.581709280352, 6005.939940600625, 6131.3766666500715, 6143.631382117087, 6219.87560248797, 6225.7509699611965, 6227.0, 6187.783427580013, 6195.690215489208, 6214.067889816563, 6253.093301471306, 6279.529035322324, 5970.701464926779, 6027.186329845458, 6036.154938308552, 6044.123602077674, 6047.818565443044, 5703.600747947604, 6173.660448675276, 6334.850672809317, 6347.1114044033875, 6394.936050639434, 5106.4893510648635, 5302.39022512416, 5931.851703707442, 6191.5665903387235, 6194.907076393858, 5171.3018742469285, 6297.653629050446, 6341.682915854189, 6342.746290148435, 6349.936500634934, 5174.379074511083, 6127.06736185181, 6224.06639004153, 6228.034654628449, 6266.248050234001, 4542.591166795004, 4558.886027849264, 5914.260717410324, 6175.96912015446, 6185.381461853779, 4984.37695288089, 5042.520960508746, 5068.265101560327, 5069.670471419346, 5085.211792172219, 4506.211413002711, 4508.301213311942, 4526.366308716782, 4535.591796738309, 4556.635469162459, 5523.861903452365, 5924.259467566554, 5940.55445841555, 5995.880082398327, 6017.909731354033, 6204.844878878064, 6229.937700623026, 6238.688065596702, 6252.531060170505, 6317.241930968313, 5800.955827951009, 5970.761169553256, 5981.401859814071, 6000.549958753111, 6010.759569617254, 5384.380796208487, 5418.431064738224, 5438.891222175614, 5497.092979658391, 5497.47773961473, 6465.256495502889, 6525.380088891643, 6528.314526974599, 6533.738650454024, 6554.770583029571, 6132.569672417391, 6195.628262304276, 6215.06773983906, 6224.253089629274, 6251.187345645037, 6490.221173459215, 6495.090687303876, 6497.480201583957, 6510.3489651034515, 6520.152380190545, 6189.84525386869, 6222.502199823913, 6226.906596401148, 6229.56393052482, 6246.281677607133, 5985.820425387245, 6206.0, 6239.282482514569, 6244.313125556095, 6284.685765711788, 6146.6004709693725, 6151.753929842936, 6177.722002509988, 6177.7837775677635, 6219.595726277777, 6110.847228819137, 6186.319504854372, 6188.938110618837, 6190.938090619216, 6191.349908259568, 5585.720713964367, 5973.954557952254, 6077.453029227391, 6143.508519318354, 6168.537359697951, 6096.573239873166, 6115.1438798569125, 6129.601575897731, 6132.233470816148, 6138.2044803098925, 6098.176746139215, 6117.6822132024845, 6137.9286238596105, 6143.6928153593035, 6149.938500614937], "value": 5749.212150892092, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [440.5704438172782, 518.4970578538816, 519.8277883372994, 521.1089037745454, 545.9535939445148, 37767.622323776755, 39317.0, 39525.40711889324, 39620.80189599052, 40912.0, 32930.50604240938, 40248.79253622389, 40615.99999999993, 40625.796871015606, 40728.981775455555, 9248.907510924888, 9368.812623747555, 9406.623735050624, 9424.905750942471, 9442.38624489406, 9318.254539636846, 9393.20157786589, 9405.341626086176, 9441.952790236039, 9446.952765236165, 9351.95324023379, 9364.578593963288, 9367.578458969363, 9376.0, 9582.80834383312, 8037.35701143907, 9350.90649093507, 9420.199283060949, 9436.245100391952, 9446.197073248748, 9210.769730756718, 9271.953640231823, 9276.93315268746, 9283.767905802342, 9304.441733496013, 38796.22407551833, 39183.0, 39183.824485265206, 39241.80379098115, 39314.0, 2806.7894907881787, 3147.728627638004, 3182.0612919188893, 3192.776505644606, 3662.5604927408617, 28663.140105796858, 29119.41761164786, 29398.971036013638, 29485.115446536638, 29988.55017174744, 3149.4331020416316, 3203.855826487814, 3205.086550333167, 3213.501907204387, 3235.902922912316, 479042.0, 486137.0, 490466.54766726296, 490614.0, 491173.0, 55706.60733481695, 55904.440955589926, 56284.0, 56290.718546407006, 56317.0, 58109.54726131796, 58519.0, 58550.41449585535, 58760.0, 58763.412365875796, 15712.371505139896, 15802.972806767753, 15982.600434988986, 15991.760123598155, 16003.59991000211, 12536.874631253571, 12619.369031548385, 12619.369031548385, 12626.494940202472, 12656.873431265754, 14490.203038832966, 14732.631684207981, 14910.850891491164, 14937.879659025617, 15103.54689359321, 3144.9371012579622, 3232.7575431842706, 3245.724113450336, 5885.705714714247, 6887.242403335629, 824.431142511665, 916.2074805293506, 932.2914584915513, 932.3007744191856, 1025.2771795883884, 27305.453890922072, 28322.0, 29713.55429668557, 29809.85095074511, 29989.850050750043, 927.8608208768738, 979.9020097990144, 1087.211771465681, 1102.2725001498973, 1110.2006555280186, 14987.475438359605, 15389.076655400711, 15455.61360965985, 15678.529644110695, 15792.289346979414, 665767.0, 669345.0, 670608.0, 671012.0, 671755.6412218006, 54546.45453545414, 55391.446085539435, 55859.72070139705, 55954.44045559574, 55961.440385595626, 1145.6505765741363, 1345.5290648273026, 1390.9026368154334, 1392.9094608850392, 1393.0457636519018, 24294.27117186487, 28644.710988005172, 28893.27766805847, 28970.565441517974, 29211.415771684024, 47378.76310618425, 47430.0, 47550.28674569954, 47920.04159916852, 47931.0, 478456.0, 479271.01822454715, 481369.0, 482930.0, 483217.0, 23107.869382988567, 23577.056917723785, 25590.744092558834, 25756.0, 25952.870235649458, 43269.0, 44696.32955505605, 44723.77638111789, 45161.548384515736, 45188.322175166744, 29809.701902980694, 35424.0, 36575.817120915286, 36694.0, 37327.626723732414, 37009.81495092507, 37069.07327316839, 37255.882323530335, 37643.49426022929, 37677.62322376741, 27676.0, 32373.51439728453, 35098.82450587731, 36332.63667363292, 38071.0, 28989.855050725455, 32530.02409927706, 33217.667823321455, 33271.00186994394, 33381.49927751037, 24674.259772206864, 24937.75062249427, 25083.0, 25332.620010700222, 25413.856376463475], "value": 18815.1693075518, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.97948612826286, 373.41746874875196, 387.15406836063204, 396.87895191966436, 403.11113993644017, 14940.402383904659, 15398.538043858702, 18306.0, 19418.737782044176, 19667.21331146754, 12311.692207694812, 19524.414267571996, 19875.00624968753, 19945.401637950847, 20080.59838803223, 5778.393268706778, 6024.427679370453, 6031.909521357183, 6037.396260373971, 6064.0, 3707.073231692077, 4962.280469331945, 5049.84850454487, 5467.261919640838, 5988.281406231258, 5922.200502932092, 5928.792492262772, 5933.614315069528, 5945.078512830518, 5952.70236488176, 5729.427057294279, 6054.969725151368, 6105.084237364409, 6133.417325354084, 6135.601185922901, 5760.53915686744, 5849.0, 5863.208466856962, 5910.320313163998, 5928.7332070056955, 12749.553765618251, 12834.165779224413, 12990.935045324808, 12998.480060797558, 13053.738925221442, 1897.3169658922777, 2328.941776455585, 2424.9815077667245, 2779.4024284778666, 2904.2013446302226, 9347.158755712018, 10580.312279701795, 12837.0, 15608.765868511982, 15616.609584760357, 1421.3035612549777, 1819.6360727854349, 1829.4420201838434, 1883.3973128598716, 1920.3662791278844, 107889.46055269752, 108107.45946270219, 114175.42912285385, 114329.0, 114379.0, 8680.435771674822, 11466.598669046543, 11467.197296189186, 11576.24754390962, 11780.705482362839, 14570.344334504973, 14579.708405831823, 14592.854071459362, 14601.415943362359, 14624.0, 8340.29107525867, 8406.831863362699, 8447.704330348532, 8471.322294216448, 8576.785580360542, 8305.584720763938, 8330.958345208235, 8473.449225800427, 8486.151384861465, 8511.0, 4360.542143074995, 4524.954750452519, 4558.680892337571, 4601.44782626087, 4638.628909687217, 1884.9905750471435, 1952.2483843719986, 2005.4986253436641, 2006.287767842424, 2014.6977953306828, 806.479820515764, 839.6053854688287, 917.8347897378402, 930.0885132570119, 960.9759756006156, 19532.414027579194, 20449.795502044788, 20556.17775288972, 20585.69121463179, 20986.790132098482, 870.0472982084607, 873.3580818098692, 913.7852604637905, 932.1750251027889, 958.5351104714208, 8797.956010219908, 9299.233145702296, 9802.0, 10569.048785609331, 10577.206709496819, 489192.0, 537917.0, 599334.0, 601683.0, 606275.0, 95866.0, 127554.0, 129817.0, 129982.0, 130357.39285214242, 943.5423819447562, 947.6920000999564, 963.2486660404895, 965.7295957132014, 976.6923419123095, 14244.0, 17208.31166753316, 17616.383426580007, 17767.0, 18017.279308828023, 49673.0, 52018.0, 52546.47453525416, 57984.840303192854, 58593.0, 67394.66302668455, 71530.64234678794, 74235.25764742284, 75058.62470687611, 75930.62034689791, 7614.8197029460525, 7620.314171724572, 7980.6408711606955, 8063.798405039921, 11141.944290278496, 11368.204225704121, 12042.879571204176, 12880.806787898002, 12902.612921612366, 13046.02154838372, 11030.889691102986, 11118.722031949266, 11289.37908415045, 11664.125190610908, 11678.766424671288, 11111.499982500645, 11204.159688023268, 11317.377544235145, 11369.31784092957, 11572.479238434122, 11392.430378480876, 11505.367204803817, 11577.826332605186, 11644.88355116472, 11926.582569610022, 9904.158146557624, 11849.644510664693, 12000.75998480043, 12321.0, 12352.876471235531, 11325.660230193107, 11327.830082548604, 11465.770684586429, 11698.883011169779, 11710.355930423908], "value": 9382.32271302802, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1437.3675582743595, 1467.5083846911286, 1605.919704014799, 1743.058748275931, 1781.8129096444882, 7510.849783004337, 7786.3381612563, 7929.603519824007, 8424.283935865458, 8448.535330556817, 8041.236082572161, 8271.586420678972, 8321.209485098922, 8383.622736976835, 8414.83170336593, 1953.4042117154263, 2290.209877592228, 2307.1694190091553, 2320.373499155227, 2331.3472227776165, 2324.337563794319, 2347.9882600586975, 2359.398353419884, 2368.27767530903, 2369.57347677418, 2310.699609050821, 2320.5010922651627, 2332.9385129766, 2333.6499525071204, 2333.9183128590503, 2288.7367952685404, 2299.6320588705894, 2309.515001849614, 2315.2591170825344, 2317.629179331307, 1909.484439201408, 2321.814254859607, 2338.8830558472177, 2339.7660233976467, 2344.753800850903, 8325.377506085564, 8356.916430835674, 8403.41176117672, 8416.87374689374, 8451.619677114482, 5040.143175660147, 5153.871153221162, 5202.141646628302, 5207.427183009866, 5221.477852214786, 3269.7711160218796, 3299.9835000825083, 3562.9667396454997, 3564.215872508044, 3568.107973006748, 1010.6664800615779, 1019.6788011776267, 1047.0524175621076, 1069.3049517813406, 1084.951177197028, 10298.867124616212, 10916.181286403551, 10951.507182176822, 11020.669379918616, 11034.0, 3833.8083095845654, 4079.3676980068135, 4177.227212965611, 4665.0, 4763.309320148559, 7866.960665196638, 9417.293702972305, 9431.104045115702, 9433.48115853621, 9452.716418507456, 5190.740462976836, 5191.92212116811, 5204.9219261711105, 5217.060929032735, 5236.659617124875, 4242.639375653104, 4568.771561421916, 4743.762811859462, 4784.904301914012, 4806.95193048072, 3122.469180239365, 3517.2086280586664, 3836.6930645548846, 3887.630675085862, 3936.980315098406, 1083.2254937719567, 1255.2656695832857, 1280.795072788368, 1287.1118927939692, 1291.593148158346, 652.8204743695427, 747.8653842308328, 885.9025507194203, 894.1996912763041, 908.5548081440047, 5493.505584497414, 5538.86152846174, 5586.608937374426, 5622.353429355595, 5730.1404789281105, 372.87695060629926, 439.8944253379166, 452.96271538177206, 455.83362072843175, 459.3844248706749, 3812.1803812180515, 3835.463035175078, 3853.246772718406, 3869.187470631199, 3928.7839168845417, 9793.853092203623, 10081.546330415149, 10144.644937427154, 10197.082262596401, 10203.7449063774, 10043.648472303434, 10156.796864062677, 10167.23745719074, 10179.541920613443, 10182.032706892833, 7699.422543309274, 7734.839774033828, 7840.960795196102, 7911.643976021093, 7924.643391047417, 5482.040642887399, 5535.308086489189, 5560.165975103767, 5590.692511911721, 5631.88736225265, 9297.86053209216, 9338.066193380608, 9492.667756628762, 9514.0, 9553.283503737108, 9425.19885809714, 9459.0, 9473.526323683926, 9569.856452153084, 9617.663381781604, 6507.0, 6733.562318449481, 6888.242293347627, 7113.359797618239, 7186.640667966685, 6927.1687397510605, 7561.130469996021, 8013.559254241072, 8016.759497215093, 8036.316913062456, 7043.577385356895, 7213.422926165789, 7224.385927196037, 7232.855342893218, 7300.379467745089, 5851.297844258716, 6781.355771201728, 6963.61700106499, 7037.683304251424, 7052.294770522906, 6551.5413921026975, 6604.471642268511, 6682.498812588978, 6707.631080290631, 6746.696398661974, 4813.0, 4816.759162041954, 4849.466558678472, 4879.0, 4891.486393928586, 4777.808887644455, 4799.328094066903, 4899.681520701143, 4950.356733024028, 4964.6524743269065], "value": 4296.927692361197, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.4517141730593, 306.66267106183216, 313.32634835104534, 314.60831265074984, 316.2788841441514, 4686.367340409044, 4777.665563410558, 4894.486078961707, 5344.839654810352, 5674.716264186794, 3862.478565393671, 4633.721976681401, 5293.57651387889, 5512.862178445541, 5541.420695101897, 4187.455630767996, 4190.497140343148, 4194.895127621819, 4212.894677633053, 4235.915281694364, 3307.065366760448, 3511.5610548681416, 4005.9799701001452, 4097.795110244476, 4227.450431443908, 3169.857356418967, 4157.376393540978, 4162.542120366757, 4164.625183733463, 4172.937405938914, 3767.05823544114, 3993.5008123984503, 4135.710500264968, 4137.97931010346, 4143.606357396043, 2967.896123635662, 3419.991102624732, 3629.2922880038404, 3664.1572438339063, 3665.560132784083, 5123.410807757118, 5206.427292997805, 5293.020791153649, 5334.946650533484, 5366.624336296461, 3747.5877653458097, 4222.91554168918, 4700.4041907940455, 4793.9280910786365, 4837.492063333335, 4758.857234282942, 5080.669756465818, 5597.870331986468, 5758.62568933018, 5852.648841069549, 3919.6085389686823, 3945.6251656092627, 4041.6968727345566, 4062.289099407592, 4063.8780836574656, 5294.285271488338, 5557.972210138963, 6040.942835003857, 6080.331163572004, 6114.357992410777, 3349.882754103595, 4044.988752811797, 4090.363854458181, 4103.261412945699, 4128.277551428488, 3985.0, 4105.014796448891, 4134.565870583546, 4137.710360274812, 4154.688398370135, 3989.401589761559, 4800.135975524369, 4805.639577031667, 4824.324594556765, 4828.03439312139, 5594.468525490072, 5794.449527294901, 5833.50415214703, 5856.950067476434, 5878.3540608629655, 1111.9388433636227, 1160.7504386556761, 1278.7889998150388, 1314.6450458376185, 1317.8798021681685, 842.6966292134827, 888.6223355074068, 918.2195134136065, 922.3773952582114, 924.4746168821498, 4650.395448591729, 4981.651284410056, 4991.401031876198, 5023.844515761395, 5217.426083130853, 5419.430959749169, 5695.241140847021, 5706.087026075808, 5710.486056254957, 5718.485336319668, 4444.799984000728, 5026.766809156693, 5059.418166910778, 5204.661696989683, 5216.295800067019, 2373.4303767096117, 2916.606258155122, 2936.7518804508427, 2937.6621688506098, 2966.8664910079096, 5032.7483625819295, 5657.60396772222, 5778.826635200867, 5834.358220595731, 5853.560982926298, 5218.0, 5390.4879036491475, 5646.350669672958, 5786.768529258867, 6110.663913484801, 5334.786608535693, 6318.241810982711, 6349.746010159542, 6366.694827560339, 6376.489880809524, 5608.943910561005, 6154.507639388928, 6155.799619074238, 6188.781833446673, 6212.844678883064, 5434.456554344534, 6036.396360363929, 6071.574989750675, 6074.90887636677, 6081.27024757032, 5016.874578135576, 6224.813255602339, 6328.240611126695, 6343.938220078585, 6408.871822563429, 3642.726795490296, 3897.103666156743, 3899.220155968874, 3948.3485224937563, 4009.699272554512, 5784.855378615568, 5980.970095149496, 6081.817545473643, 6130.762271770861, 6131.877362452637, 4743.905121897612, 5159.742012899265, 5348.154886564129, 5726.055200891807, 5911.438413350725, 5027.371578552681, 5607.523360514402, 5937.1204965592515, 5941.346451890375, 5963.719954025182, 5442.346918369821, 5672.7163641816915, 5714.999875021937, 5762.884742305214, 5842.152887831156, 5856.443637854397, 5887.0, 5887.264091988502, 5919.289685237799, 5929.99190137669, 5208.427073021889, 5523.917141242804, 5585.944140558542, 5789.334226563999, 5817.389174136655], "value": 4179.099677305696, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.4190944331216, 700.4676445901117, 723.3056265984654, 746.8692978728725, 784.6900474312648, 13796.0, 14315.069520481162, 14717.337719802608, 14765.0, 14805.925970370134, 13316.20102793833, 13492.392842322095, 13504.864951350484, 13518.053736238468, 13760.724785504284, 668.6924014953103, 819.6106849246605, 839.2991851803721, 840.9159084091573, 843.5149788871393, 680.6766785776741, 814.0313027497289, 831.8752187171942, 839.630562552475, 845.3997661660229, 827.0695467598952, 876.5310558851016, 877.2499512916428, 886.6630680341461, 899.3299991506337, 729.5258082246529, 854.6196942360652, 859.9914000859974, 870.9390342676015, 871.7907702151501, 606.2755007765721, 627.1721327847243, 639.833643252753, 643.4498503779248, 653.0595941843777, 11537.826932596017, 13776.972688908336, 13841.515546955809, 13967.511137110254, 13996.370163342674, 10903.618373356974, 12602.306873121976, 13403.664908377272, 13550.390232439564, 13684.52104176349, 10825.350478971208, 10849.67450976472, 11154.609588664358, 11238.438078096062, 11582.594609188722, 3483.7332695031096, 3602.3695853225845, 3690.4833323334756, 3690.907727306812, 3761.887143385703, 89891.55054224753, 92210.61684074745, 92328.53835730845, 92754.0, 96930.03069969281, 10180.389176649425, 10382.948085259677, 10493.370397776158, 10502.579896804195, 10572.629957951589, 20245.08897099634, 20797.89601051985, 21126.0, 21295.574088518144, 21334.35996920095, 7897.921020789719, 7903.446758726834, 7918.643661035152, 8089.514629122272, 8103.270705636521, 3493.9650603494147, 4282.807273672693, 4291.055967687166, 4297.505786834554, 4306.849260275875, 3442.208292092833, 3580.373434648926, 3582.2119133790525, 3589.407747721649, 3598.5321908151773, 649.5226008883467, 659.1957811469999, 659.3571268013649, 660.7753363856312, 669.5380187670589, 548.1271496397034, 565.7143142712904, 585.1281590430314, 626.2078470734524, 630.1713247080075, 6620.602763834186, 7611.619419029026, 9392.765180870396, 9413.482258475713, 9422.858657120149, 620.2742790934617, 625.8310256230792, 633.4330773957374, 647.9481641468672, 663.4559661077966, 3872.457855900176, 4322.178786030644, 4540.123640845213, 4549.977250113794, 4585.9541404585525, 97339.51330243303, 97583.0, 100347.49826250822, 100980.49509752404, 115494.0, 93884.53057734668, 95543.04456955341, 99831.00168998216, 101014.0, 108672.0, 90089.0, 90548.09451905396, 90911.0, 91611.0, 92273.53863230642, 9350.485723285285, 9686.806263874541, 9977.650782222587, 10003.099721025143, 10003.949980250052, 47616.7619161902, 52326.73836630792, 56604.71697641486, 57673.0, 58141.0, 61320.69339653273, 74646.88029679668, 77631.0, 78351.0, 80236.0, 12424.689382765142, 12962.0, 13084.934575327063, 13458.932705336802, 13487.595372138852, 13935.512257070954, 13973.161610303414, 13979.930100349433, 14235.359408826835, 14380.784288235476, 12259.877401226231, 13109.0, 13159.276239806903, 13782.86217137856, 13873.306334683024, 12943.482260709467, 13087.738245235232, 13141.0, 13254.734905302033, 13697.863021369658, 12796.232226066466, 13134.671633209246, 13350.532731354357, 13366.264855433044, 13467.797983030458, 8203.83592328162, 8614.069513723412, 9346.0653934606, 10864.619738309122, 11449.942750286194, 9045.412048216846, 9728.610855565697, 11425.257358271685, 11454.770904582028, 11481.885181148416], "value": 7274.759063598485, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1561.2999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 118.0, 115.0, 117.0, 118.0, 117.0, 118.0, 115.0, 120.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 111.0, 113.0, 154.0, 104.0, 171.0, 148.0, 112.0, 137.0, 110.0], "value": 130.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 232.0, 199.0, 216.0, 227.0, 198.0, 221.0, 283.0, 206.0, 198.0], "value": 217.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 61.0, 85.0, 60.0, 59.0, 64.0, 50.0, 84.0, 77.0], "value": 68.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 91.0, 74.0, 70.0, 95.0, 87.0, 70.0, 70.0, 89.0], "value": 80.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 133.0, 87.0, 85.0, 89.0, 86.0, 89.0, 93.0, 89.0, 75.0], "value": 96.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 105.0, 102.0, 170.0, 172.0, 98.0, 99.0, 100.0, 99.0, 99.0], "value": 114.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 91.0, 102.0, 132.0, 89.0, 88.0, 90.0, 90.0, 88.0, 88.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 58.0, 60.0, 62.0, 59.0, 60.0, 60.0, 58.0, 58.0], "value": 58.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 72.0, 82.0, 74.0, 72.0, 59.0, 105.0, 109.0, 109.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 85.0, 83.0, 83.0, 85.0, 83.0, 84.0, 84.0, 81.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 161.0, 141.0, 135.0, 173.0, 158.0, 135.0, 172.0, 268.0, 172.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 174.0, 144.0, 217.0, 203.0, 165.0, 166.0, 169.0, 166.0], "value": 173.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 91.0, 93.0, 88.0, 71.0, 66.0, 68.0, 68.0, 68.0, 68.0], "value": 76.1, "unit": "score"}]}]} 03:18:32 INFO - Return code: 0 03:18:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:18:32 ERROR - # TBPL SUCCESS # 03:18:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:18:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:18:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:18:32 INFO - 2016-12-17 03:18:32.799 screenresolution[1680:11998] starting screenresolution argv=screenresolution get 03:18:32 INFO - 2016-12-17 03:18:32.802 screenresolution[1680:11998] Display 0: 1600x1200x32@60 03:18:32 INFO - 2016-12-17 03:18:32.808 screenresolution[1681:12001] starting screenresolution argv=screenresolution list 03:18:32 INFO - Available Modes on Display 0 03:18:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:18:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:18:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:18:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:18:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:18:33 INFO - Intel Iris: 03:18:33 INFO - Chipset Model: Intel Iris 03:18:33 INFO - Type: GPU 03:18:33 INFO - Bus: Built-In 03:18:33 INFO - VRAM (Dynamic, Max): 1536 MB 03:18:33 INFO - Vendor: Intel (0x8086) 03:18:33 INFO - Device ID: 0x0a2e 03:18:33 INFO - Revision ID: 0x0009 03:18:33 INFO - Displays: 03:18:33 INFO - Display: 03:18:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:18:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:18:33 INFO - Display Serial Number: 1600x1200 60 03:18:33 INFO - Main Display: Yes 03:18:33 INFO - Mirror: Off 03:18:33 INFO - Online: Yes 03:18:33 INFO - Rotation: Supported 03:18:33 INFO - Return code: 0 03:18:33 INFO - Running post-action listener: _resource_record_post_action 03:18:33 INFO - [mozharness: 2016-12-17 11:18:33.060063Z] Finished run-tests step (success) 03:18:33 INFO - Running post-run listener: _resource_record_post_run 03:18:33 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 33865728; Write bytes: 660991488; Read time: 208; Write time: 1964 03:18:33 INFO - TinderboxPrint: CPU usage
54.3% 03:18:33 INFO - TinderboxPrint: I/O read bytes / time
33,865,728 / 208 03:18:33 INFO - TinderboxPrint: I/O write bytes / time
660,991,488 / 1,964 03:18:33 INFO - TinderboxPrint: CPU idle
2,214.0 (45.7%) 03:18:33 INFO - TinderboxPrint: CPU system
976.8 (20.2%) 03:18:33 INFO - TinderboxPrint: CPU user
1,651.0 (34.1%) 03:18:33 INFO - TinderboxPrint: Swap in / out
378,753,024 / 0 03:18:33 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 347851264; Write bytes: 346555392; Read time: 27063; Write time: 747 03:18:33 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 31776768; Write bytes: 307284480; Read time: 189; Write time: 1189 03:18:33 INFO - Running post-run listener: _upload_blobber_files 03:18:33 INFO - Blob upload gear active. 03:18:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:18:33 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 03:18:33 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'] 03:18:33 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 03:18:33 INFO - (blobuploader) - INFO - Open directory for files ... 03:18:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:18:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:18:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:18:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:18:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:18:35 INFO - (blobuploader) - INFO - Done attempting. 03:18:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:18:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:18:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:18:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:18:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:18:36 INFO - (blobuploader) - INFO - Done attempting. 03:18:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:18:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:18:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:18:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:18:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:18:38 INFO - (blobuploader) - INFO - Done attempting. 03:18:38 INFO - (blobuploader) - INFO - Iteration through files over. 03:18:38 INFO - Return code: 0 03:18:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:18:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:18:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b4e85178b69190c4bc21b3de63346096108f67f8d49e666e7ddb46da066e570dfe3b23369833abfa34ba1eb7f4ca0e91612a8d20dd37cd70cbebe1ebc2d01d74", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/955ca8de60e90110bf409320502d1bee31f639c50b16ec5a4e26c5a34c65c81bd0cf467dce8c251bd1d95863b9622f42e15e8dd103291c69416af2aa77187c44", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7485461f9294c0c1184b2e9dc1203c02701aff5c2effbb98d7b368f976ea68d5fac6a06c0acd2dda9b4b73a8226878267b6e58fc31fc846861520d6fff7c212c"} 03:18:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:18:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:18:38 INFO - Contents: 03:18:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b4e85178b69190c4bc21b3de63346096108f67f8d49e666e7ddb46da066e570dfe3b23369833abfa34ba1eb7f4ca0e91612a8d20dd37cd70cbebe1ebc2d01d74", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/955ca8de60e90110bf409320502d1bee31f639c50b16ec5a4e26c5a34c65c81bd0cf467dce8c251bd1d95863b9622f42e15e8dd103291c69416af2aa77187c44", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7485461f9294c0c1184b2e9dc1203c02701aff5c2effbb98d7b368f976ea68d5fac6a06c0acd2dda9b4b73a8226878267b6e58fc31fc846861520d6fff7c212c"} 03:18:38 INFO - Running post-run listener: copy_logs_to_upload_dir 03:18:38 INFO - Copying logs to upload dir... 03:18:38 INFO - mkdir: /builds/slave/test/build/upload/logs 03:18:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.349198 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-17 03:18:38.285512) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-17 03:18:38.288732) ========= 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.mzyp8fHBgk/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.Z2CXzebsE5/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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/b4e85178b69190c4bc21b3de63346096108f67f8d49e666e7ddb46da066e570dfe3b23369833abfa34ba1eb7f4ca0e91612a8d20dd37cd70cbebe1ebc2d01d74", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/955ca8de60e90110bf409320502d1bee31f639c50b16ec5a4e26c5a34c65c81bd0cf467dce8c251bd1d95863b9622f42e15e8dd103291c69416af2aa77187c44", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7485461f9294c0c1184b2e9dc1203c02701aff5c2effbb98d7b368f976ea68d5fac6a06c0acd2dda9b4b73a8226878267b6e58fc31fc846861520d6fff7c212c"} build_url:https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011224 build_url: 'https://queue.taskcluster.net/v1/task/eP7hX88ZQxySjK7AFgKOkg/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/b4e85178b69190c4bc21b3de63346096108f67f8d49e666e7ddb46da066e570dfe3b23369833abfa34ba1eb7f4ca0e91612a8d20dd37cd70cbebe1ebc2d01d74", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/955ca8de60e90110bf409320502d1bee31f639c50b16ec5a4e26c5a34c65c81bd0cf467dce8c251bd1d95863b9622f42e15e8dd103291c69416af2aa77187c44", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7485461f9294c0c1184b2e9dc1203c02701aff5c2effbb98d7b368f976ea68d5fac6a06c0acd2dda9b4b73a8226878267b6e58fc31fc846861520d6fff7c212c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-17 03:18:38.322453) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 03:18:38.322777) ========= 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.mzyp8fHBgk/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.Z2CXzebsE5/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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.005216 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 03:18:38.371219) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-17 03:18:38.371545) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-17 03:18:38.371896) ========= ========= Total master_lag: 0.18 =========