builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0127 starttime: 1479149045.79 results: success (0) buildid: 20161114074144 builduid: c0ce765cf2c74afa85cbf7cb62a44e31 revision: 8cdff71808a0e11361bca7f218658c4343c730ca ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 10:44:05.788291) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 10:44:05.788766) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 10:44:05.789080) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 10:44:05.812486) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:44:05.812883) ========= 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.PJPikLXMPN/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.c1H112gSAW/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-14 10:44:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 153M=0s 2016-11-14 10:44:05 (153 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.086119 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:44:05.920629) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:44:05.923995) ========= 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.PJPikLXMPN/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.c1H112gSAW/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.020404 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:44:05.972345) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-14 10:44:05.972687) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8cdff71808a0e11361bca7f218658c4343c730ca --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 8cdff71808a0e11361bca7f218658c4343c730ca --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PJPikLXMPN/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.c1H112gSAW/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-14 10:44:06,055 truncating revision to first 12 chars 2016-11-14 10:44:06,055 Setting DEBUG logging. 2016-11-14 10:44:06,055 attempt 1/10 2016-11-14 10:44:06,055 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8cdff71808a0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 10:44:08,271 unpacking tar archive at: mozilla-aurora-8cdff71808a0/testing/mozharness/ program finished with exit code 0 elapsedTime=2.602972 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-14 10:44:08.600808) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 10:44:08.601133) ========= script_repo_revision: 8cdff71808a0e11361bca7f218658c4343c730ca ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 10:44:08.601509) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 10:44:08.601773) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 10:44:08.620109) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-11-14 10:44:08.620408) ========= /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.PJPikLXMPN/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.c1H112gSAW/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:44:08 INFO - MultiFileLogger online at 20161114 10:44:08 in /builds/slave/test 10:44:08 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 10:44:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:44:08 INFO - {'append_to_log': False, 10:44:08 INFO - 'base_work_dir': '/builds/slave/test', 10:44:08 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:44:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:44:08 INFO - 'branch': 'Mozilla-Aurora', 10:44:08 INFO - 'buildbot_json_path': 'buildprops.json', 10:44:08 INFO - 'config_files': ('talos/mac_config.py',), 10:44:08 INFO - 'default_actions': ('clobber', 10:44:08 INFO - 'read-buildbot-config', 10:44:08 INFO - 'download-and-extract', 10:44:08 INFO - 'populate-webroot', 10:44:08 INFO - 'create-virtualenv', 10:44:08 INFO - 'install', 10:44:08 INFO - 'run-tests'), 10:44:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:44:08 INFO - 'download_minidump_stackwalk': True, 10:44:08 INFO - 'download_symbols': 'ondemand', 10:44:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:44:08 INFO - 'tooltool.py': '/tools/tooltool.py', 10:44:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:44:08 INFO - '/tools/misc-python/virtualenv.py')}, 10:44:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:44:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:44:08 INFO - 'installer_path': 'installer.exe', 10:44:08 INFO - 'log_level': 'info', 10:44:08 INFO - 'log_name': 'talos', 10:44:08 INFO - 'log_to_console': True, 10:44:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:44:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:44:08 INFO - 'opt_config_files': (), 10:44:08 INFO - 'pip_index': False, 10:44:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:44:08 INFO - 'cmd': ('bash', 10:44:08 INFO - '-c', 10:44:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:44:08 INFO - 'enabled': True, 10:44:08 INFO - 'halt_on_failure': False, 10:44:08 INFO - 'name': 'check_screen_resolution'},), 10:44:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:44:08 INFO - 'cmd': ('bash', 10:44:08 INFO - '-c', 10:44:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:44:08 INFO - 'enabled': True, 10:44:08 INFO - 'halt_on_failure': False, 10:44:08 INFO - 'name': 'check_screen_resolution'},), 10:44:08 INFO - 'run_cmd_checks_enabled': True, 10:44:08 INFO - 'sps_profile': False, 10:44:08 INFO - 'sps_profile_interval': 0, 10:44:08 INFO - 'suite': 'dromaeojs-e10s', 10:44:08 INFO - 'system_bits': '32', 10:44:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:44:08 INFO - 'title': 't-yosemite-r7-0127', 10:44:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:44:08 INFO - 'use_talos_json': True, 10:44:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:44:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:44:08 INFO - 'work_dir': 'build'} 10:44:08 INFO - [mozharness: 2016-11-14 18:44:08.825016Z] Running clobber step. 10:44:08 INFO - Running pre-action listener: _resource_record_pre_action 10:44:08 INFO - Running main action method: clobber 10:44:08 INFO - rmtree: /builds/slave/test/build 10:44:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:44:09 INFO - Running post-action listener: _resource_record_post_action 10:44:09 INFO - [mozharness: 2016-11-14 18:44:09.171389Z] Finished clobber step (success) 10:44:09 INFO - [mozharness: 2016-11-14 18:44:09.171536Z] Running read-buildbot-config step. 10:44:09 INFO - Running pre-action listener: _resource_record_pre_action 10:44:09 INFO - Running main action method: read_buildbot_config 10:44:09 INFO - Using buildbot properties: 10:44:09 INFO - { 10:44:09 INFO - "project": "", 10:44:09 INFO - "product": "firefox", 10:44:09 INFO - "script_repo_revision": "production", 10:44:09 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 10:44:09 INFO - "repository": "", 10:44:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 10:44:09 INFO - "buildid": "20161114074144", 10:44:09 INFO - "pgo_build": "False", 10:44:09 INFO - "basedir": "/builds/slave/test", 10:44:09 INFO - "buildnumber": 453, 10:44:09 INFO - "slavename": "t-yosemite-r7-0127", 10:44:09 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:44:09 INFO - "platform": "yosemite_r7", 10:44:09 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:44:09 INFO - "branch": "mozilla-aurora", 10:44:09 INFO - "repo_path": "releases/mozilla-aurora", 10:44:09 INFO - "revision": "8cdff71808a0e11361bca7f218658c4343c730ca", 10:44:09 INFO - "stage_platform": "macosx64", 10:44:09 INFO - "builduid": "c0ce765cf2c74afa85cbf7cb62a44e31", 10:44:09 INFO - "slavebuilddir": "test" 10:44:09 INFO - } 10:44:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 10:44:09 INFO - Running post-action listener: _resource_record_post_action 10:44:09 INFO - [mozharness: 2016-11-14 18:44:09.174827Z] Finished read-buildbot-config step (success) 10:44:09 INFO - [mozharness: 2016-11-14 18:44:09.174887Z] Running download-and-extract step. 10:44:09 INFO - Running pre-action listener: _resource_record_pre_action 10:44:09 INFO - Running main action method: download_and_extract 10:44:09 INFO - mkdir: /builds/slave/test/build/tests 10:44:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:09 INFO - trying https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 10:44:09 INFO - Downloading https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/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 10:44:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/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 10:44:10 INFO - Downloaded 1199 bytes. 10:44:10 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 10:44:10 INFO - Using the following test package requirements: 10:44:10 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:44:10 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:44:10 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 10:44:10 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:44:10 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 10:44:10 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:44:10 INFO - u'jsshell-mac.zip'], 10:44:10 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:44:10 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 10:44:10 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:44:10 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:44:10 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 10:44:10 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:44:10 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 10:44:10 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:44:10 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 10:44:10 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:44:10 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 10:44:10 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:44:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:44:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:44:10 INFO - Fetch https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:44:10 INFO - Http code: 200 10:44:10 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 10:44:10 INFO - x-amz-cf-id: x2weHOy_6PCG7e5t8EnIVevxyUv0Y5Ppb7C3eJp16btQ7-qa7o1c_A== 10:44:10 INFO - x-amz-version-id: qEZpoxVrb8K_lOtz8d1xXpN4NyRiiL2Q 10:44:10 INFO - Expected file size: 16327509 10:44:10 INFO - Obtained file size: 16327509 10:44:17 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 10:44:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:44:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:44:17 INFO - Fetch https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:44:18 INFO - Http code: 200 10:44:18 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 10:44:18 INFO - x-amz-cf-id: BhNvXXOMeGhWL6W4K8E60MtufGaCmBzU65f7evnPm1rOodfzkuBdCA== 10:44:18 INFO - x-amz-version-id: qEZpoxVrb8K_lOtz8d1xXpN4NyRiiL2Q 10:44:18 INFO - Expected file size: 16327509 10:44:18 INFO - Obtained file size: 16327509 10:44:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 10:44:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:44:24 INFO - Fetch https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 10:44:25 INFO - Http code: 200 10:44:25 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 10:44:25 INFO - x-amz-cf-id: JSsSgjCbxow4yqT1q-HzYe1eUFkdv8DwL_Dt9pMF3A7dB2yFL1LVuw== 10:44:25 INFO - x-amz-version-id: jXsupoNyJ.YzlDUqzWygU8BmOV_JFB8G 10:44:25 INFO - Expected file size: 14409038 10:44:25 INFO - Obtained file size: 14409038 10:44:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:25 INFO - trying https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:44:25 INFO - Downloading https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 10:44:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/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 10:44:28 INFO - Downloaded 114362341 bytes. 10:44:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:44:28 INFO - mkdir: /builds/slave/test/properties 10:44:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:44:28 INFO - Writing to file /builds/slave/test/properties/build_url 10:44:28 INFO - Contents: 10:44:28 INFO - build_url:https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:44:29 INFO - Running post-action listener: _resource_record_post_action 10:44:29 INFO - Running post-action listener: set_extra_try_arguments 10:44:29 INFO - [mozharness: 2016-11-14 18:44:29.427694Z] Finished download-and-extract step (success) 10:44:29 INFO - [mozharness: 2016-11-14 18:44:29.427812Z] Running populate-webroot step. 10:44:29 INFO - Running pre-action listener: _resource_record_pre_action 10:44:29 INFO - Running main action method: populate_webroot 10:44:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:44:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:44:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:44:29 INFO - '--tppagecycles', 10:44:29 INFO - '7'], 10:44:29 INFO - 'tests': ['tsvgm']}}, 10:44:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:44:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:44:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:44:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:44:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:44:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:44:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:44:29 INFO - 'talos_options': ['--disable-e10s'], 10:44:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:44:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:44:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:44:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:44:29 INFO - 'talos_options': ['--disable-e10s'], 10:44:29 INFO - 'tests': ['damp', 'tps']}, 10:44:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:44:29 INFO - 'tests': ['damp', 'tps']}, 10:44:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:44:29 INFO - 'tests': ['dromaeo_dom']}, 10:44:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:44:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:44:29 INFO - 'tests': ['basic_compositor_video']}, 10:44:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:44:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:44:29 INFO - 'tests': ['a11yr', 10:44:29 INFO - 'ts_paint', 10:44:29 INFO - 'tpaint', 10:44:29 INFO - 'sessionrestore', 10:44:29 INFO - 'sessionrestore_no_auto_restore', 10:44:29 INFO - 'tabpaint']}, 10:44:29 INFO - 'other-e10s': {'tests': ['a11yr', 10:44:29 INFO - 'ts_paint', 10:44:29 INFO - 'tpaint', 10:44:29 INFO - 'sessionrestore', 10:44:29 INFO - 'sessionrestore_no_auto_restore', 10:44:29 INFO - 'tabpaint']}, 10:44:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:44:29 INFO - 'ts_paint', 10:44:29 INFO - 'tpaint', 10:44:29 INFO - 'sessionrestore', 10:44:29 INFO - 'sessionrestore_no_auto_restore', 10:44:29 INFO - 'tabpaint']}, 10:44:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:44:29 INFO - 'ts_paint', 10:44:29 INFO - 'tpaint', 10:44:29 INFO - 'sessionrestore', 10:44:29 INFO - 'sessionrestore_no_auto_restore', 10:44:29 INFO - 'tabpaint']}, 10:44:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:44:29 INFO - 'tests': ['a11yr', 10:44:29 INFO - 'ts_paint', 10:44:29 INFO - 'tpaint', 10:44:29 INFO - 'sessionrestore', 10:44:29 INFO - 'sessionrestore_no_auto_restore', 10:44:29 INFO - 'tabpaint']}, 10:44:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:44:29 INFO - 'tests': ['a11yr', 10:44:29 INFO - 'ts_paint', 10:44:29 INFO - 'tpaint', 10:44:29 INFO - 'sessionrestore', 10:44:29 INFO - 'sessionrestore_no_auto_restore', 10:44:29 INFO - 'tabpaint']}, 10:44:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:44:29 INFO - 'tests': ['tsvgx', 10:44:29 INFO - 'tsvgr_opacity', 10:44:29 INFO - 'tart', 10:44:29 INFO - 'tscrollx', 10:44:29 INFO - 'cart']}, 10:44:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:44:29 INFO - 'tsvgr_opacity', 10:44:29 INFO - 'tart', 10:44:29 INFO - 'tscrollx', 10:44:29 INFO - 'cart']}, 10:44:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:44:29 INFO - 'talos_options': ['--disable-e10s'], 10:44:29 INFO - 'tests': ['tp5o']}, 10:44:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:44:29 INFO - 'tests': ['tp5o']}, 10:44:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:44:29 INFO - 'talos_options': ['--disable-e10s', 10:44:29 INFO - '--xperf_path', 10:44:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:44:29 INFO - 'tests': ['tp5n']}, 10:44:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:44:29 INFO - 'talos_options': ['--xperf_path', 10:44:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:44:29 INFO - 'tests': ['tp5n']}}, 10:44:29 INFO - 'talos.zip': {'path': '', 10:44:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:44:29 INFO - Running post-action listener: _resource_record_post_action 10:44:29 INFO - [mozharness: 2016-11-14 18:44:29.446346Z] Finished populate-webroot step (success) 10:44:29 INFO - [mozharness: 2016-11-14 18:44:29.446489Z] Running create-virtualenv step. 10:44:29 INFO - Running pre-action listener: _resource_record_pre_action 10:44:29 INFO - Running main action method: create_virtualenv 10:44:29 INFO - Creating virtualenv /builds/slave/test/build/venv 10:44:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:44:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:44:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:44:29 INFO - Using real prefix '/tools/python27' 10:44:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:44:30 INFO - Installing distribute.............................................................................................................................................................................................done. 10:44:33 INFO - Installing pip.................done. 10:44:33 INFO - Return code: 0 10:44:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:44:33 INFO - Reading from file tmpfile_stdout 10:44:33 INFO - Output received: 10:44:33 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:44:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:44:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1085c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108539570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f6a5c7650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d7d30>, '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 0x10823ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083cfb20>, '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.PJPikLXMPN/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.c1H112gSAW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:44:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:44:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJPikLXMPN/Render', 10:44:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:33 INFO - 'HOME': '/Users/cltbld', 10:44:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:33 INFO - 'LOGNAME': 'cltbld', 10:44:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:33 INFO - 'MOZ_NO_REMOTE': '1', 10:44:33 INFO - 'NO_EM_RESTART': '1', 10:44:33 INFO - 'PAGER': '/bin/cat', 10:44:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:33 INFO - 'PWD': '/builds/slave/test', 10:44:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:33 INFO - 'SHELL': '/bin/bash', 10:44:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1H112gSAW/Listeners', 10:44:33 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 10:44:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:33 INFO - 'USER': 'cltbld', 10:44:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:33 INFO - 'XPC_FLAGS': '0x0', 10:44:33 INFO - 'XPC_SERVICE_NAME': '0', 10:44:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:33 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:44:33 INFO - Cleaning up... 10:44:33 INFO - Return code: 0 10:44:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:44:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1085c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108539570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f6a5c7650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d7d30>, '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 0x10823ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083cfb20>, '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.PJPikLXMPN/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.c1H112gSAW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:44:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:44:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJPikLXMPN/Render', 10:44:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:33 INFO - 'HOME': '/Users/cltbld', 10:44:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:33 INFO - 'LOGNAME': 'cltbld', 10:44:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:33 INFO - 'MOZ_NO_REMOTE': '1', 10:44:33 INFO - 'NO_EM_RESTART': '1', 10:44:33 INFO - 'PAGER': '/bin/cat', 10:44:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:33 INFO - 'PWD': '/builds/slave/test', 10:44:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:33 INFO - 'SHELL': '/bin/bash', 10:44:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1H112gSAW/Listeners', 10:44:33 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 10:44:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:33 INFO - 'USER': 'cltbld', 10:44:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:33 INFO - 'XPC_FLAGS': '0x0', 10:44:33 INFO - 'XPC_SERVICE_NAME': '0', 10:44:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:33 INFO - Downloading/unpacking psutil>=3.1.1 10:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:44:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:44:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:44:37 INFO - Installing collected packages: psutil 10:44:37 INFO - Running setup.py install for psutil 10:44:37 INFO - building 'psutil._psutil_osx' extension 10:44:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:44:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:44:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:44:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:44:37 INFO - building 'psutil._psutil_posix' extension 10:44:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:44:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:44:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:44:37 INFO - ^ 10:44:38 INFO - 1 warning generated. 10:44:38 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:44:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:44:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:44:38 INFO - Successfully installed psutil 10:44:38 INFO - Cleaning up... 10:44:38 INFO - Return code: 0 10:44:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:44:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:38 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 0x1085c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108539570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f6a5c7650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d7d30>, '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 0x10823ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083cfb20>, '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.PJPikLXMPN/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.c1H112gSAW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:44:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:44:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:44:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJPikLXMPN/Render', 10:44:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:38 INFO - 'HOME': '/Users/cltbld', 10:44:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:38 INFO - 'LOGNAME': 'cltbld', 10:44:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:38 INFO - 'MOZ_NO_REMOTE': '1', 10:44:38 INFO - 'NO_EM_RESTART': '1', 10:44:38 INFO - 'PAGER': '/bin/cat', 10:44:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:38 INFO - 'PWD': '/builds/slave/test', 10:44:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:38 INFO - 'SHELL': '/bin/bash', 10:44:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1H112gSAW/Listeners', 10:44:38 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 10:44:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:38 INFO - 'USER': 'cltbld', 10:44:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:38 INFO - 'XPC_FLAGS': '0x0', 10:44:38 INFO - 'XPC_SERVICE_NAME': '0', 10:44:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:44:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:44:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:44:41 INFO - Installing collected packages: mozsystemmonitor 10:44:41 INFO - Running setup.py install for mozsystemmonitor 10:44:41 INFO - Successfully installed mozsystemmonitor 10:44:41 INFO - Cleaning up... 10:44:41 INFO - Return code: 0 10:44:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1085c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108539570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f6a5c7650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d7d30>, '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 0x10823ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083cfb20>, '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.PJPikLXMPN/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.c1H112gSAW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:44:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:44:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:44:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJPikLXMPN/Render', 10:44:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:41 INFO - 'HOME': '/Users/cltbld', 10:44:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:41 INFO - 'LOGNAME': 'cltbld', 10:44:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:41 INFO - 'MOZ_NO_REMOTE': '1', 10:44:41 INFO - 'NO_EM_RESTART': '1', 10:44:41 INFO - 'PAGER': '/bin/cat', 10:44:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:41 INFO - 'PWD': '/builds/slave/test', 10:44:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:41 INFO - 'SHELL': '/bin/bash', 10:44:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1H112gSAW/Listeners', 10:44:41 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 10:44:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:41 INFO - 'USER': 'cltbld', 10:44:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:41 INFO - 'XPC_FLAGS': '0x0', 10:44:41 INFO - 'XPC_SERVICE_NAME': '0', 10:44:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:41 INFO - Downloading/unpacking jsonschema==2.5.1 10:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:44:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:44 INFO - Downloading functools32-3.2.3-2.tar.gz 10:44:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:44:44 INFO - warning: no files found matching '*.txt' 10:44:44 INFO - no previously-included directories found matching 'build' 10:44:44 INFO - no previously-included directories found matching 'dist' 10:44:44 INFO - no previously-included directories found matching '.git*' 10:44:44 INFO - Installing collected packages: jsonschema, functools32 10:44:44 INFO - Running setup.py install for functools32 10:44:44 INFO - warning: no files found matching '*.txt' 10:44:44 INFO - no previously-included directories found matching 'build' 10:44:44 INFO - no previously-included directories found matching 'dist' 10:44:44 INFO - no previously-included directories found matching '.git*' 10:44:44 INFO - Successfully installed jsonschema functools32 10:44:44 INFO - Cleaning up... 10:44:44 INFO - Return code: 0 10:44:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:44 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 0x1085c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108539570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f6a5c7650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d7d30>, '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 0x10823ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083cfb20>, '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.PJPikLXMPN/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.c1H112gSAW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:44:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:44:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:44:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJPikLXMPN/Render', 10:44:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:44 INFO - 'HOME': '/Users/cltbld', 10:44:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:44 INFO - 'LOGNAME': 'cltbld', 10:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:44 INFO - 'MOZ_NO_REMOTE': '1', 10:44:44 INFO - 'NO_EM_RESTART': '1', 10:44:44 INFO - 'PAGER': '/bin/cat', 10:44:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:44 INFO - 'PWD': '/builds/slave/test', 10:44:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:44 INFO - 'SHELL': '/bin/bash', 10:44:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1H112gSAW/Listeners', 10:44:44 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 10:44:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:44 INFO - 'USER': 'cltbld', 10:44:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:44 INFO - 'XPC_FLAGS': '0x0', 10:44:44 INFO - 'XPC_SERVICE_NAME': '0', 10:44:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:44:45 INFO - Cleaning up... 10:44:45 INFO - Return code: 0 10:44:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:44: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')]} 10:44:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44: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')]} 10:44:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:45 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 0x1085c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108539570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f6a5c7650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d7d30>, '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 0x10823ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083cfb20>, '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.PJPikLXMPN/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.c1H112gSAW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:44:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:44:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:44:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJPikLXMPN/Render', 10:44:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:45 INFO - 'HOME': '/Users/cltbld', 10:44:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:45 INFO - 'LOGNAME': 'cltbld', 10:44:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:45 INFO - 'MOZ_NO_REMOTE': '1', 10:44:45 INFO - 'NO_EM_RESTART': '1', 10:44:45 INFO - 'PAGER': '/bin/cat', 10:44:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:45 INFO - 'PWD': '/builds/slave/test', 10:44:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:45 INFO - 'SHELL': '/bin/bash', 10:44:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1H112gSAW/Listeners', 10:44:45 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 10:44:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:45 INFO - 'USER': 'cltbld', 10:44:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:45 INFO - 'XPC_FLAGS': '0x0', 10:44:45 INFO - 'XPC_SERVICE_NAME': '0', 10:44:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:45 INFO - Downloading/unpacking blobuploader==1.2.4 10:44:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:48 INFO - Downloading blobuploader-1.2.4.tar.gz 10:44:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:44:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:44:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:49 INFO - Downloading docopt-0.6.1.tar.gz 10:44:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:44:49 INFO - Installing collected packages: blobuploader, requests, docopt 10:44:49 INFO - Running setup.py install for blobuploader 10:44:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:44:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:44:49 INFO - Running setup.py install for requests 10:44:49 INFO - Running setup.py install for docopt 10:44:50 INFO - Successfully installed blobuploader requests docopt 10:44:50 INFO - Cleaning up... 10:44:50 INFO - Return code: 0 10:44:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:50 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 0x1085c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108539570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f6a5c7650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d7d30>, '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 0x10823ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083cfb20>, '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.PJPikLXMPN/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.c1H112gSAW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:44:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:44:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:44:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJPikLXMPN/Render', 10:44:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:50 INFO - 'HOME': '/Users/cltbld', 10:44:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:50 INFO - 'LOGNAME': 'cltbld', 10:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:50 INFO - 'MOZ_NO_REMOTE': '1', 10:44:50 INFO - 'NO_EM_RESTART': '1', 10:44:50 INFO - 'PAGER': '/bin/cat', 10:44:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:50 INFO - 'PWD': '/builds/slave/test', 10:44:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:50 INFO - 'SHELL': '/bin/bash', 10:44:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1H112gSAW/Listeners', 10:44:50 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 10:44:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:50 INFO - 'USER': 'cltbld', 10:44:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:50 INFO - 'XPC_FLAGS': '0x0', 10:44:50 INFO - 'XPC_SERVICE_NAME': '0', 10:44:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:44:50 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-qnJXZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:44:50 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-WsO8Xb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:44:50 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Mebc99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:44:50 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-CYoea8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:44:50 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-7kdqoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:44:51 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-3z4FvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:44:51 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-kpGuhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:44:51 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-outlcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:44:51 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Jpx9vj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:44:51 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-GKGaaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:44:51 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-SsCaUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:44:51 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-xRle2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:44:51 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-999twR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:44:51 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-pUQMOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:44:51 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-l0xieI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:44:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:44:52 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-E801wG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:44:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:44:52 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-H53_4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:44:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:44:52 INFO - Running setup.py install for manifestparser 10:44:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:44:52 INFO - Running setup.py install for mozcrash 10:44:52 INFO - Running setup.py install for mozdebug 10:44:52 INFO - Running setup.py install for mozdevice 10:44:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:44:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:44:52 INFO - Running setup.py install for mozfile 10:44:52 INFO - Running setup.py install for mozhttpd 10:44:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:44:53 INFO - Running setup.py install for mozinfo 10:44:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:44:53 INFO - Running setup.py install for mozInstall 10:44:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:44:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:44:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:44:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:44:53 INFO - Running setup.py install for mozleak 10:44:53 INFO - Running setup.py install for mozlog 10:44:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:44:53 INFO - Running setup.py install for moznetwork 10:44:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:44:53 INFO - Running setup.py install for mozprocess 10:44:54 INFO - Running setup.py install for mozprofile 10:44:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:44:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:44:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:44:54 INFO - Running setup.py install for mozrunner 10:44:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:44:54 INFO - Running setup.py install for mozscreenshot 10:44:54 INFO - Running setup.py install for moztest 10:44:54 INFO - Running setup.py install for mozversion 10:44:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:44:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:44:54 INFO - Cleaning up... 10:44:54 INFO - Return code: 0 10:44:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:54 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 0x1085c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108539570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f6a5c7650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d7d30>, '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 0x10823ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083cfb20>, '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.PJPikLXMPN/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.c1H112gSAW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:44:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:44:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:44:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJPikLXMPN/Render', 10:44:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:54 INFO - 'HOME': '/Users/cltbld', 10:44:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:54 INFO - 'LOGNAME': 'cltbld', 10:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:54 INFO - 'MOZ_NO_REMOTE': '1', 10:44:54 INFO - 'NO_EM_RESTART': '1', 10:44:54 INFO - 'PAGER': '/bin/cat', 10:44:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:54 INFO - 'PWD': '/builds/slave/test', 10:44:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:54 INFO - 'SHELL': '/bin/bash', 10:44:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1H112gSAW/Listeners', 10:44:54 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 10:44:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:54 INFO - 'USER': 'cltbld', 10:44:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:54 INFO - 'XPC_FLAGS': '0x0', 10:44:54 INFO - 'XPC_SERVICE_NAME': '0', 10:44:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:44:55 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-PLi9Fc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:44:55 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ZcjPIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:44:55 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)) 10:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:44:55 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-onMdqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:44:55 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-KPpjUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:44:55 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-5NEwO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:44:55 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-OEjEjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:44:55 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-SZHy8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:44:55 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-z7ZM6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:44:55 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-OPCYuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:44:56 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-sBneox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:44:56 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ojilC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:44:56 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ezPf_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:44:56 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-bH3J6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:44:56 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ovfhDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:44:56 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)) 10:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:44:56 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-wpJjHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:44:56 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-hsk19J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:44:56 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-pVu0Hv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:44:56 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)) 10:44:56 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)) 10:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:44:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:59 INFO - Downloading blessings-1.6.tar.gz 10:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:44:59 INFO - Installing collected packages: blessings 10:44:59 INFO - Running setup.py install for blessings 10:44:59 INFO - Successfully installed blessings 10:44:59 INFO - Cleaning up... 10:44:59 INFO - Return code: 0 10:44:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:44:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:44:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:45:00 INFO - Reading from file tmpfile_stdout 10:45:00 INFO - Current package versions: 10:45:00 INFO - blessings == 1.6 10:45:00 INFO - blobuploader == 1.2.4 10:45:00 INFO - docopt == 0.6.1 10:45:00 INFO - functools32 == 3.2.3-2 10:45:00 INFO - jsonschema == 2.5.1 10:45:00 INFO - manifestparser == 1.1 10:45:00 INFO - mozInstall == 1.12 10:45:00 INFO - mozcrash == 1.0 10:45:00 INFO - mozdebug == 0.1 10:45:00 INFO - mozdevice == 0.48 10:45:00 INFO - mozfile == 1.2 10:45:00 INFO - mozhttpd == 0.7 10:45:00 INFO - mozinfo == 0.9 10:45:00 INFO - mozleak == 0.1 10:45:00 INFO - mozlog == 3.4 10:45:00 INFO - moznetwork == 0.27 10:45:00 INFO - mozprocess == 0.23 10:45:00 INFO - mozprofile == 0.28 10:45:00 INFO - mozrunner == 6.13 10:45:00 INFO - mozscreenshot == 0.1 10:45:00 INFO - mozsystemmonitor == 0.3 10:45:00 INFO - moztest == 0.7 10:45:00 INFO - mozversion == 1.4 10:45:00 INFO - psutil == 3.1.1 10:45:00 INFO - requests == 1.2.3 10:45:00 INFO - wsgiref == 0.1.2 10:45:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:45:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:00 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 0x1085c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108539570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f6a5c7650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d7d30>, '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 0x10823ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083cfb20>, '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.PJPikLXMPN/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.c1H112gSAW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:45:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:45:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:45:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJPikLXMPN/Render', 10:45:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:00 INFO - 'HOME': '/Users/cltbld', 10:45:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:00 INFO - 'LOGNAME': 'cltbld', 10:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:00 INFO - 'MOZ_NO_REMOTE': '1', 10:45:00 INFO - 'NO_EM_RESTART': '1', 10:45:00 INFO - 'PAGER': '/bin/cat', 10:45:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:00 INFO - 'PWD': '/builds/slave/test', 10:45:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:00 INFO - 'SHELL': '/bin/bash', 10:45:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1H112gSAW/Listeners', 10:45:00 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 10:45:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:00 INFO - 'USER': 'cltbld', 10:45:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:00 INFO - 'XPC_FLAGS': '0x0', 10:45:00 INFO - 'XPC_SERVICE_NAME': '0', 10:45:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:45:00 INFO - Cleaning up... 10:45:00 INFO - Return code: 0 10:45:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:45:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1085c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108539570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f6a5c7650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d7d30>, '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 0x10823ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083cfb20>, '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.PJPikLXMPN/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.c1H112gSAW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:45:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:45:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:45:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJPikLXMPN/Render', 10:45:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:01 INFO - 'HOME': '/Users/cltbld', 10:45:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:01 INFO - 'LOGNAME': 'cltbld', 10:45:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:01 INFO - 'MOZ_NO_REMOTE': '1', 10:45:01 INFO - 'NO_EM_RESTART': '1', 10:45:01 INFO - 'PAGER': '/bin/cat', 10:45:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:01 INFO - 'PWD': '/builds/slave/test', 10:45:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:01 INFO - 'SHELL': '/bin/bash', 10:45:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1H112gSAW/Listeners', 10:45:01 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 10:45:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:01 INFO - 'USER': 'cltbld', 10:45:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:01 INFO - 'XPC_FLAGS': '0x0', 10:45:01 INFO - 'XPC_SERVICE_NAME': '0', 10:45:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:45:01 INFO - Cleaning up... 10:45:01 INFO - Return code: 0 10:45:01 INFO - Running post-action listener: _resource_record_post_action 10:45:01 INFO - Running post-action listener: _start_resource_monitoring 10:45:01 INFO - Starting resource monitoring. 10:45:01 INFO - [mozharness: 2016-11-14 18:45:01.525609Z] Finished create-virtualenv step (success) 10:45:01 INFO - [mozharness: 2016-11-14 18:45:01.526606Z] Running install step. 10:45:01 INFO - Running pre-action listener: _resource_record_pre_action 10:45:01 INFO - Running main action method: install 10:45:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:45:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:45:01 INFO - Reading from file tmpfile_stdout 10:45:01 INFO - Detecting whether we're running mozinstall >=1.0... 10:45:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:45:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:45:01 INFO - Reading from file tmpfile_stdout 10:45:01 INFO - Output received: 10:45:01 INFO - Usage: mozinstall [options] installer 10:45:01 INFO - Options: 10:45:01 INFO - -h, --help show this help message and exit 10:45:01 INFO - -d DEST, --destination=DEST 10:45:01 INFO - Directory to install application into. [default: 10:45:01 INFO - "/builds/slave/test"] 10:45:01 INFO - --app=APP Application being installed. [default: firefox] 10:45:01 INFO - mkdir: /builds/slave/test/build/application 10:45:01 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'] 10:45:01 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 10:45:33 INFO - Reading from file tmpfile_stdout 10:45:33 INFO - Output received: 10:45:33 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:45:33 INFO - Running post-action listener: _resource_record_post_action 10:45:33 INFO - [mozharness: 2016-11-14 18:45:33.795497Z] Finished install step (success) 10:45:33 INFO - [mozharness: 2016-11-14 18:45:33.795630Z] Running run-tests step. 10:45:33 INFO - Running pre-action listener: _resource_record_pre_action 10:45:33 INFO - Running main action method: run_tests 10:45:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:45:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:45:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:45:33 INFO - 2016-11-14 10:45:33.810 screenresolution[2546:290265] starting screenresolution argv=screenresolution get 10:45:33 INFO - 2016-11-14 10:45:33.812 screenresolution[2546:290265] Display 0: 1600x1200x32@60 10:45:33 INFO - 2016-11-14 10:45:33.818 screenresolution[2547:290267] starting screenresolution argv=screenresolution list 10:45:33 INFO - Available Modes on Display 0 10:45:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:45:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:45:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:45:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:45:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:45:34 INFO - Intel Iris: 10:45:34 INFO - Chipset Model: Intel Iris 10:45:34 INFO - Type: GPU 10:45:34 INFO - Bus: Built-In 10:45:34 INFO - VRAM (Dynamic, Max): 1536 MB 10:45:34 INFO - Vendor: Intel (0x8086) 10:45:34 INFO - Device ID: 0x0a2e 10:45:34 INFO - Revision ID: 0x0009 10:45:34 INFO - Displays: 10:45:34 INFO - Display: 10:45:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:45:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:45:34 INFO - Display Serial Number: 1600x1200 60 10:45:34 INFO - Main Display: Yes 10:45:34 INFO - Mirror: Off 10:45:34 INFO - Online: Yes 10:45:34 INFO - Rotation: Supported 10:45:34 INFO - Return code: 0 10:45:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:45:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:45:34 INFO - Python 2.7.3 10:45:34 INFO - Return code: 0 10:45:34 INFO - grabbing minidump binary from tooltool 10:45:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:34 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 0x1083d7d30>, '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 0x10823ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083cfb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:45:34 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:45:34 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:45:34 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:45:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:45:34 INFO - Return code: 0 10:45:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:45:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:45:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:45:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:45:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:45:34 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/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 10:45:34 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/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0127 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 10:45:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PJPikLXMPN/Render', 10:45:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:34 INFO - 'HOME': '/Users/cltbld', 10:45:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:34 INFO - 'LOGNAME': 'cltbld', 10:45:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:45:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:45:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:34 INFO - 'MOZ_NO_REMOTE': '1', 10:45:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:45:34 INFO - 'NO_EM_RESTART': '1', 10:45:34 INFO - 'PAGER': '/bin/cat', 10:45:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:34 INFO - 'PWD': '/builds/slave/test', 10:45:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:45:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:34 INFO - 'SHELL': '/bin/bash', 10:45:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1H112gSAW/Listeners', 10:45:34 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 10:45:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:34 INFO - 'USER': 'cltbld', 10:45:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:34 INFO - 'XPC_FLAGS': '0x0', 10:45:34 INFO - 'XPC_SERVICE_NAME': '0', 10:45:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:34 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/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 10:45:34 INFO - mozversion application_buildid: 20161114074144 10:45:34 INFO - mozversion application_changeset: 8cdff71808a0e11361bca7f218658c4343c730ca 10:45:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:45:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:45:34 INFO - mozversion application_name: Firefox 10:45:34 INFO - mozversion application_remotingname: firefox-dev 10:45:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:45:34 INFO - mozversion application_vendor: Mozilla 10:45:34 INFO - mozversion application_version: 52.0a2 10:45:34 INFO - mozversion platform_buildid: 20161114074144 10:45:34 INFO - mozversion platform_changeset: 8cdff71808a0e11361bca7f218658c4343c730ca 10:45:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:45:34 INFO - mozversion platform_version: 52.0a2 10:45:34 INFO - using testdate: 1479149134 10:45:34 INFO - actual date: 1479149134 10:45:34 INFO - starting webserver on 'localhost:51878' 10:45:34 INFO - SUITE-START | Running 2 tests 10:45:34 INFO - TEST-START | dromaeo_css 10:45:34 INFO - operating with platform_type : mac_ 10:45:34 INFO - Initialising browser for dromaeo_css test... 10:45:34 INFO - TEST-INFO | started process 2557 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpCD91rN/profile http://localhost:51878/getInfo.html) 10:45:36 ERROR - PROCESS | 2557 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 10:45:37 INFO - PROCESS | 2557 | __metrics Screen width/height:1600/1200 10:45:37 INFO - PROCESS | 2557 | colorDepth:24 10:45:37 INFO - PROCESS | 2557 | Browser inner width/height: 1024/689 10:45:37 INFO - PROCESS | 2557 | __metrics 10:45:37 ERROR - PROCESS | 2557 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 10:45:38 INFO - TEST-INFO | 2557: exit 0 10:45:38 INFO - Browser initialized. 10:45:38 INFO - Running cycle 1/1 for dromaeo_css test... 10:45:38 INFO - TEST-INFO | started process 2561 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpCD91rN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:45:50 INFO - PROCESS | 2561 | RSS: Main: 224169984 10:45:50 INFO - PROCESS | 2561 | 10:46:39 INFO - PROCESS | 2561 | 1479149199845 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 10:48:54 INFO - PROCESS | 2561 | Cycle 1(1): loaded http://localhost:51878/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51878/tests/dromaeo/cssquery-ext.html) 10:48:56 INFO - PROCESS | 2561 | RSS: Main: 235474944 10:48:56 INFO - PROCESS | 2561 | 10:51:58 INFO - PROCESS | 2561 | Cycle 1(1): loaded http://localhost:51878/tests/dromaeo/cssquery-ext.html (next: http://localhost:51878/tests/dromaeo/cssquery-jquery.html) 10:51:58 INFO - PROCESS | 2561 | RSS: Main: 247828480 10:51:58 INFO - PROCESS | 2561 | 10:55:00 INFO - PROCESS | 2561 | Cycle 1(1): loaded http://localhost:51878/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51878/tests/dromaeo/cssquery-mootools.html) 10:55:01 INFO - PROCESS | 2561 | RSS: Main: 250564608 10:55:01 INFO - PROCESS | 2561 | 10:58:03 INFO - PROCESS | 2561 | Cycle 1(1): loaded http://localhost:51878/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51878/tests/dromaeo/cssquery-prototype.html) 10:58:03 INFO - PROCESS | 2561 | RSS: Main: 250757120 10:58:03 INFO - PROCESS | 2561 | 11:01:06 INFO - PROCESS | 2561 | Cycle 1(1): loaded http://localhost:51878/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51878/tests/dromaeo/cssquery-yui.html) 11:01:07 INFO - PROCESS | 2561 | RSS: Main: 254160896 11:01:07 INFO - PROCESS | 2561 | 11:04:09 INFO - PROCESS | 2561 | Cycle 1(1): loaded http://localhost:51878/tests/dromaeo/cssquery-yui.html (next: http://localhost:51878/tests/dromaeo/cssquery-dojo.html) 11:04:10 INFO - PROCESS | 2561 | RSS: Main: 256917504 11:04:10 INFO - PROCESS | 2561 | 11:04:10 INFO - PROCESS | 2561 | __start_tp_report 11:04:10 INFO - PROCESS | 2561 | _x_x_mozilla_page_load 11:04:10 INFO - PROCESS | 2561 | _x_x_mozilla_page_load_details 11:04:10 INFO - PROCESS | 2561 | |i|pagename|runs| 11:04:10 INFO - PROCESS | 2561 | |0;dojo.html;3383.847726852293;3422.2299982503923;3426.486027095936;3428.5200071989925;3488.3546543889365;5892.410758924106;5910.940890591093;5916.142159386886;5985.431384018522;6013.037913933771;5951.851203719898;5959.493443057335;6045.274567051949;6049.1228771828055;6050.485708714765;5351.117065684158;5369.5167434930845;5430.049741295277;5432.6468779529205;5512.751926163332;4602.6087782538525;5459.481349271813;5485.506304432601;5488.91766623501;5516.310461192351;5342.866428339303;5349.331333583302;5479.287692599956;5485.6434331768505;5523.420040895709;5390;5436.293281873351;5438.428964958702;5452.809151679672;5454.863628409322;5282;5345.652532585408;5349.465053494659;5360.410354860962;5373.35519737634;6035.849103772441;6056.909146362764;6064.878702425971;6126.571140020201;6145.139680444741;5472.616916815825;6084.965555855515;6125.846853828645;6241.625502469867;6271.717772700239;6149.385061493815;6219.440250377443;6224.502039836802;6244.344343843876;6288.591241569284;6047.576669633171;6065.8180254592435;6100.533434559381;6105.32841387447;6123.4795042421;5335.306410166653;5788.739506722208;6469.935300646934;6486.189226346707;6508.730797494443;5327.706976116351;6140.723667434976;6160.322364539897;6167.691615419211;6192.380761923861;6233.081210191065;6263.6241825490615;6307.653079080603;6312.084747711556;6315.778947736807;6118.497354392036;6119.44924956756;6142.262928448615;6223.346548612421;6255.718492667841;6091.299500557404;6109.283207920342;6119.877602448015;6154.076888466766;6163.137160797492;4484.955150448453;4927.507249275115;4982.10322142018;5002.074616196015;5015.523525265094;4504.234280172313;4519.457665080211;4547.886302842389;4576.725396476222;4577.633789296915;6050.364711705296;6059.151718759377;6069.3020302665755;6099.115628233882;6123.6486823095165;6177.073438984098;6330.335314791972;6338.366163383717;6342.016987366966;6376.139221205078;6128.264608247039;6130.141780150782;6136.693165341715;6143.139960405547;6163.7842675506145;5319.175527793198;5339.679619222859;5390.056740070472;5434.9945260126215;5526.806561770319;6403.77586784451;6483.384078512534;6519.511036672174;6541.672916354068;6556.508261880283;6312;6314.147590075465;6316.336784637547;6332.176817013758;6349.841253968688;6220.440160385587;6294;6464.095026696173;6514.771982980573;6537.411632953057;6147.96926015385;6159;6181.443670069715;6240.625562466266;6280.717367718372;6295.5907865988565;6300.432961033529;6302.747890084345;6311.779087731908;6321.30465648769;6037.426444487766;6086.573939824169;6160.568760186744;6182.412670796267;6184.752609895554;6101.359357267423;6115.483736156635;6131.141640170291;6133.120447061136;6138.079288106641;5519.923614895057;5524.977879092381;6158.414950579688;6184;6203.720832562636;6125.84685382869;6144.723487442986;6152.692365381802;6172;6180.196574445294;5631.774729010794;6047.485963693135;6078.939210608014;6138.969305153624;6168.321484636596 11:04:10 INFO - PROCESS | 2561 | |1;ext.html;476.5234765234765;481.7567128600056;509.16496945010215;522.6968358352157;524.546267478631;39014;40601.98495037625;40695;42515.57484425155;42530.36204456936;34131.31737365251;41504.58495415045;41781.58218417815;41869.95325116866;41941;9374.625014999408;9457.054294570522;9532.571034303473;9553.187979021792;9566;9456.905430945671;9499.762505937339;9505.097015783489;9544.713658590254;9561.139497445263;8443.915560844405;9510.47692376919;9513.476758778264;9525;9529.285303602222;9449.905500944971;9469.24246060317;9538.141567258945;9549.28380371468;9550.331476796628;9409.435433873921;9503.57233924475;9510.762230944212;9520.666776662783;9538.236941044699;39355.40966885499;39940.800295998335;40071.79784606466;40229.79310620686;40274.59725402767;3115.7818952673324;3310.4041272570926;3312.3540909522653;3329.334133173351;3607.9639203608153;28356.29109272264;29716.257093572618;29855.850720746475;31040.689593104005;32003.359932801446;3127.233827712202;3150.5746724192168;3159.810411375302;3247.788893721924;3249.6425393206728;403569;481824;485082;496424;496703;65962;66133.33866661272;66139;66334.33665663282;66496.66751666211;69751.95372069423;69771.65114174498;70190;70476.94284585735;70568.6471567639;16405.343786248653;16516.50450486487;16542.338306467846;16579.66840663204;16583.83416165847;12718.364081795873;12799.488020479077;12820.743585128244;12830.486780528674;12866.806997895039;19804.70292945607;20135.597288054156;20161.899190503955;20163.39509814708;20171.495712607302;7212.819679508054;8399.11809260031;8428.578571071423;8497.36269779769;8557.44376615518;842.2377748137945;933.2627224492575;967.1295833749682;970.45169479244;1026.7997740440542;29619.111426657233;30131.246718832204;30210.54684179739;30291.848540757153;30661.69338306633;947.3463310315998;983.4345251480428;1007.4811472091878;1045.7960697663882;1140.190464770006;13897.513587024405;15493.767593486107;15579.376824927103;15605.30688208202;15800.92099539495;648818;657183;658672;659192;660003;57285.42714572884;57661;58309;58454;58508.70745646244;1342.3422522963872;1370.5751217122724;1379.7792353223435;1390.8400533938727;1393.1292941911306;26261;29136;29552.261193470335;29967.100986970425;30401.239969000068;42219.57780422157;42484.38882695005;50844;50862.491375085774;51093;575564;575899.1205043948;578765;579675;580482;35717.28565428729;42826;43392.91517711956;43830.561694382646;44298;38508.80745596254;46197.53802461932;46246.53753462557;46353.768231158625;47791.76104119596;32177.517337239493;39113.8044309788;39268.01829954274;39374.409383859835;39757.403638946016;38373.808130959165;38776.806115970365;38805.805970971094;39751;39919.80040099781;37674.246515070095;38056;38605;38676.80661596674;39526.01184970399;32309.838450807594;34421.48367774535;34924.65075349214;35335.64664353323;35357.46963795597;22363;25823.0961916332;26105.955761769317;26664.60003099994;27155.592666109627 11:04:10 INFO - PROCESS | 2561 | |2;jquery.html;369.94450832375156;371.196359880858;375.2813362410983;388.57062945445284;401.1936008622669;17125.38834348204;18694.15876285567;18800.717989230172;19012.904935475337;19208.711869321938;18573.814261857377;19297.324593639227;19541.706874396896;19614.705779413285;19708.802911970877;4765.880852978686;5604.41153678864;5751.827445176641;5958.136070269808;5961.642301461904;5710.029295019835;5892.646441213541;5919.911201331984;5925.970370148143;5929.8814023719715;5685.346185188715;5819.883602327951;5867.794627188049;5877.353491115995;6004.849878753044;6012.278526576795;6041.305249896274;6062.727177277033;6074.058520929263;6079.422454866803;5817.0983497557945;5899.941000589982;5967.045272756338;6018.15745795589;6042.848928776772;12640.304783236918;13078.280694561796;13330.533431329857;20203.696944545845;23755.643665345033;2160.4274867160216;2208.9558208835892;2300.7009088818513;2481.019997101135;2568.7688108070365;9437.150656440885;9953.552090155961;10101.494925253708;10249.385036897735;10306.433146176958;1856.7400563921062;1868.2993877296014;1868.3647559829724;1897.667908116088;1899.848012159024;112565.43717281443;119821.40089299498;123582.38208808989;125051;125721.74278257284;11241.437928103562;11519.424028798527;11609.245399049034;11639.41802909868;11793.351365674798;14583.197924114063;14591.270436478133;14593.41626334956;14596.416143354147;14624;7002.614856182959;7061.399781018568;7086.433085353159;8107;8387.62255698495;7004.964975175091;8287.254147126787;8332.50004999702;8361.95819020901;8387.580620968927;4400.515943246241;4434;4468.642508599304;4508.165989291926;4511.150428324371;1677.2117104960648;1692.7291633338607;1870.78485974112;1891.6027634196973;1924.8171423715005;813.5037627047496;890.7461373508648;924.5608336040461;942.2273735536932;982.6757170133839;19195.904020479808;19225.423237302624;19349.709754353695;19888.70166947497;20233;943.8678584998106;957.482959202037;963.0754475703263;974.1038244814788;987.1165307050294;9411.341206115643;10037.69886903394;10673.146148308271;10720.195985301134;10728.678139655824;506282;507280;509307.90692092606;559789;565126;78333;120686;126735;130894;132137;972.6595691508057;985.1724551376877;1013.8023085498257;1018.251585084962;1025.0671888581355;17918.5520361992;18425;18791.90604046971;18839.811601883805;19228.423147305602;50764.984700305045;51068.46794596168;51693.48306516887;51922.740386297824;54328;66099.33900660931;73961.63019185085;75801;76195.857062143;76373;7668.233176682287;7913.643886025257;8098.068722097034;8141.470804397695;8323.417360784686;11779.823302650639;12081.818772718241;12684.175528590613;12819.551315703906;12963.740725185633;9168.770780730536;10876.728081798019;11019.228653994463;11060.336379817236;11278.436078195893;9235.445873247627;10838.67483975482;10857.782844343226;11088.33469991803;11145.944270278596;10941.124710023338;11143.164262680493;11217.943910280397;11471.541138354372;11696.06431485462;8010.479318844256;8192.631331589973;8227.83544329122;8231.135730748185;8350.08149103586;8141.063777665644;9800.509974501389;10953.671389858317;10979;11458.94270528642 11:04:10 INFO - PROCESS | 2561 | |3;mootools.html;1469.7134058858524;1512.1531942112417;1686.6289416328411;1765.3821162593085;1811.315476606756;7608.125065617442;7877.212278772135;8120.472169308998;8544.401891867572;8569.357298202636;8018.037835459739;8294.502329860212;8325.75022749317;8414.579271036455;8434.831303373929;2310.803581695558;2346.1319311854636;2349.001674288429;2350.9647355289685;2358.575456417844;2337.567550003246;2367.7868991790733;2378.3459548624182;2378.6432035194684;2379.8691071991034;2249.7750224977453;2255.5150642611834;2279.111146652807;2279.7150356205475;2283.6460348645987;2320.524292520029;2325.1164557468137;2330.0563271874835;2341.3210169050953;2351.2240960483;2337.7779110984598;2360.4688944987242;2368.3487041063845;2370.2533701883854;2375.0618505690327;8326.791830204233;8407.70573029941;8465.068842427328;8490.023647280581;8506.532140732257;4988.5260900214425;5072.594192464631;5204.895902081975;5253.737313134328;5276.234945932857;3377.307651931348;3484.372812893678;3620.9275814483826;3631.9467354467174;3644.1618427761764;1049.826778581533;1050.5430137889966;1093.5024563823442;1106.878243393226;1109.2567979453704;10044.94977525115;10248.333858299187;11058.22592418531;11089.83365249522;11102.777944441146;4479.686421950432;4606.055758569485;4687.179743544866;4697.295405689174;4758.690685105457;9415.952920235492;9497.002814704494;9499.002604726542;9499.192568631606;9511.476868772284;5170.767315470813;5189.506996835295;5190.065788158092;5211.062008838446;5234.2933703949475;3780.1305699688673;4046.979765101156;4404.317330813729;4721.598664113589;4728.172569800271;3790.50723405961;3825.062859599416;3871.4773505576386;3892.3772196449;3899.3760998240145;1274.1463219642676;1290.5483080921601;1291.5802364231663;1302.2707283921034;1305.3864683598692;752.3717695724022;849.4351256414416;884.1688812516214;895.8118795052982;903.742433406476;5597.132444471114;5680.346760122557;5704.942950570524;5708.429157084258;5889.5877288590245;894.7986702991905;899.2311573604604;901.454619954931;941.3880977364768;949.6581230756922;3571.5178450909316;3632.9636703632627;3851.9229615407535;3866.6326698964117;3938.2714197873483;9937.503124843728;10197.18422526211;10302.433366164783;10311.278210525341;10372.844407333896;9884.901150988399;10148.340357876861;10269.691909242734;10274.33216840903;10290.948545257226;7497.1378291496085;7864.567448790257;7899.565523896126;7930.841383172305;7940.364770818321;4846.903061938812;5493.752781124939;5513.842093160481;5570.164475328733;5690.77236910519;9234.769130771516;9238.214751746176;9317.580708868252;9341.20599749029;9389.295802814953;7973.44185906981;9355;9431.104045115702;9476.099770521789;9535.141837234682;5803.7968671096305;6318.399752023551;6531.281559028408;6612.768553100618;6896.172459304916;7878;7885.487443316166;7960.402969777406;8072.919270807217;8077.717279895175;7130.928690713026;7221.205667376689;7252.854942901007;7260.019897314006;7315.670794814139;6961.6519174042105;7089.397401220747;7115.715371385087;7178.641067946686;7226.638668066471;6717.966410167918;6723.731050757915;6731.394174524316;6815.079964204769;6827.726890924506;4243.724157929724;4884.804607815648;4909.803607855646;4982;4989.80040798364;4730.834420795393;4817.397825271841;4854.611631069435;4987.825426110068;5019.799208031784 11:04:10 INFO - PROCESS | 2561 | |4;prototype.html;299.9670036296007;300.6272222444169;303.4871067895258;307.48035819465105;310.23681742912436;4568.840090596821;5407.405185429599;5414;5451.836444906649;5509;4968.677035992658;5369.838904832852;5492.505674489295;5533.806316778913;5568.276124103871;3378.476336167898;3519.630438803927;3551.8907479135846;3805.2389522095555;3888.1446081861945;3524.894253172396;3608.7008676876303;4144.937825932613;4225.936610950838;4232.978835105836;3590.605033446319;3638.7634803737806;4050.2102090092444;4161.230172418097;4181.268278051344;3673.669369756708;4110.403991421242;4133.81397837098;4147.751134931914;4164.437800896871;3405.1056601888445;3528.4530897710897;3648.2703459307977;3648.9270214595826;3681.889543313705;5246.7638956247065;5315.388730296027;5370.462953704638;5408.567314614821;5416.458354164592;4215.051613386994;4426.84506042287;4440.333949907507;4776.355192049064;4800.567948884583;5424.294841670597;5502.174673798922;5832.504237139849;5876.382979787104;5881.44172775469;3847.8653247136217;3864.6328598783352;3973.781442020688;4015.738976966517;4042.353223484258;5759.272218334483;6081.635101893857;6101.359357267467;6115.755369785204;6170.96914515429;3628.63713628635;3661.6704496594903;3901.3367727486398;4113.424120623055;4131.938020929689;3573.5890372606964;4010.5509558711046;4088.9591104089172;4151.522574903865;4163.854265100707;4378.5183629800695;4442.200403927324;4496.572825581565;4758.357621721023;4782.210935195653;5756.251687280626;5793.391693872167;5806.535477161817;5850.531957443394;5900.468957793819;1219.8706643151017;1270.885620294178;1278.6483716977718;1305.4321370203802;1321.6563693439775;828.6933834481281;861.521855370263;889.915458031486;917.926026548926;926.4302453990747;4665.113628410591;4729.668923175344;5225.503577160163;5247.714309994076;5299.33758280215;5119.8720031998755;5243.475652434802;5423.05096608092;5666.045214401025;5683.943160568424;4917.237828136645;4946.23333383319;4947.752612369367;5028.572571331478;5179.81870634526;2652.4960257551;2775.3764048032126;2804.032608750019;3014.5779590857296;3037.726604605596;5178.349263769034;5876.500497457679;5945.672987985616;6008.519318454513;6019.578629495981;4620.735839438651;5807.125472890862;5833.883322333614;5896.233489646404;5922.644641321534;5001.474550262133;5226.398964119175;5370.838874833683;5931.347551769302;5949.375315591887;5143.742812859267;5476.315460567429;5593.972030139823;5669.86602679474;5762.481376676119;4933.654644174873;5303.893922121459;5597.6641401516035;5666.9716651416475;5691.800786972602;5379.73101344939;5425.674459532441;5942.9690857662035;5956.106584012433;6066.969665151646;3618.4934109224205;3631.6368363163474;3700.777953322809;3719.1260053887854;3759.578783307177;5310.761015754378;5741.196232527533;5806.82579522615;5994.250718660168;6044.788432404845;5683.1759394886685;5764.884702306014;5854.268216472941;5871;5879.323877754113;4612.907741845212;5290.1800220965815;5849.6197747148;5852.502537284208;5892.499137573354;5411.675299482044;5646.015519152605;5653.208550802973;5668.004057139789;5750.338711048115;5679.716014199356;5841.18223448726;5865.178874957424;5876.471117599436;5888.558358123244;5089.312942752829;5319.38827034896;5359.837695759521;5363.973180134074;5784.797532086357 11:04:10 INFO - PROCESS | 2561 | |5;yui.html;634.5462993959316;676.458832933653;690.4717890813527;724.2214619284271;731.217597171027;13737.107088039284;14487.130772153652;14599.051061680982;14660.486882959098;14662.04696694714;12920.030997675189;13152.473901043946;13205.537806176786;13345.332733363317;13359.131656442325;666.8932970724672;814.4665244265016;817.709713051867;818.3167055508658;820.0118856777568;648.6075924065943;667.4560233409779;804.3605333759675;816.8774683797418;819.3117781063905;852.3096292003497;867.1978419961542;867.319154463746;867.761365624452;868.1708967935606;829.2412442615;864.1660797330543;865.0398058155442;865.0657290126649;867.9869801952975;631.0849268560605;640.3212594649686;642.0914406115342;642.1202951955851;643.5366536094004;13473.258970756604;14170.929145354206;14188.92905535476;14301.856981430157;14354.569362919025;12673.746525069539;13312.267825269606;13403.531863551669;13425.66435839102;13457.192568445924;11039;11207.327560346404;11375;11534.423278836024;11669.766604667944;3458.15275257561;3628.8004159771203;3673.81630918453;3674.706023518112;3791.6018818023986;76727;77329.8400523987;78433.60783196047;88127.11872881254;92489.53755231248;10395.480225988671;10566.418846963337;10615.52230149645;10616.840747388795;10684.038436540748;19489.415317540497;19673.803261967485;19741.802581974283;19877.503062423264;19921.60156796856;7595.354394876497;7606.619669016527;7612;7682.692692292246;7772.261635144652;4166.479190101238;4257.723247988871;4324.113556720865;4381.3208952612395;4382.9780851096175;3385;3487.5466189395215;3511.2801875615946;3515.402381595135;3515.5605549306338;623.7910300049443;624.6096189881324;625.6214989931117;632.908228306893;638.5976834594176;497.30625777040825;562.7411390760296;578.3695771608966;641.0704478506185;643.9806805795833;8694.260987816106;8899.955500222457;8947.92931671164;9046.231070358961;9275.211607013345;574.5087949802942;576.2566289486599;579.9449052340021;614.8800983808114;637.8883695353295;3785.4511095891494;4187.72779769314;4339.7830108494445;4351.5430879757805;4472.642188624902;90193.64709529362;94619.52690236505;107651.46174269078;119288;119373.40313298377;67840.64318713598;98215;101820;105547.4722626382;107220.92779072265;88919;90877.54561227151;91325;93430.53284733533;95443;8214.671413143646;8508.361872859685;8550.78623034429;8681.91318086811;8762.693305734523;43936.560634394526;57874.4212557869;58260;59099.70450147894;59504.40495595162;68292.97560536496;82610;82898.34203316021;83467.58266208872;84204.57897710717;10705.678829635124;10733.516991735547;10888.61889833852;11065.391403472564;11188.1608879336;11954.402279886144;12058.51765929353;12149.878501215228;12199.69500762488;12269.815952760895;10807.962296418658;11536.174826258088;11652.184347095623;11680.532778688757;11715;11184.105271578417;11442.199046066695;11580.189386742848;11744.941275293912;11748.70628234301;11341.149413793833;11368.602098926498;11485.885141148481;11519.30884146954;11847.940760296144;9400.811983760423;9880;10243.590256389662;10449.268551201343;10568;8904.643814247358;9844.753881153028;9905.801883962424;9915.206783457163;9932.652357167464 11:04:10 INFO - PROCESS | 2561 | __end_tp_report 11:04:10 INFO - PROCESS | 2561 | __start_cc_report 11:04:10 INFO - PROCESS | 2561 | _x_x_mozilla_cycle_collect,381 11:04:10 INFO - PROCESS | 2561 | __end_cc_report 11:04:10 INFO - PROCESS | 2561 | __startTimestamp1479150250018__endTimestamp 11:04:10 INFO - PROCESS | 2561 | 11:04:10 INFO - PROCESS | 2561 | ------- Summary: start ------- 11:04:10 INFO - PROCESS | 2561 | Number of tests: 6 11:04:10 INFO - PROCESS | 2561 | 11:04:10 INFO - PROCESS | 2561 | [#0] dojo.html Cycles:165 Average:5845.81 Median:6118.50 stddev:636.28 (10.4%) stddev-sans-first:608.22 11:04:10 INFO - PROCESS | 2561 | Values: 3383.8 3422.2 3426.5 3428.5 3488.4 5892.4 5910.9 5916.1 5985.4 6013.0 5951.9 5959.5 6045.3 6049.1 6050.5 5351.1 5369.5 5430.0 5432.6 5512.8 4602.6 5459.5 5485.5 5488.9 5516.3 5342.9 5349.3 5479.3 5485.6 5523.4 5390.0 5436.3 5438.4 5452.8 5454.9 5282.0 5345.7 5349.5 5360.4 5373.4 6035.8 6056.9 6064.9 6126.6 6145.1 5472.6 6085.0 6125.8 6241.6 6271.7 6149.4 6219.4 6224.5 6244.3 6288.6 6047.6 6065.8 6100.5 6105.3 6123.5 5335.3 5788.7 6469.9 6486.2 6508.7 5327.7 6140.7 6160.3 6167.7 6192.4 6233.1 6263.6 6307.7 6312.1 6315.8 6118.5 6119.4 6142.3 6223.3 6255.7 6091.3 6109.3 6119.9 6154.1 6163.1 4485.0 4927.5 4982.1 5002.1 5015.5 4504.2 4519.5 4547.9 4576.7 4577.6 6050.4 6059.2 6069.3 6099.1 6123.6 6177.1 6330.3 6338.4 6342.0 6376.1 6128.3 6130.1 6136.7 6143.1 6163.8 5319.2 5339.7 5390.1 5435.0 5526.8 6403.8 6483.4 6519.5 6541.7 6556.5 6312.0 6314.1 6316.3 6332.2 6349.8 6220.4 6294.0 6464.1 6514.8 6537.4 6148.0 6159.0 6181.4 6240.6 6280.7 6295.6 6300.4 6302.7 6311.8 6321.3 6037.4 6086.6 6160.6 6182.4 6184.8 6101.4 6115.5 6131.1 6133.1 6138.1 5519.9 5525.0 6158.4 6184.0 6203.7 6125.8 6144.7 6152.7 6172.0 6180.2 5631.8 6047.5 6078.9 6139.0 6168.3 11:04:10 INFO - PROCESS | 2561 | 11:04:10 INFO - PROCESS | 2561 | [#1] ext.html Cycles:180 Average:70931.09 Median:41099.79 stddev:153487.61 (373.5%) stddev-sans-first:153827.02 11:04:10 INFO - PROCESS | 2561 | Values: 476.5 481.8 509.2 522.7 524.5 39014.0 40602.0 40695.0 42515.6 42530.4 34131.3 41504.6 41781.6 41870.0 41941.0 9374.6 9457.1 9532.6 9553.2 9566.0 9456.9 9499.8 9505.1 9544.7 9561.1 8443.9 9510.5 9513.5 9525.0 9529.3 9449.9 9469.2 9538.1 9549.3 9550.3 9409.4 9503.6 9510.8 9520.7 9538.2 39355.4 39940.8 40071.8 40229.8 40274.6 3115.8 3310.4 3312.4 3329.3 3608.0 28356.3 29716.3 29855.9 31040.7 32003.4 3127.2 3150.6 3159.8 3247.8 3249.6 403569.0 481824.0 485082.0 496424.0 496703.0 65962.0 66133.3 66139.0 66334.3 66496.7 69752.0 69771.7 70190.0 70476.9 70568.6 16405.3 16516.5 16542.3 16579.7 16583.8 12718.4 12799.5 12820.7 12830.5 12866.8 19804.7 20135.6 20161.9 20163.4 20171.5 7212.8 8399.1 8428.6 8497.4 8557.4 842.2 933.3 967.1 970.5 1026.8 29619.1 30131.2 30210.5 30291.8 30661.7 947.3 983.4 1007.5 1045.8 1140.2 13897.5 15493.8 15579.4 15605.3 15800.9 648818.0 657183.0 658672.0 659192.0 660003.0 57285.4 57661.0 58309.0 58454.0 58508.7 1342.3 1370.6 1379.8 1390.8 1393.1 26261.0 29136.0 29552.3 29967.1 30401.2 42219.6 42484.4 50844.0 50862.5 51093.0 575564.0 575899.1 578765.0 579675.0 580482.0 35717.3 42826.0 43392.9 43830.6 44298.0 38508.8 46197.5 46246.5 46353.8 47791.8 32177.5 39113.8 39268.0 39374.4 39757.4 38373.8 38776.8 38805.8 39751.0 39919.8 37674.2 38056.0 38605.0 38676.8 39526.0 32309.8 34421.5 34924.7 35335.6 35357.5 22363.0 25823.1 26106.0 26664.6 27155.6 11:04:10 INFO - PROCESS | 2561 | 11:04:10 INFO - PROCESS | 2561 | [#2] jquery.html Cycles:180 Average:32327.63 Median:370.57 stddev:89079.78 (24038.6%) stddev-sans-first:89297.35 11:04:10 INFO - PROCESS | 2561 | Values: 369.9 371.2 375.3 388.6 401.2 17125.4 18694.2 18800.7 19012.9 19208.7 18573.8 19297.3 19541.7 19614.7 19708.8 4765.9 5604.4 5751.8 5958.1 5961.6 5710.0 5892.6 5919.9 5926.0 5929.9 5685.3 5819.9 5867.8 5877.4 6004.8 6012.3 6041.3 6062.7 6074.1 6079.4 5817.1 5899.9 5967.0 6018.2 6042.8 12640.3 13078.3 13330.5 20203.7 23755.6 2160.4 2209.0 2300.7 2481.0 2568.8 9437.2 9953.6 10101.5 10249.4 10306.4 1856.7 1868.3 1868.4 1897.7 1899.8 112565.4 119821.4 123582.4 125051.0 125721.7 11241.4 11519.4 11609.2 11639.4 11793.4 14583.2 14591.3 14593.4 14596.4 14624.0 7002.6 7061.4 7086.4 8107.0 8387.6 7005.0 8287.3 8332.5 8362.0 8387.6 4400.5 4434.0 4468.6 4508.2 4511.2 1677.2 1692.7 1870.8 1891.6 1924.8 813.5 890.7 924.6 942.2 982.7 19195.9 19225.4 19349.7 19888.7 20233.0 943.9 957.5 963.1 974.1 987.1 9411.3 10037.7 10673.1 10720.2 10728.7 506282.0 507280.0 509307.9 559789.0 565126.0 78333.0 120686.0 126735.0 130894.0 132137.0 972.7 985.2 1013.8 1018.3 1025.1 17918.6 18425.0 18791.9 18839.8 19228.4 50765.0 51068.5 51693.5 51922.7 54328.0 66099.3 73961.6 75801.0 76195.9 76373.0 7668.2 7913.6 8098.1 8141.5 8323.4 11779.8 12081.8 12684.2 12819.6 12963.7 9168.8 10876.7 11019.2 11060.3 11278.4 9235.4 10838.7 10857.8 11088.3 11145.9 10941.1 11143.2 11217.9 11471.5 11696.1 8010.5 8192.6 8227.8 8231.1 8350.1 8141.1 9800.5 10953.7 10979.0 11458.9 11:04:10 INFO - PROCESS | 2561 | 11:04:10 INFO - PROCESS | 2561 | [#3] mootools.html Cycles:180 Average:5447.13 Median:5121.68 stddev:2974.43 (58.1%) stddev-sans-first:2967.75 11:04:10 INFO - PROCESS | 2561 | Values: 1469.7 1512.2 1686.6 1765.4 1811.3 7608.1 7877.2 8120.5 8544.4 8569.4 8018.0 8294.5 8325.8 8414.6 8434.8 2310.8 2346.1 2349.0 2351.0 2358.6 2337.6 2367.8 2378.3 2378.6 2379.9 2249.8 2255.5 2279.1 2279.7 2283.6 2320.5 2325.1 2330.1 2341.3 2351.2 2337.8 2360.5 2368.3 2370.3 2375.1 8326.8 8407.7 8465.1 8490.0 8506.5 4988.5 5072.6 5204.9 5253.7 5276.2 3377.3 3484.4 3620.9 3631.9 3644.2 1049.8 1050.5 1093.5 1106.9 1109.3 10044.9 10248.3 11058.2 11089.8 11102.8 4479.7 4606.1 4687.2 4697.3 4758.7 9416.0 9497.0 9499.0 9499.2 9511.5 5170.8 5189.5 5190.1 5211.1 5234.3 3780.1 4047.0 4404.3 4721.6 4728.2 3790.5 3825.1 3871.5 3892.4 3899.4 1274.1 1290.5 1291.6 1302.3 1305.4 752.4 849.4 884.2 895.8 903.7 5597.1 5680.3 5704.9 5708.4 5889.6 894.8 899.2 901.5 941.4 949.7 3571.5 3633.0 3851.9 3866.6 3938.3 9937.5 10197.2 10302.4 10311.3 10372.8 9884.9 10148.3 10269.7 10274.3 10290.9 7497.1 7864.6 7899.6 7930.8 7940.4 4846.9 5493.8 5513.8 5570.2 5690.8 9234.8 9238.2 9317.6 9341.2 9389.3 7973.4 9355.0 9431.1 9476.1 9535.1 5803.8 6318.4 6531.3 6612.8 6896.2 7878.0 7885.5 7960.4 8072.9 8077.7 7130.9 7221.2 7252.9 7260.0 7315.7 6961.7 7089.4 7115.7 7178.6 7226.6 6718.0 6723.7 6731.4 6815.1 6827.7 4243.7 4884.8 4909.8 4982.0 4989.8 4730.8 4817.4 4854.6 4987.8 5019.8 11:04:10 INFO - PROCESS | 2561 | 11:04:10 INFO - PROCESS | 2561 | [#4] prototype.html Cycles:180 Average:4602.52 Median:5247.24 stddev:1438.20 (27.4%) stddev-sans-first:1405.51 11:04:10 INFO - PROCESS | 2561 | Values: 300.0 300.6 303.5 307.5 310.2 4568.8 5407.4 5414.0 5451.8 5509.0 4968.7 5369.8 5492.5 5533.8 5568.3 3378.5 3519.6 3551.9 3805.2 3888.1 3524.9 3608.7 4144.9 4225.9 4233.0 3590.6 3638.8 4050.2 4161.2 4181.3 3673.7 4110.4 4133.8 4147.8 4164.4 3405.1 3528.5 3648.3 3648.9 3681.9 5246.8 5315.4 5370.5 5408.6 5416.5 4215.1 4426.8 4440.3 4776.4 4800.6 5424.3 5502.2 5832.5 5876.4 5881.4 3847.9 3864.6 3973.8 4015.7 4042.4 5759.3 6081.6 6101.4 6115.8 6171.0 3628.6 3661.7 3901.3 4113.4 4131.9 3573.6 4010.6 4089.0 4151.5 4163.9 4378.5 4442.2 4496.6 4758.4 4782.2 5756.3 5793.4 5806.5 5850.5 5900.5 1219.9 1270.9 1278.6 1305.4 1321.7 828.7 861.5 889.9 917.9 926.4 4665.1 4729.7 5225.5 5247.7 5299.3 5119.9 5243.5 5423.1 5666.0 5683.9 4917.2 4946.2 4947.8 5028.6 5179.8 2652.5 2775.4 2804.0 3014.6 3037.7 5178.3 5876.5 5945.7 6008.5 6019.6 4620.7 5807.1 5833.9 5896.2 5922.6 5001.5 5226.4 5370.8 5931.3 5949.4 5143.7 5476.3 5594.0 5669.9 5762.5 4933.7 5303.9 5597.7 5667.0 5691.8 5379.7 5425.7 5943.0 5956.1 6067.0 3618.5 3631.6 3700.8 3719.1 3759.6 5310.8 5741.2 5806.8 5994.3 6044.8 5683.2 5764.9 5854.3 5871.0 5879.3 4612.9 5290.2 5849.6 5852.5 5892.5 5411.7 5646.0 5653.2 5668.0 5750.3 5679.7 5841.2 5865.2 5876.5 5888.6 5089.3 5319.4 5359.8 5364.0 5784.8 11:04:10 INFO - PROCESS | 2561 | 11:04:10 INFO - PROCESS | 2561 | [#5] yui.html Cycles:180 Average:20396.62 Median:530.02 stddev:30541.62 (5762.3%) stddev-sans-first:30591.25 11:04:10 INFO - PROCESS | 2561 | Values: 634.5 676.5 690.5 724.2 731.2 13737.1 14487.1 14599.1 14660.5 14662.0 12920.0 13152.5 13205.5 13345.3 13359.1 666.9 814.5 817.7 818.3 820.0 648.6 667.5 804.4 816.9 819.3 852.3 867.2 867.3 867.8 868.2 829.2 864.2 865.0 865.1 868.0 631.1 640.3 642.1 642.1 643.5 13473.3 14170.9 14188.9 14301.9 14354.6 12673.7 13312.3 13403.5 13425.7 13457.2 11039.0 11207.3 11375.0 11534.4 11669.8 3458.2 3628.8 3673.8 3674.7 3791.6 76727.0 77329.8 78433.6 88127.1 92489.5 10395.5 10566.4 10615.5 10616.8 10684.0 19489.4 19673.8 19741.8 19877.5 19921.6 7595.4 7606.6 7612.0 7682.7 7772.3 4166.5 4257.7 4324.1 4381.3 4383.0 3385.0 3487.5 3511.3 3515.4 3515.6 623.8 624.6 625.6 632.9 638.6 497.3 562.7 578.4 641.1 644.0 8694.3 8900.0 8947.9 9046.2 9275.2 574.5 576.3 579.9 614.9 637.9 3785.5 4187.7 4339.8 4351.5 4472.6 90193.6 94619.5 107651.5 119288.0 119373.4 67840.6 98215.0 101820.0 105547.5 107220.9 88919.0 90877.5 91325.0 93430.5 95443.0 8214.7 8508.4 8550.8 8681.9 8762.7 43936.6 57874.4 58260.0 59099.7 59504.4 68293.0 82610.0 82898.3 83467.6 84204.6 10705.7 10733.5 10888.6 11065.4 11188.2 11954.4 12058.5 12149.9 12199.7 12269.8 10808.0 11536.2 11652.2 11680.5 11715.0 11184.1 11442.2 11580.2 11744.9 11748.7 11341.1 11368.6 11485.9 11519.3 11847.9 9400.8 9880.0 10243.6 10449.3 10568.0 8904.6 9844.8 9905.8 9915.2 9932.7 11:04:10 INFO - PROCESS | 2561 | -------- Summary: end -------- 11:04:10 INFO - PROCESS | 2561 | 11:04:10 INFO - TEST-INFO | 2561: exit 0 11:04:10 INFO - TEST-OK | dromaeo_css | took 1116237ms 11:04:10 INFO - TEST-START | kraken 11:04:10 INFO - operating with platform_type : mac_ 11:04:10 INFO - Initialising browser for kraken test... 11:04:10 INFO - TEST-INFO | started process 2565 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpwlAEl2/profile http://localhost:51878/getInfo.html) 11:04:12 ERROR - PROCESS | 2565 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 11:04:13 INFO - PROCESS | 2565 | __metrics Screen width/height:1600/1200 11:04:13 INFO - PROCESS | 2565 | colorDepth:24 11:04:13 INFO - PROCESS | 2565 | Browser inner width/height: 1024/689 11:04:13 INFO - PROCESS | 2565 | __metrics 11:04:13 ERROR - PROCESS | 2565 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 11:04:14 INFO - TEST-INFO | 2565: exit 0 11:04:14 INFO - Browser initialized. 11:04:14 INFO - Running cycle 1/1 for kraken test... 11:04:14 INFO - TEST-INFO | started process 2567 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpwlAEl2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:04:26 INFO - PROCESS | 2567 | RSS: Main: 224333824 11:04:26 INFO - PROCESS | 2567 | 11:04:29 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:51878/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51878/tests/kraken/driver.html?testName=audio-beat-detection) 11:04:30 INFO - PROCESS | 2567 | RSS: Main: 236703744 11:04:30 INFO - PROCESS | 2567 | 11:04:32 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:51878/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51878/tests/kraken/driver.html?testName=audio-dft) 11:04:33 INFO - PROCESS | 2567 | RSS: Main: 244301824 11:04:33 INFO - PROCESS | 2567 | 11:04:36 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:51878/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51878/tests/kraken/driver.html?testName=audio-fft) 11:04:37 INFO - PROCESS | 2567 | RSS: Main: 247902208 11:04:37 INFO - PROCESS | 2567 | 11:04:39 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:51878/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51878/tests/kraken/driver.html?testName=audio-oscillator) 11:04:40 INFO - PROCESS | 2567 | RSS: Main: 249208832 11:04:40 INFO - PROCESS | 2567 | 11:04:42 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:51878/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51878/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:04:42 INFO - PROCESS | 2567 | RSS: Main: 249610240 11:04:42 INFO - PROCESS | 2567 | 11:04:45 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:51878/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51878/tests/kraken/driver.html?testName=imaging-darkroom) 11:04:46 INFO - PROCESS | 2567 | RSS: Main: 253538304 11:04:46 INFO - PROCESS | 2567 | 11:04:49 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:51878/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51878/tests/kraken/driver.html?testName=imaging-desaturate) 11:04:50 INFO - PROCESS | 2567 | RSS: Main: 252674048 11:04:50 INFO - PROCESS | 2567 | 11:04:52 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:51878/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51878/tests/kraken/driver.html?testName=json-parse-financial) 11:04:53 INFO - PROCESS | 2567 | RSS: Main: 252866560 11:04:53 INFO - PROCESS | 2567 | 11:04:55 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:51878/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51878/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:04:55 INFO - PROCESS | 2567 | RSS: Main: 252035072 11:04:55 INFO - PROCESS | 2567 | 11:04:57 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:51878/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51878/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:04:58 INFO - PROCESS | 2567 | RSS: Main: 252088320 11:04:58 INFO - PROCESS | 2567 | 11:05:00 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:51878/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51878/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:05:01 INFO - PROCESS | 2567 | RSS: Main: 252035072 11:05:01 INFO - PROCESS | 2567 | 11:05:04 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:51878/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51878/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:05:05 INFO - PROCESS | 2567 | RSS: Main: 252588032 11:05:05 INFO - PROCESS | 2567 | 11:05:08 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:51878/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51878/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:05:08 INFO - PROCESS | 2567 | RSS: Main: 252395520 11:05:08 INFO - PROCESS | 2567 | 11:05:10 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:51878/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51878/tests/kraken/driver.html?testName=ai-astar) 11:05:10 INFO - PROCESS | 2567 | RSS: Main: 254251008 11:05:10 INFO - PROCESS | 2567 | 11:05:10 INFO - PROCESS | 2567 | __start_tp_report 11:05:10 INFO - PROCESS | 2567 | _x_x_mozilla_page_load 11:05:10 INFO - PROCESS | 2567 | _x_x_mozilla_page_load_details 11:05:10 INFO - PROCESS | 2567 | |i|pagename|runs| 11:05:10 INFO - PROCESS | 2567 | |0;ai-astar;107;118;117;117;117;118;119;117;118;117 11:05:10 INFO - PROCESS | 2567 | |1;audio-beat-detection;173;109;110;179;143;137;115;114;143;114 11:05:10 INFO - PROCESS | 2567 | |2;audio-dft;200;227;195;271;210;205;199;197;196;202 11:05:10 INFO - PROCESS | 2567 | |3;audio-fft;83;59;60;84;62;60;81;52;92;56 11:05:10 INFO - PROCESS | 2567 | |4;audio-oscillator;75;87;111;98;96;97;89;68;70;85 11:05:10 INFO - PROCESS | 2567 | |5;imaging-gaussian-blur;92;98;113;88;129;99;93;92;92;90 11:05:10 INFO - PROCESS | 2567 | |6;imaging-darkroom;98;98;97;92;96;93;142;143;123;97 11:05:10 INFO - PROCESS | 2567 | |7;imaging-desaturate;118;92;137;78;89;91;92;94;136;91 11:05:10 INFO - PROCESS | 2567 | |8;json-parse-financial;55;56;57;57;61;60;60;59;57;59 11:05:10 INFO - PROCESS | 2567 | |9;json-stringify-tinderbox;69;76;69;78;75;74;64;77;91;65 11:05:10 INFO - PROCESS | 2567 | |10;stanford-crypto-aes;86;96;80;100;103;98;97;86;81;78 11:05:10 INFO - PROCESS | 2567 | |11;stanford-crypto-ccm;146;153;149;168;160;155;160;154;204;147 11:05:10 INFO - PROCESS | 2567 | |12;stanford-crypto-pbkdf2;166;212;216;156;218;218;172;171;168;170 11:05:10 INFO - PROCESS | 2567 | |13;stanford-crypto-sha256-iterative;67;68;69;69;67;68;70;78;85;85 11:05:10 INFO - PROCESS | 2567 | __end_tp_report 11:05:10 INFO - PROCESS | 2567 | __start_cc_report 11:05:10 INFO - PROCESS | 2567 | _x_x_mozilla_cycle_collect,998 11:05:10 INFO - PROCESS | 2567 | __end_cc_report 11:05:10 INFO - PROCESS | 2567 | __startTimestamp1479150310708__endTimestamp 11:05:10 INFO - PROCESS | 2567 | 11:05:10 INFO - PROCESS | 2567 | ------- Summary: start ------- 11:05:10 INFO - PROCESS | 2567 | Number of tests: 14 11:05:10 INFO - PROCESS | 2567 | 11:05:10 INFO - PROCESS | 2567 | [#0] ai-astar Cycles:10 Average:116.50 Median:117.50 stddev:3.41 (2.9%) stddev-sans-first:0.73 11:05:10 INFO - PROCESS | 2567 | Values: 107.0 118.0 117.0 117.0 117.0 118.0 119.0 117.0 118.0 117.0 11:05:10 INFO - PROCESS | 2567 | 11:05:10 INFO - PROCESS | 2567 | [#1] audio-beat-detection Cycles:10 Average:133.70 Median:140.00 stddev:25.99 (18.6%) stddev-sans-first:23.35 11:05:10 INFO - PROCESS | 2567 | Values: 173.0 109.0 110.0 179.0 143.0 137.0 115.0 114.0 143.0 114.0 11:05:10 INFO - PROCESS | 2567 | 11:05:10 INFO - PROCESS | 2567 | [#2] audio-dft Cycles:10 Average:210.20 Median:203.50 stddev:23.36 (11.5%) stddev-sans-first:24.48 11:05:10 INFO - PROCESS | 2567 | Values: 200.0 227.0 195.0 271.0 210.0 205.0 199.0 197.0 196.0 202.0 11:05:10 INFO - PROCESS | 2567 | 11:05:10 INFO - PROCESS | 2567 | [#3] audio-fft Cycles:10 Average:68.90 Median:71.50 stddev:14.39 (20.1%) stddev-sans-first:14.33 11:05:10 INFO - PROCESS | 2567 | Values: 83.0 59.0 60.0 84.0 62.0 60.0 81.0 52.0 92.0 56.0 11:05:10 INFO - PROCESS | 2567 | 11:05:10 INFO - PROCESS | 2567 | [#4] audio-oscillator Cycles:10 Average:87.60 Median:88.00 stddev:13.65 (15.5%) stddev-sans-first:13.69 11:05:10 INFO - PROCESS | 2567 | Values: 75.0 87.0 111.0 98.0 96.0 97.0 89.0 68.0 70.0 85.0 11:05:10 INFO - PROCESS | 2567 | 11:05:10 INFO - PROCESS | 2567 | [#5] imaging-gaussian-blur Cycles:10 Average:98.60 Median:92.00 stddev:12.83 (13.9%) stddev-sans-first:13.38 11:05:10 INFO - PROCESS | 2567 | Values: 92.0 98.0 113.0 88.0 129.0 99.0 93.0 92.0 92.0 90.0 11:05:10 INFO - PROCESS | 2567 | 11:05:10 INFO - PROCESS | 2567 | [#6] imaging-darkroom Cycles:10 Average:107.90 Median:96.50 stddev:20.20 (20.9%) stddev-sans-first:21.11 11:05:10 INFO - PROCESS | 2567 | Values: 98.0 98.0 97.0 92.0 96.0 93.0 142.0 143.0 123.0 97.0 11:05:10 INFO - PROCESS | 2567 | 11:05:10 INFO - PROCESS | 2567 | [#7] imaging-desaturate Cycles:10 Average:101.80 Median:91.00 stddev:20.78 (22.8%) stddev-sans-first:21.20 11:05:10 INFO - PROCESS | 2567 | Values: 118.0 92.0 137.0 78.0 89.0 91.0 92.0 94.0 136.0 91.0 11:05:10 INFO - PROCESS | 2567 | 11:05:10 INFO - PROCESS | 2567 | [#8] json-parse-financial Cycles:10 Average:58.10 Median:59.00 stddev:1.97 (3.3%) stddev-sans-first:1.74 11:05:10 INFO - PROCESS | 2567 | Values: 55.0 56.0 57.0 57.0 61.0 60.0 60.0 59.0 57.0 59.0 11:05:10 INFO - PROCESS | 2567 | 11:05:10 INFO - PROCESS | 2567 | [#9] json-stringify-tinderbox Cycles:10 Average:73.80 Median:75.50 stddev:7.81 (10.4%) stddev-sans-first:8.09 11:05:10 INFO - PROCESS | 2567 | Values: 69.0 76.0 69.0 78.0 75.0 74.0 64.0 77.0 91.0 65.0 11:05:10 INFO - PROCESS | 2567 | 11:05:10 INFO - PROCESS | 2567 | [#10] stanford-crypto-aes Cycles:10 Average:90.50 Median:86.00 stddev:9.26 (10.8%) stddev-sans-first:9.68 11:05:10 INFO - PROCESS | 2567 | Values: 86.0 96.0 80.0 100.0 103.0 98.0 97.0 86.0 81.0 78.0 11:05:10 INFO - PROCESS | 2567 | 11:05:10 INFO - PROCESS | 2567 | [#11] stanford-crypto-ccm Cycles:10 Average:159.60 Median:157.50 stddev:16.98 (10.8%) stddev-sans-first:17.28 11:05:10 INFO - PROCESS | 2567 | Values: 146.0 153.0 149.0 168.0 160.0 155.0 160.0 154.0 204.0 147.0 11:05:10 INFO - PROCESS | 2567 | 11:05:10 INFO - PROCESS | 2567 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.70 Median:192.00 stddev:25.65 (13.4%) stddev-sans-first:26.09 11:05:10 INFO - PROCESS | 2567 | Values: 166.0 212.0 216.0 156.0 218.0 218.0 172.0 171.0 168.0 170.0 11:05:10 INFO - PROCESS | 2567 | 11:05:10 INFO - PROCESS | 2567 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.60 Median:69.50 stddev:7.26 (10.4%) stddev-sans-first:7.41 11:05:10 INFO - PROCESS | 2567 | Values: 67.0 68.0 69.0 69.0 67.0 68.0 70.0 78.0 85.0 85.0 11:05:10 INFO - PROCESS | 2567 | -------- Summary: end -------- 11:05:10 INFO - PROCESS | 2567 | 11:05:11 INFO - TEST-INFO | 2567: exit 0 11:05:11 INFO - TEST-OK | kraken | took 60918ms 11:05:11 INFO - SUITE-END | took 1177s 11:05:11 INFO - Completed test suite (00:19:37) 11:05:11 INFO - javascript benchmark 11:05:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7211.022026679435, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3383.847726852293, 3422.2299982503923, 3426.486027095936, 3428.5200071989925, 3488.3546543889365, 5892.410758924106, 5910.940890591093, 5916.142159386886, 5985.431384018522, 6013.037913933771, 5951.851203719898, 5959.493443057335, 6045.274567051949, 6049.1228771828055, 6050.485708714765, 5351.117065684158, 5369.5167434930845, 5430.049741295277, 5432.6468779529205, 5512.751926163332, 4602.6087782538525, 5459.481349271813, 5485.506304432601, 5488.91766623501, 5516.310461192351, 5342.866428339303, 5349.331333583302, 5479.287692599956, 5485.6434331768505, 5523.420040895709, 5390.0, 5436.293281873351, 5438.428964958702, 5452.809151679672, 5454.863628409322, 5282.0, 5345.652532585408, 5349.465053494659, 5360.410354860962, 5373.35519737634, 6035.849103772441, 6056.909146362764, 6064.878702425971, 6126.571140020201, 6145.139680444741, 5472.616916815825, 6084.965555855515, 6125.846853828645, 6241.625502469867, 6271.717772700239, 6149.385061493815, 6219.440250377443, 6224.502039836802, 6244.344343843876, 6288.591241569284, 6047.576669633171, 6065.8180254592435, 6100.533434559381, 6105.32841387447, 6123.4795042421, 5335.306410166653, 5788.739506722208, 6469.935300646934, 6486.189226346707, 6508.730797494443, 5327.706976116351, 6140.723667434976, 6160.322364539897, 6167.691615419211, 6192.380761923861, 6233.081210191065, 6263.6241825490615, 6307.653079080603, 6312.084747711556, 6315.778947736807, 6118.497354392036, 6119.44924956756, 6142.262928448615, 6223.346548612421, 6255.718492667841, 6091.299500557404, 6109.283207920342, 6119.877602448015, 6154.076888466766, 6163.137160797492, 4484.955150448453, 4927.507249275115, 4982.10322142018, 5002.074616196015, 5015.523525265094, 4504.234280172313, 4519.457665080211, 4547.886302842389, 4576.725396476222, 4577.633789296915, 6050.364711705296, 6059.151718759377, 6069.3020302665755, 6099.115628233882, 6123.6486823095165, 6177.073438984098, 6330.335314791972, 6338.366163383717, 6342.016987366966, 6376.139221205078, 6128.264608247039, 6130.141780150782, 6136.693165341715, 6143.139960405547, 6163.7842675506145, 5319.175527793198, 5339.679619222859, 5390.056740070472, 5434.9945260126215, 5526.806561770319, 6403.77586784451, 6483.384078512534, 6519.511036672174, 6541.672916354068, 6556.508261880283, 6312.0, 6314.147590075465, 6316.336784637547, 6332.176817013758, 6349.841253968688, 6220.440160385587, 6294.0, 6464.095026696173, 6514.771982980573, 6537.411632953057, 6147.96926015385, 6159.0, 6181.443670069715, 6240.625562466266, 6280.717367718372, 6295.5907865988565, 6300.432961033529, 6302.747890084345, 6311.779087731908, 6321.30465648769, 6037.426444487766, 6086.573939824169, 6160.568760186744, 6182.412670796267, 6184.752609895554, 6101.359357267423, 6115.483736156635, 6131.141640170291, 6133.120447061136, 6138.079288106641, 5519.923614895057, 5524.977879092381, 6158.414950579688, 6184.0, 6203.720832562636, 6125.84685382869, 6144.723487442986, 6152.692365381802, 6172.0, 6180.196574445294, 5631.774729010794, 6047.485963693135, 6078.939210608014, 6138.969305153624, 6168.321484636596], "value": 5806.548532719713, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.5234765234765, 481.7567128600056, 509.16496945010215, 522.6968358352157, 524.546267478631, 39014.0, 40601.98495037625, 40695.0, 42515.57484425155, 42530.36204456936, 34131.31737365251, 41504.58495415045, 41781.58218417815, 41869.95325116866, 41941.0, 9374.625014999408, 9457.054294570522, 9532.571034303473, 9553.187979021792, 9566.0, 9456.905430945671, 9499.762505937339, 9505.097015783489, 9544.713658590254, 9561.139497445263, 8443.915560844405, 9510.47692376919, 9513.476758778264, 9525.0, 9529.285303602222, 9449.905500944971, 9469.24246060317, 9538.141567258945, 9549.28380371468, 9550.331476796628, 9409.435433873921, 9503.57233924475, 9510.762230944212, 9520.666776662783, 9538.236941044699, 39355.40966885499, 39940.800295998335, 40071.79784606466, 40229.79310620686, 40274.59725402767, 3115.7818952673324, 3310.4041272570926, 3312.3540909522653, 3329.334133173351, 3607.9639203608153, 28356.29109272264, 29716.257093572618, 29855.850720746475, 31040.689593104005, 32003.359932801446, 3127.233827712202, 3150.5746724192168, 3159.810411375302, 3247.788893721924, 3249.6425393206728, 403569.0, 481824.0, 485082.0, 496424.0, 496703.0, 65962.0, 66133.33866661272, 66139.0, 66334.33665663282, 66496.66751666211, 69751.95372069423, 69771.65114174498, 70190.0, 70476.94284585735, 70568.6471567639, 16405.343786248653, 16516.50450486487, 16542.338306467846, 16579.66840663204, 16583.83416165847, 12718.364081795873, 12799.488020479077, 12820.743585128244, 12830.486780528674, 12866.806997895039, 19804.70292945607, 20135.597288054156, 20161.899190503955, 20163.39509814708, 20171.495712607302, 7212.819679508054, 8399.11809260031, 8428.578571071423, 8497.36269779769, 8557.44376615518, 842.2377748137945, 933.2627224492575, 967.1295833749682, 970.45169479244, 1026.7997740440542, 29619.111426657233, 30131.246718832204, 30210.54684179739, 30291.848540757153, 30661.69338306633, 947.3463310315998, 983.4345251480428, 1007.4811472091878, 1045.7960697663882, 1140.190464770006, 13897.513587024405, 15493.767593486107, 15579.376824927103, 15605.30688208202, 15800.92099539495, 648818.0, 657183.0, 658672.0, 659192.0, 660003.0, 57285.42714572884, 57661.0, 58309.0, 58454.0, 58508.70745646244, 1342.3422522963872, 1370.5751217122724, 1379.7792353223435, 1390.8400533938727, 1393.1292941911306, 26261.0, 29136.0, 29552.261193470335, 29967.100986970425, 30401.239969000068, 42219.57780422157, 42484.38882695005, 50844.0, 50862.491375085774, 51093.0, 575564.0, 575899.1205043948, 578765.0, 579675.0, 580482.0, 35717.28565428729, 42826.0, 43392.91517711956, 43830.561694382646, 44298.0, 38508.80745596254, 46197.53802461932, 46246.53753462557, 46353.768231158625, 47791.76104119596, 32177.517337239493, 39113.8044309788, 39268.01829954274, 39374.409383859835, 39757.403638946016, 38373.808130959165, 38776.806115970365, 38805.805970971094, 39751.0, 39919.80040099781, 37674.246515070095, 38056.0, 38605.0, 38676.80661596674, 39526.01184970399, 32309.838450807594, 34421.48367774535, 34924.65075349214, 35335.64664353323, 35357.46963795597, 22363.0, 25823.0961916332, 26105.955761769317, 26664.60003099994, 27155.592666109627], "value": 20271.936302420952, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.94450832375156, 371.196359880858, 375.2813362410983, 388.57062945445284, 401.1936008622669, 17125.38834348204, 18694.15876285567, 18800.717989230172, 19012.904935475337, 19208.711869321938, 18573.814261857377, 19297.324593639227, 19541.706874396896, 19614.705779413285, 19708.802911970877, 4765.880852978686, 5604.41153678864, 5751.827445176641, 5958.136070269808, 5961.642301461904, 5710.029295019835, 5892.646441213541, 5919.911201331984, 5925.970370148143, 5929.8814023719715, 5685.346185188715, 5819.883602327951, 5867.794627188049, 5877.353491115995, 6004.849878753044, 6012.278526576795, 6041.305249896274, 6062.727177277033, 6074.058520929263, 6079.422454866803, 5817.0983497557945, 5899.941000589982, 5967.045272756338, 6018.15745795589, 6042.848928776772, 12640.304783236918, 13078.280694561796, 13330.533431329857, 20203.696944545845, 23755.643665345033, 2160.4274867160216, 2208.9558208835892, 2300.7009088818513, 2481.019997101135, 2568.7688108070365, 9437.150656440885, 9953.552090155961, 10101.494925253708, 10249.385036897735, 10306.433146176958, 1856.7400563921062, 1868.2993877296014, 1868.3647559829724, 1897.667908116088, 1899.848012159024, 112565.43717281443, 119821.40089299498, 123582.38208808989, 125051.0, 125721.74278257284, 11241.437928103562, 11519.424028798527, 11609.245399049034, 11639.41802909868, 11793.351365674798, 14583.197924114063, 14591.270436478133, 14593.41626334956, 14596.416143354147, 14624.0, 7002.614856182959, 7061.399781018568, 7086.433085353159, 8107.0, 8387.62255698495, 7004.964975175091, 8287.254147126787, 8332.50004999702, 8361.95819020901, 8387.580620968927, 4400.515943246241, 4434.0, 4468.642508599304, 4508.165989291926, 4511.150428324371, 1677.2117104960648, 1692.7291633338607, 1870.78485974112, 1891.6027634196973, 1924.8171423715005, 813.5037627047496, 890.7461373508648, 924.5608336040461, 942.2273735536932, 982.6757170133839, 19195.904020479808, 19225.423237302624, 19349.709754353695, 19888.70166947497, 20233.0, 943.8678584998106, 957.482959202037, 963.0754475703263, 974.1038244814788, 987.1165307050294, 9411.341206115643, 10037.69886903394, 10673.146148308271, 10720.195985301134, 10728.678139655824, 506282.0, 507280.0, 509307.90692092606, 559789.0, 565126.0, 78333.0, 120686.0, 126735.0, 130894.0, 132137.0, 972.6595691508057, 985.1724551376877, 1013.8023085498257, 1018.251585084962, 1025.0671888581355, 17918.5520361992, 18425.0, 18791.90604046971, 18839.811601883805, 19228.423147305602, 50764.984700305045, 51068.46794596168, 51693.48306516887, 51922.740386297824, 54328.0, 66099.33900660931, 73961.63019185085, 75801.0, 76195.857062143, 76373.0, 7668.233176682287, 7913.643886025257, 8098.068722097034, 8141.470804397695, 8323.417360784686, 11779.823302650639, 12081.818772718241, 12684.175528590613, 12819.551315703906, 12963.740725185633, 9168.770780730536, 10876.728081798019, 11019.228653994463, 11060.336379817236, 11278.436078195893, 9235.445873247627, 10838.67483975482, 10857.782844343226, 11088.33469991803, 11145.944270278596, 10941.124710023338, 11143.164262680493, 11217.943910280397, 11471.541138354372, 11696.06431485462, 8010.479318844256, 8192.631331589973, 8227.83544329122, 8231.135730748185, 8350.08149103586, 8141.063777665644, 9800.509974501389, 10953.671389858317, 10979.0, 11458.94270528642], "value": 9249.823074177899, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1469.7134058858524, 1512.1531942112417, 1686.6289416328411, 1765.3821162593085, 1811.315476606756, 7608.125065617442, 7877.212278772135, 8120.472169308998, 8544.401891867572, 8569.357298202636, 8018.037835459739, 8294.502329860212, 8325.75022749317, 8414.579271036455, 8434.831303373929, 2310.803581695558, 2346.1319311854636, 2349.001674288429, 2350.9647355289685, 2358.575456417844, 2337.567550003246, 2367.7868991790733, 2378.3459548624182, 2378.6432035194684, 2379.8691071991034, 2249.7750224977453, 2255.5150642611834, 2279.111146652807, 2279.7150356205475, 2283.6460348645987, 2320.524292520029, 2325.1164557468137, 2330.0563271874835, 2341.3210169050953, 2351.2240960483, 2337.7779110984598, 2360.4688944987242, 2368.3487041063845, 2370.2533701883854, 2375.0618505690327, 8326.791830204233, 8407.70573029941, 8465.068842427328, 8490.023647280581, 8506.532140732257, 4988.5260900214425, 5072.594192464631, 5204.895902081975, 5253.737313134328, 5276.234945932857, 3377.307651931348, 3484.372812893678, 3620.9275814483826, 3631.9467354467174, 3644.1618427761764, 1049.826778581533, 1050.5430137889966, 1093.5024563823442, 1106.878243393226, 1109.2567979453704, 10044.94977525115, 10248.333858299187, 11058.22592418531, 11089.83365249522, 11102.777944441146, 4479.686421950432, 4606.055758569485, 4687.179743544866, 4697.295405689174, 4758.690685105457, 9415.952920235492, 9497.002814704494, 9499.002604726542, 9499.192568631606, 9511.476868772284, 5170.767315470813, 5189.506996835295, 5190.065788158092, 5211.062008838446, 5234.2933703949475, 3780.1305699688673, 4046.979765101156, 4404.317330813729, 4721.598664113589, 4728.172569800271, 3790.50723405961, 3825.062859599416, 3871.4773505576386, 3892.3772196449, 3899.3760998240145, 1274.1463219642676, 1290.5483080921601, 1291.5802364231663, 1302.2707283921034, 1305.3864683598692, 752.3717695724022, 849.4351256414416, 884.1688812516214, 895.8118795052982, 903.742433406476, 5597.132444471114, 5680.346760122557, 5704.942950570524, 5708.429157084258, 5889.5877288590245, 894.7986702991905, 899.2311573604604, 901.454619954931, 941.3880977364768, 949.6581230756922, 3571.5178450909316, 3632.9636703632627, 3851.9229615407535, 3866.6326698964117, 3938.2714197873483, 9937.503124843728, 10197.18422526211, 10302.433366164783, 10311.278210525341, 10372.844407333896, 9884.901150988399, 10148.340357876861, 10269.691909242734, 10274.33216840903, 10290.948545257226, 7497.1378291496085, 7864.567448790257, 7899.565523896126, 7930.841383172305, 7940.364770818321, 4846.903061938812, 5493.752781124939, 5513.842093160481, 5570.164475328733, 5690.77236910519, 9234.769130771516, 9238.214751746176, 9317.580708868252, 9341.20599749029, 9389.295802814953, 7973.44185906981, 9355.0, 9431.104045115702, 9476.099770521789, 9535.141837234682, 5803.7968671096305, 6318.399752023551, 6531.281559028408, 6612.768553100618, 6896.172459304916, 7878.0, 7885.487443316166, 7960.402969777406, 8072.919270807217, 8077.717279895175, 7130.928690713026, 7221.205667376689, 7252.854942901007, 7260.019897314006, 7315.670794814139, 6961.6519174042105, 7089.397401220747, 7115.715371385087, 7178.641067946686, 7226.638668066471, 6717.966410167918, 6723.731050757915, 6731.394174524316, 6815.079964204769, 6827.726890924506, 4243.724157929724, 4884.804607815648, 4909.803607855646, 4982.0, 4989.80040798364, 4730.834420795393, 4817.397825271841, 4854.611631069435, 4987.825426110068, 5019.799208031784], "value": 4428.221524177375, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.9670036296007, 300.6272222444169, 303.4871067895258, 307.48035819465105, 310.23681742912436, 4568.840090596821, 5407.405185429599, 5414.0, 5451.836444906649, 5509.0, 4968.677035992658, 5369.838904832852, 5492.505674489295, 5533.806316778913, 5568.276124103871, 3378.476336167898, 3519.630438803927, 3551.8907479135846, 3805.2389522095555, 3888.1446081861945, 3524.894253172396, 3608.7008676876303, 4144.937825932613, 4225.936610950838, 4232.978835105836, 3590.605033446319, 3638.7634803737806, 4050.2102090092444, 4161.230172418097, 4181.268278051344, 3673.669369756708, 4110.403991421242, 4133.81397837098, 4147.751134931914, 4164.437800896871, 3405.1056601888445, 3528.4530897710897, 3648.2703459307977, 3648.9270214595826, 3681.889543313705, 5246.7638956247065, 5315.388730296027, 5370.462953704638, 5408.567314614821, 5416.458354164592, 4215.051613386994, 4426.84506042287, 4440.333949907507, 4776.355192049064, 4800.567948884583, 5424.294841670597, 5502.174673798922, 5832.504237139849, 5876.382979787104, 5881.44172775469, 3847.8653247136217, 3864.6328598783352, 3973.781442020688, 4015.738976966517, 4042.353223484258, 5759.272218334483, 6081.635101893857, 6101.359357267467, 6115.755369785204, 6170.96914515429, 3628.63713628635, 3661.6704496594903, 3901.3367727486398, 4113.424120623055, 4131.938020929689, 3573.5890372606964, 4010.5509558711046, 4088.9591104089172, 4151.522574903865, 4163.854265100707, 4378.5183629800695, 4442.200403927324, 4496.572825581565, 4758.357621721023, 4782.210935195653, 5756.251687280626, 5793.391693872167, 5806.535477161817, 5850.531957443394, 5900.468957793819, 1219.8706643151017, 1270.885620294178, 1278.6483716977718, 1305.4321370203802, 1321.6563693439775, 828.6933834481281, 861.521855370263, 889.915458031486, 917.926026548926, 926.4302453990747, 4665.113628410591, 4729.668923175344, 5225.503577160163, 5247.714309994076, 5299.33758280215, 5119.8720031998755, 5243.475652434802, 5423.05096608092, 5666.045214401025, 5683.943160568424, 4917.237828136645, 4946.23333383319, 4947.752612369367, 5028.572571331478, 5179.81870634526, 2652.4960257551, 2775.3764048032126, 2804.032608750019, 3014.5779590857296, 3037.726604605596, 5178.349263769034, 5876.500497457679, 5945.672987985616, 6008.519318454513, 6019.578629495981, 4620.735839438651, 5807.125472890862, 5833.883322333614, 5896.233489646404, 5922.644641321534, 5001.474550262133, 5226.398964119175, 5370.838874833683, 5931.347551769302, 5949.375315591887, 5143.742812859267, 5476.315460567429, 5593.972030139823, 5669.86602679474, 5762.481376676119, 4933.654644174873, 5303.893922121459, 5597.6641401516035, 5666.9716651416475, 5691.800786972602, 5379.73101344939, 5425.674459532441, 5942.9690857662035, 5956.106584012433, 6066.969665151646, 3618.4934109224205, 3631.6368363163474, 3700.777953322809, 3719.1260053887854, 3759.578783307177, 5310.761015754378, 5741.196232527533, 5806.82579522615, 5994.250718660168, 6044.788432404845, 5683.1759394886685, 5764.884702306014, 5854.268216472941, 5871.0, 5879.323877754113, 4612.907741845212, 5290.1800220965815, 5849.6197747148, 5852.502537284208, 5892.499137573354, 5411.675299482044, 5646.015519152605, 5653.208550802973, 5668.004057139789, 5750.338711048115, 5679.716014199356, 5841.18223448726, 5865.178874957424, 5876.471117599436, 5888.558358123244, 5089.312942752829, 5319.38827034896, 5359.837695759521, 5363.973180134074, 5784.797532086357], "value": 4137.183453867523, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [634.5462993959316, 676.458832933653, 690.4717890813527, 724.2214619284271, 731.217597171027, 13737.107088039284, 14487.130772153652, 14599.051061680982, 14660.486882959098, 14662.04696694714, 12920.030997675189, 13152.473901043946, 13205.537806176786, 13345.332733363317, 13359.131656442325, 666.8932970724672, 814.4665244265016, 817.709713051867, 818.3167055508658, 820.0118856777568, 648.6075924065943, 667.4560233409779, 804.3605333759675, 816.8774683797418, 819.3117781063905, 852.3096292003497, 867.1978419961542, 867.319154463746, 867.761365624452, 868.1708967935606, 829.2412442615, 864.1660797330543, 865.0398058155442, 865.0657290126649, 867.9869801952975, 631.0849268560605, 640.3212594649686, 642.0914406115342, 642.1202951955851, 643.5366536094004, 13473.258970756604, 14170.929145354206, 14188.92905535476, 14301.856981430157, 14354.569362919025, 12673.746525069539, 13312.267825269606, 13403.531863551669, 13425.66435839102, 13457.192568445924, 11039.0, 11207.327560346404, 11375.0, 11534.423278836024, 11669.766604667944, 3458.15275257561, 3628.8004159771203, 3673.81630918453, 3674.706023518112, 3791.6018818023986, 76727.0, 77329.8400523987, 78433.60783196047, 88127.11872881254, 92489.53755231248, 10395.480225988671, 10566.418846963337, 10615.52230149645, 10616.840747388795, 10684.038436540748, 19489.415317540497, 19673.803261967485, 19741.802581974283, 19877.503062423264, 19921.60156796856, 7595.354394876497, 7606.619669016527, 7612.0, 7682.692692292246, 7772.261635144652, 4166.479190101238, 4257.723247988871, 4324.113556720865, 4381.3208952612395, 4382.9780851096175, 3385.0, 3487.5466189395215, 3511.2801875615946, 3515.402381595135, 3515.5605549306338, 623.7910300049443, 624.6096189881324, 625.6214989931117, 632.908228306893, 638.5976834594176, 497.30625777040825, 562.7411390760296, 578.3695771608966, 641.0704478506185, 643.9806805795833, 8694.260987816106, 8899.955500222457, 8947.92931671164, 9046.231070358961, 9275.211607013345, 574.5087949802942, 576.2566289486599, 579.9449052340021, 614.8800983808114, 637.8883695353295, 3785.4511095891494, 4187.72779769314, 4339.7830108494445, 4351.5430879757805, 4472.642188624902, 90193.64709529362, 94619.52690236505, 107651.46174269078, 119288.0, 119373.40313298377, 67840.64318713598, 98215.0, 101820.0, 105547.4722626382, 107220.92779072265, 88919.0, 90877.54561227151, 91325.0, 93430.53284733533, 95443.0, 8214.671413143646, 8508.361872859685, 8550.78623034429, 8681.91318086811, 8762.693305734523, 43936.560634394526, 57874.4212557869, 58260.0, 59099.70450147894, 59504.40495595162, 68292.97560536496, 82610.0, 82898.34203316021, 83467.58266208872, 84204.57897710717, 10705.678829635124, 10733.516991735547, 10888.61889833852, 11065.391403472564, 11188.1608879336, 11954.402279886144, 12058.51765929353, 12149.878501215228, 12199.69500762488, 12269.815952760895, 10807.962296418658, 11536.174826258088, 11652.184347095623, 11680.532778688757, 11715.0, 11184.105271578417, 11442.199046066695, 11580.189386742848, 11744.941275293912, 11748.70628234301, 11341.149413793833, 11368.602098926498, 11485.885141148481, 11519.30884146954, 11847.940760296144, 9400.811983760423, 9880.0, 10243.590256389662, 10449.268551201343, 10568.0, 8904.643814247358, 9844.753881153028, 9905.801883962424, 9915.206783457163, 9932.652357167464], "value": 7047.800945723606, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 117.0, 117.0, 117.0, 118.0, 119.0, 117.0, 118.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 109.0, 110.0, 179.0, 143.0, 137.0, 115.0, 114.0, 143.0, 114.0], "value": 133.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 227.0, 195.0, 271.0, 210.0, 205.0, 199.0, 197.0, 196.0, 202.0], "value": 210.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 60.0, 84.0, 62.0, 60.0, 81.0, 52.0, 92.0, 56.0], "value": 68.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 87.0, 111.0, 98.0, 96.0, 97.0, 89.0, 68.0, 70.0, 85.0], "value": 87.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 98.0, 113.0, 88.0, 129.0, 99.0, 93.0, 92.0, 92.0, 90.0], "value": 98.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 97.0, 92.0, 96.0, 93.0, 142.0, 143.0, 123.0, 97.0], "value": 107.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 92.0, 137.0, 78.0, 89.0, 91.0, 92.0, 94.0, 136.0, 91.0], "value": 101.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 57.0, 57.0, 61.0, 60.0, 60.0, 59.0, 57.0, 59.0], "value": 58.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 76.0, 69.0, 78.0, 75.0, 74.0, 64.0, 77.0, 91.0, 65.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 96.0, 80.0, 100.0, 103.0, 98.0, 97.0, 86.0, 81.0, 78.0], "value": 90.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 153.0, 149.0, 168.0, 160.0, 155.0, 160.0, 154.0, 204.0, 147.0], "value": 159.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 212.0, 216.0, 156.0, 218.0, 218.0, 172.0, 171.0, 168.0, 170.0], "value": 186.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 69.0, 69.0, 67.0, 68.0, 70.0, 78.0, 85.0, 85.0], "value": 72.6, "unit": "score"}]}]} 11:05:11 INFO - Return code: 0 11:05:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:05:12 ERROR - # TBPL SUCCESS # 11:05:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:05:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:05:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:05:12 INFO - 2016-11-14 11:05:12.041 screenresolution[2572:293201] starting screenresolution argv=screenresolution get 11:05:12 INFO - 2016-11-14 11:05:12.044 screenresolution[2572:293201] Display 0: 1600x1200x32@60 11:05:12 INFO - 2016-11-14 11:05:12.050 screenresolution[2573:293204] starting screenresolution argv=screenresolution list 11:05:12 INFO - Available Modes on Display 0 11:05:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:05:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:05:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:05:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:05:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:05:12 INFO - Intel Iris: 11:05:12 INFO - Chipset Model: Intel Iris 11:05:12 INFO - Type: GPU 11:05:12 INFO - Bus: Built-In 11:05:12 INFO - VRAM (Dynamic, Max): 1536 MB 11:05:12 INFO - Vendor: Intel (0x8086) 11:05:12 INFO - Device ID: 0x0a2e 11:05:12 INFO - Revision ID: 0x0009 11:05:12 INFO - Displays: 11:05:12 INFO - Display: 11:05:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:05:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:05:12 INFO - Display Serial Number: 1600x1200 60 11:05:12 INFO - Main Display: Yes 11:05:12 INFO - Mirror: Off 11:05:12 INFO - Online: Yes 11:05:12 INFO - Rotation: Supported 11:05:12 INFO - Return code: 0 11:05:12 INFO - Running post-action listener: _resource_record_post_action 11:05:12 INFO - [mozharness: 2016-11-14 19:05:12.283779Z] Finished run-tests step (success) 11:05:12 INFO - Running post-run listener: _resource_record_post_run 11:05:12 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 6992384; Write bytes: 655572992; Read time: 60; Write time: 2190 11:05:12 INFO - TinderboxPrint: CPU usage
54.5% 11:05:12 INFO - TinderboxPrint: I/O read bytes / time
6,992,384 / 60 11:05:12 INFO - TinderboxPrint: I/O write bytes / time
655,572,992 / 2,190 11:05:12 INFO - TinderboxPrint: CPU idle
2,204.3 (45.5%) 11:05:12 INFO - TinderboxPrint: CPU system
978.2 (20.2%) 11:05:12 INFO - TinderboxPrint: CPU user
1,659.4 (34.3%) 11:05:12 INFO - TinderboxPrint: Swap in / out
367,222,784 / 0 11:05:12 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 352428544; Write bytes: 346765312; Read time: 27618; Write time: 735 11:05:12 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 6976000; Write bytes: 298633216; Read time: 59; Write time: 1418 11:05:12 INFO - Running post-run listener: _upload_blobber_files 11:05:12 INFO - Blob upload gear active. 11:05:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:05:12 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 11:05:12 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'] 11:05:12 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 11:05:13 INFO - (blobuploader) - INFO - Open directory for files ... 11:05:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:05:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:05:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:05:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:05:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:05:13 INFO - (blobuploader) - INFO - Done attempting. 11:05:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:05:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:05:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:05:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:05:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:05:14 INFO - (blobuploader) - INFO - Done attempting. 11:05:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:05:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:05:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:05:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:05:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:05:17 INFO - (blobuploader) - INFO - Done attempting. 11:05:17 INFO - (blobuploader) - INFO - Iteration through files over. 11:05:17 INFO - Return code: 0 11:05:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:05:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:05:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08867ab07f18615456affaaa7c8d88d45695b97a9a6864b5339ff20e6d6dee3231c74e365a05f3b75df5e8ba196a9665105f818776b93ba5916d46486691c524", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5045b5eb04c8b57408ac682676881b371d30420782caccf7686a9dc075840ecc7b2d499e7c12a204705948c91e4bd8ae5c578ab0bf6a3d1920e7752da9c7b59", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7c22bc10c77ae9a35e68b618244382236edf7081b029c675f68eb6668f05e5004d13dc89d6d17d330d9d0bcee3d635e94e4bcc1890924752da853b59774d3863"} 11:05:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:05:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:05:17 INFO - Contents: 11:05:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08867ab07f18615456affaaa7c8d88d45695b97a9a6864b5339ff20e6d6dee3231c74e365a05f3b75df5e8ba196a9665105f818776b93ba5916d46486691c524", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5045b5eb04c8b57408ac682676881b371d30420782caccf7686a9dc075840ecc7b2d499e7c12a204705948c91e4bd8ae5c578ab0bf6a3d1920e7752da9c7b59", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7c22bc10c77ae9a35e68b618244382236edf7081b029c675f68eb6668f05e5004d13dc89d6d17d330d9d0bcee3d635e94e4bcc1890924752da853b59774d3863"} 11:05:17 INFO - Running post-run listener: copy_logs_to_upload_dir 11:05:17 INFO - Copying logs to upload dir... 11:05:17 INFO - mkdir: /builds/slave/test/build/upload/logs 11:05:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.798000 ========= master_lag: 0.14 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-11-14 11:05:17.554860) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 11:05:17.558271) ========= 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.PJPikLXMPN/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.c1H112gSAW/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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/08867ab07f18615456affaaa7c8d88d45695b97a9a6864b5339ff20e6d6dee3231c74e365a05f3b75df5e8ba196a9665105f818776b93ba5916d46486691c524", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5045b5eb04c8b57408ac682676881b371d30420782caccf7686a9dc075840ecc7b2d499e7c12a204705948c91e4bd8ae5c578ab0bf6a3d1920e7752da9c7b59", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7c22bc10c77ae9a35e68b618244382236edf7081b029c675f68eb6668f05e5004d13dc89d6d17d330d9d0bcee3d635e94e4bcc1890924752da853b59774d3863"} build_url:https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011315 build_url: 'https://queue.taskcluster.net/v1/task/QQZLLuCyTS2ZaGKUzfI74g/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/08867ab07f18615456affaaa7c8d88d45695b97a9a6864b5339ff20e6d6dee3231c74e365a05f3b75df5e8ba196a9665105f818776b93ba5916d46486691c524", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5045b5eb04c8b57408ac682676881b371d30420782caccf7686a9dc075840ecc7b2d499e7c12a204705948c91e4bd8ae5c578ab0bf6a3d1920e7752da9c7b59", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7c22bc10c77ae9a35e68b618244382236edf7081b029c675f68eb6668f05e5004d13dc89d6d17d330d9d0bcee3d635e94e4bcc1890924752da853b59774d3863"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 11:05:17.591578) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:05:17.591936) ========= 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.PJPikLXMPN/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.c1H112gSAW/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.005484 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:05:17.654335) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 11:05:17.654632) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 11:05:17.655394) ========= ========= Total master_lag: 0.29 =========