builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0153 starttime: 1476284093.79 results: success (0) buildid: 20161012065646 builduid: f721d818a020410fbbce7e46b013384d revision: 26f2e65163e9f3cfab071a9823293217b1e1de8d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 07:54:53.789193) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 07:54:53.789564) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2016-10-12 07:54:53.789826) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2016-10-12 07:54:57.826080) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 07:54:57.826400) ========= 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.eQBXsDRynA/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.ARfKqb3ufF/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-12 07:54:57-- 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% 15.5M=0.001s 2016-10-12 07:54:57 (15.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119281 ========= master_lag: 0.94 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 07:54:58.881744) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 07:54:58.882079) ========= 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.eQBXsDRynA/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.ARfKqb3ufF/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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.037145 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 07:54:58.942882) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 07:54:58.943207) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 26f2e65163e9f3cfab071a9823293217b1e1de8d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 26f2e65163e9f3cfab071a9823293217b1e1de8d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eQBXsDRynA/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.ARfKqb3ufF/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-12 07:54:59,020 truncating revision to first 12 chars 2016-10-12 07:54:59,021 Setting DEBUG logging. 2016-10-12 07:54:59,021 attempt 1/10 2016-10-12 07:54:59,021 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/26f2e65163e9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-12 07:55:00,032 unpacking tar archive at: mozilla-central-26f2e65163e9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.288489 ========= master_lag: 0.34 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 07:55:00.567134) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 07:55:00.567414) ========= script_repo_revision: 26f2e65163e9f3cfab071a9823293217b1e1de8d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 07:55:00.567753) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 07:55:00.572073) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 07:55:00.682993) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-12 07:55:00.683269) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eQBXsDRynA/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.ARfKqb3ufF/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 07:55:00 INFO - MultiFileLogger online at 20161012 07:55:00 in /builds/slave/test 07:55:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:55:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:55:00 INFO - {'append_to_log': False, 07:55:00 INFO - 'base_work_dir': '/builds/slave/test', 07:55:00 INFO - 'blob_upload_branch': 'Firefox', 07:55:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:55:00 INFO - 'branch': 'Firefox', 07:55:00 INFO - 'buildbot_json_path': 'buildprops.json', 07:55:00 INFO - 'config_files': ('talos/mac_config.py',), 07:55:00 INFO - 'default_actions': ('clobber', 07:55:00 INFO - 'read-buildbot-config', 07:55:00 INFO - 'download-and-extract', 07:55:00 INFO - 'populate-webroot', 07:55:00 INFO - 'create-virtualenv', 07:55:00 INFO - 'install', 07:55:00 INFO - 'run-tests'), 07:55:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:55:00 INFO - 'download_minidump_stackwalk': True, 07:55:00 INFO - 'download_symbols': 'ondemand', 07:55:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:55:00 INFO - 'tooltool.py': '/tools/tooltool.py', 07:55:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:55:00 INFO - '/tools/misc-python/virtualenv.py')}, 07:55:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:55:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:55:00 INFO - 'installer_path': 'installer.exe', 07:55:00 INFO - 'log_level': 'info', 07:55:00 INFO - 'log_name': 'talos', 07:55:00 INFO - 'log_to_console': True, 07:55:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:55:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:55:00 INFO - 'opt_config_files': (), 07:55:00 INFO - 'pip_index': False, 07:55:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:55:00 INFO - 'cmd': ('bash', 07:55:00 INFO - '-c', 07:55:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:55:00 INFO - 'enabled': True, 07:55:00 INFO - 'halt_on_failure': False, 07:55:00 INFO - 'name': 'check_screen_resolution'},), 07:55:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:55:00 INFO - 'cmd': ('bash', 07:55:00 INFO - '-c', 07:55:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:55:00 INFO - 'enabled': True, 07:55:00 INFO - 'halt_on_failure': False, 07:55:00 INFO - 'name': 'check_screen_resolution'},), 07:55:00 INFO - 'run_cmd_checks_enabled': True, 07:55:00 INFO - 'sps_profile': False, 07:55:00 INFO - 'sps_profile_interval': 0, 07:55:00 INFO - 'suite': 'dromaeojs', 07:55:00 INFO - 'system_bits': '32', 07:55:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:55:00 INFO - 'title': 't-yosemite-r7-0153', 07:55:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:55:00 INFO - 'use_talos_json': True, 07:55:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:55:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:55:00 INFO - 'work_dir': 'build'} 07:55:00 INFO - [mozharness: 2016-10-12 14:55:00.893421Z] Running clobber step. 07:55:00 INFO - Running pre-action listener: _resource_record_pre_action 07:55:00 INFO - Running main action method: clobber 07:55:00 INFO - rmtree: /builds/slave/test/build 07:55:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:55:03 INFO - Running post-action listener: _resource_record_post_action 07:55:03 INFO - [mozharness: 2016-10-12 14:55:03.057282Z] Finished clobber step (success) 07:55:03 INFO - [mozharness: 2016-10-12 14:55:03.057436Z] Running read-buildbot-config step. 07:55:03 INFO - Running pre-action listener: _resource_record_pre_action 07:55:03 INFO - Running main action method: read_buildbot_config 07:55:03 INFO - Using buildbot properties: 07:55:03 INFO - { 07:55:03 INFO - "project": "", 07:55:03 INFO - "product": "firefox", 07:55:03 INFO - "script_repo_revision": "production", 07:55:03 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:55:03 INFO - "repository": "", 07:55:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:55:03 INFO - "buildid": "20161012065646", 07:55:03 INFO - "pgo_build": "False", 07:55:03 INFO - "basedir": "/builds/slave/test", 07:55:03 INFO - "buildnumber": 7, 07:55:03 INFO - "slavename": "t-yosemite-r7-0153", 07:55:03 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 07:55:03 INFO - "platform": "yosemite_r7", 07:55:03 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:55:03 INFO - "branch": "mozilla-central", 07:55:03 INFO - "repo_path": "mozilla-central", 07:55:03 INFO - "revision": "26f2e65163e9f3cfab071a9823293217b1e1de8d", 07:55:03 INFO - "stage_platform": "macosx64", 07:55:03 INFO - "builduid": "f721d818a020410fbbce7e46b013384d", 07:55:03 INFO - "slavebuilddir": "test" 07:55:03 INFO - } 07:55:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 07:55:03 INFO - Running post-action listener: _resource_record_post_action 07:55:03 INFO - [mozharness: 2016-10-12 14:55:03.060653Z] Finished read-buildbot-config step (success) 07:55:03 INFO - [mozharness: 2016-10-12 14:55:03.060786Z] Running download-and-extract step. 07:55:03 INFO - Running pre-action listener: _resource_record_pre_action 07:55:03 INFO - Running main action method: download_and_extract 07:55:03 INFO - mkdir: /builds/slave/test/build/tests 07:55:03 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')]} 07:55:03 INFO - trying https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 07:55:03 INFO - Downloading https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 07:55:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 07:55:04 INFO - Downloaded 1199 bytes. 07:55:04 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 07:55:04 INFO - Using the following test package requirements: 07:55:04 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:55:04 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:55:04 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 07:55:04 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:55:04 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 07:55:04 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:55:04 INFO - u'jsshell-mac.zip'], 07:55:04 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:55:04 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 07:55:04 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:55:04 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:55:04 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 07:55:04 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:55:04 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 07:55:04 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:55:04 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 07:55:04 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:55:04 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 07:55:04 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:55:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:55:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:55:04 INFO - Fetch https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:55:04 INFO - Http code: 200 07:55:04 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 07:55:04 INFO - x-amz-cf-id: _qdml7g7ReIOyQzAVruqElWvbU2QabLBPP0l7u6jjPqHQibEK3kH8Q== 07:55:04 INFO - x-amz-version-id: apM2111KG802AEnd7.UJDYILtwRGSl45 07:55:05 INFO - Expected file size: 16510510 07:55:05 INFO - Obtained file size: 16510510 07:55:11 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:55:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:55:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:55:11 INFO - Fetch https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:55:12 INFO - Http code: 200 07:55:12 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 07:55:12 INFO - x-amz-cf-id: WffRoV7qji5CCuTmk53C4lMYJyYjVQtcycHz6oFtEUm4E-WLZvXFPA== 07:55:12 INFO - x-amz-version-id: apM2111KG802AEnd7.UJDYILtwRGSl45 07:55:12 INFO - Expected file size: 16510510 07:55:12 INFO - Obtained file size: 16510510 07:55:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 07:55:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:55:19 INFO - Fetch https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 07:55:19 INFO - Http code: 200 07:55:19 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 07:55:19 INFO - x-amz-cf-id: sFDB9MrylLBB0lTar4c7epB40vaoUbc7v2qt7d7pyEhgyKpY6mdgGA== 07:55:19 INFO - x-amz-version-id: c1NDkrYepf0ACQuOfyxHeoUsZB3OZWan 07:55:20 INFO - Expected file size: 14409006 07:55:20 INFO - Obtained file size: 14409006 07:55:20 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')]} 07:55:20 INFO - trying https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:55:20 INFO - Downloading https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 07:55:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 07:55:23 INFO - Downloaded 111720019 bytes. 07:55:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:55:23 INFO - mkdir: /builds/slave/test/properties 07:55:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:55:23 INFO - Writing to file /builds/slave/test/properties/build_url 07:55:23 INFO - Contents: 07:55:23 INFO - build_url:https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:55:24 INFO - Running post-action listener: _resource_record_post_action 07:55:24 INFO - Running post-action listener: set_extra_try_arguments 07:55:24 INFO - [mozharness: 2016-10-12 14:55:24.009782Z] Finished download-and-extract step (success) 07:55:24 INFO - [mozharness: 2016-10-12 14:55:24.009903Z] Running populate-webroot step. 07:55:24 INFO - Running pre-action listener: _resource_record_pre_action 07:55:24 INFO - Running main action method: populate_webroot 07:55:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:55:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:55:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:55:24 INFO - '--tppagecycles', 07:55:24 INFO - '7'], 07:55:24 INFO - 'tests': ['tsvgm']}}, 07:55:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:55:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:55:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:55:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:55:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:55:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:55:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:55:24 INFO - 'talos_options': ['--disable-e10s'], 07:55:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:55:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:55:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:55:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:55:24 INFO - 'talos_options': ['--disable-e10s'], 07:55:24 INFO - 'tests': ['damp', 'tps']}, 07:55:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:55:24 INFO - 'tests': ['damp', 'tps']}, 07:55:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:55:24 INFO - 'tests': ['dromaeo_dom']}, 07:55:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:55:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:55:24 INFO - 'tests': ['basic_compositor_video']}, 07:55:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:55:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:55:24 INFO - 'tests': ['a11yr', 07:55:24 INFO - 'ts_paint', 07:55:24 INFO - 'tpaint', 07:55:24 INFO - 'sessionrestore', 07:55:24 INFO - 'sessionrestore_no_auto_restore', 07:55:24 INFO - 'tabpaint']}, 07:55:24 INFO - 'other-e10s': {'tests': ['a11yr', 07:55:24 INFO - 'ts_paint', 07:55:24 INFO - 'tpaint', 07:55:24 INFO - 'sessionrestore', 07:55:24 INFO - 'sessionrestore_no_auto_restore', 07:55:24 INFO - 'tabpaint']}, 07:55:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:55:24 INFO - 'ts_paint', 07:55:24 INFO - 'tpaint', 07:55:24 INFO - 'sessionrestore', 07:55:24 INFO - 'sessionrestore_no_auto_restore', 07:55:24 INFO - 'tabpaint']}, 07:55:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:55:24 INFO - 'ts_paint', 07:55:24 INFO - 'tpaint', 07:55:24 INFO - 'sessionrestore', 07:55:24 INFO - 'sessionrestore_no_auto_restore', 07:55:24 INFO - 'tabpaint']}, 07:55:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:55:24 INFO - 'tests': ['a11yr', 07:55:24 INFO - 'ts_paint', 07:55:24 INFO - 'tpaint', 07:55:24 INFO - 'sessionrestore', 07:55:24 INFO - 'sessionrestore_no_auto_restore', 07:55:24 INFO - 'tabpaint']}, 07:55:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:55:24 INFO - 'tests': ['a11yr', 07:55:24 INFO - 'ts_paint', 07:55:24 INFO - 'tpaint', 07:55:24 INFO - 'sessionrestore', 07:55:24 INFO - 'sessionrestore_no_auto_restore', 07:55:24 INFO - 'tabpaint']}, 07:55:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:55:24 INFO - 'tests': ['tsvgx', 07:55:24 INFO - 'tsvgr_opacity', 07:55:24 INFO - 'tart', 07:55:24 INFO - 'tscrollx', 07:55:24 INFO - 'cart']}, 07:55:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:55:24 INFO - 'tsvgr_opacity', 07:55:24 INFO - 'tart', 07:55:24 INFO - 'tscrollx', 07:55:24 INFO - 'cart']}, 07:55:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:55:24 INFO - 'talos_options': ['--disable-e10s'], 07:55:24 INFO - 'tests': ['tp5o']}, 07:55:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:55:24 INFO - 'tests': ['tp5o']}, 07:55:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:55:24 INFO - 'talos_options': ['--disable-e10s', 07:55:24 INFO - '--xperf_path', 07:55:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:55:24 INFO - 'tests': ['tp5n']}, 07:55:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:55:24 INFO - 'talos_options': ['--xperf_path', 07:55:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:55:24 INFO - 'tests': ['tp5n']}}, 07:55:24 INFO - 'talos.zip': {'path': '', 07:55:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:55:24 INFO - Running post-action listener: _resource_record_post_action 07:55:24 INFO - [mozharness: 2016-10-12 14:55:24.028613Z] Finished populate-webroot step (success) 07:55:24 INFO - [mozharness: 2016-10-12 14:55:24.028740Z] Running create-virtualenv step. 07:55:24 INFO - Running pre-action listener: _resource_record_pre_action 07:55:24 INFO - Running main action method: create_virtualenv 07:55:24 INFO - Creating virtualenv /builds/slave/test/build/venv 07:55:24 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 07:55:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:55:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:55:24 INFO - Using real prefix '/tools/python27' 07:55:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:55:24 INFO - Installing distribute.............................................................................................................................................................................................done. 07:55:27 INFO - Installing pip.................done. 07:55:27 INFO - Return code: 0 07:55:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:55:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:55:28 INFO - Reading from file tmpfile_stdout 07:55:28 INFO - Output received: 07:55:28 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) 07:55:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:55:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:55:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:55:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:55:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:55:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55:28 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 0x10caed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea1adb8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c901d30>, '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 0x10c727db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9b20>, '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.eQBXsDRynA/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.ARfKqb3ufF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 07:55:28 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 07:55:28 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 07:55:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQBXsDRynA/Render', 07:55:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:55:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:55:28 INFO - 'HOME': '/Users/cltbld', 07:55:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:55:28 INFO - 'LOGNAME': 'cltbld', 07:55:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:55:28 INFO - 'MOZ_NO_REMOTE': '1', 07:55:28 INFO - 'NO_EM_RESTART': '1', 07:55:28 INFO - 'PAGER': '/bin/cat', 07:55:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:55:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:55:28 INFO - 'PWD': '/builds/slave/test', 07:55:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:55:28 INFO - 'SHELL': '/bin/bash', 07:55:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARfKqb3ufF/Listeners', 07:55:28 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 07:55:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:55:28 INFO - 'USER': 'cltbld', 07:55:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:55:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:55:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:55:28 INFO - 'XPC_FLAGS': '0x0', 07:55:28 INFO - 'XPC_SERVICE_NAME': '0', 07:55:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:55:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:55:28 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 07:55:28 INFO - Cleaning up... 07:55:28 INFO - Return code: 0 07:55:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:55:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:55:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:55:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:55:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:55:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55:28 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 0x10caed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea1adb8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c901d30>, '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 0x10c727db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9b20>, '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.eQBXsDRynA/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.ARfKqb3ufF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 07:55:28 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 07:55:28 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 07:55:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQBXsDRynA/Render', 07:55:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:55:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:55:28 INFO - 'HOME': '/Users/cltbld', 07:55:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:55:28 INFO - 'LOGNAME': 'cltbld', 07:55:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:55:28 INFO - 'MOZ_NO_REMOTE': '1', 07:55:28 INFO - 'NO_EM_RESTART': '1', 07:55:28 INFO - 'PAGER': '/bin/cat', 07:55:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:55:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:55:28 INFO - 'PWD': '/builds/slave/test', 07:55:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:55:28 INFO - 'SHELL': '/bin/bash', 07:55:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARfKqb3ufF/Listeners', 07:55:28 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 07:55:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:55:28 INFO - 'USER': 'cltbld', 07:55:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:55:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:55:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:55:28 INFO - 'XPC_FLAGS': '0x0', 07:55:28 INFO - 'XPC_SERVICE_NAME': '0', 07:55:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:55:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:55:28 INFO - Downloading/unpacking psutil>=3.1.1 07:55:28 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 07:55:28 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 07:55:28 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 07:55:28 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 07:55:28 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 07:55:28 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 07:55:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:55:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:55:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:55:31 INFO - Installing collected packages: psutil 07:55:31 INFO - Running setup.py install for psutil 07:55:32 INFO - building 'psutil._psutil_osx' extension 07:55:32 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 07:55:32 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 07:55:32 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 07:55:32 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 07:55:32 INFO - building 'psutil._psutil_posix' extension 07:55:32 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 07:55:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:55:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:55:32 INFO - ^ 07:55:33 INFO - 1 warning generated. 07:55:33 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 07:55:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:55:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:55:33 INFO - Successfully installed psutil 07:55:33 INFO - Cleaning up... 07:55:33 INFO - Return code: 0 07:55:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:55: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')]} 07:55:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:55:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55: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')]} 07:55:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:55:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10caed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea1adb8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c901d30>, '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 0x10c727db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9b20>, '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.eQBXsDRynA/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.ARfKqb3ufF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 07:55: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:55: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 mozsystemmonitor==0.3 07:55:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQBXsDRynA/Render', 07:55:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:55:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:55:33 INFO - 'HOME': '/Users/cltbld', 07:55:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:55:33 INFO - 'LOGNAME': 'cltbld', 07:55:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:55:33 INFO - 'MOZ_NO_REMOTE': '1', 07:55:33 INFO - 'NO_EM_RESTART': '1', 07:55:33 INFO - 'PAGER': '/bin/cat', 07:55:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:55:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:55:33 INFO - 'PWD': '/builds/slave/test', 07:55:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:55:33 INFO - 'SHELL': '/bin/bash', 07:55:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARfKqb3ufF/Listeners', 07:55:33 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 07:55:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:55:33 INFO - 'USER': 'cltbld', 07:55:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:55:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:55:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:55:33 INFO - 'XPC_FLAGS': '0x0', 07:55:33 INFO - 'XPC_SERVICE_NAME': '0', 07:55:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:55:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:55:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:55: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 07:55: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 07:55: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 07:55: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 07:55: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 07:55: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 07:55:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:55:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:55:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:55:35 INFO - Installing collected packages: mozsystemmonitor 07:55:35 INFO - Running setup.py install for mozsystemmonitor 07:55:36 INFO - Successfully installed mozsystemmonitor 07:55:36 INFO - Cleaning up... 07:55:36 INFO - Return code: 0 07:55:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:55:36 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')]} 07:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:55:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55:36 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')]} 07:55:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:55:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55:36 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 0x10caed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea1adb8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c901d30>, '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 0x10c727db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9b20>, '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.eQBXsDRynA/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.ARfKqb3ufF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 07:55:36 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 07:55:36 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 07:55:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQBXsDRynA/Render', 07:55:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:55:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:55:36 INFO - 'HOME': '/Users/cltbld', 07:55:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:55:36 INFO - 'LOGNAME': 'cltbld', 07:55:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:55:36 INFO - 'MOZ_NO_REMOTE': '1', 07:55:36 INFO - 'NO_EM_RESTART': '1', 07:55:36 INFO - 'PAGER': '/bin/cat', 07:55:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:55:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:55:36 INFO - 'PWD': '/builds/slave/test', 07:55:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:55:36 INFO - 'SHELL': '/bin/bash', 07:55:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARfKqb3ufF/Listeners', 07:55:36 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 07:55:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:55:36 INFO - 'USER': 'cltbld', 07:55:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:55:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:55:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:55:36 INFO - 'XPC_FLAGS': '0x0', 07:55:36 INFO - 'XPC_SERVICE_NAME': '0', 07:55:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:55:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:55:36 INFO - Downloading/unpacking jsonschema==2.5.1 07:55:36 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 07:55:36 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 07:55:36 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 07:55:36 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 07:55:36 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 07:55:36 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 07:55:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:55:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:55: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 07:55: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 07:55: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 07:55: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 07:55: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 07:55: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 07:55:39 INFO - Downloading functools32-3.2.3-2.tar.gz 07:55:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:55:39 INFO - warning: no files found matching '*.txt' 07:55:39 INFO - no previously-included directories found matching 'build' 07:55:39 INFO - no previously-included directories found matching 'dist' 07:55:39 INFO - no previously-included directories found matching '.git*' 07:55:39 INFO - Installing collected packages: jsonschema, functools32 07:55:39 INFO - Running setup.py install for functools32 07:55:39 INFO - warning: no files found matching '*.txt' 07:55:39 INFO - no previously-included directories found matching 'build' 07:55:39 INFO - no previously-included directories found matching 'dist' 07:55:39 INFO - no previously-included directories found matching '.git*' 07:55:39 INFO - Successfully installed jsonschema functools32 07:55:39 INFO - Cleaning up... 07:55:39 INFO - Return code: 0 07:55:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:55:39 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')]} 07:55:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:55:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55:39 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')]} 07:55:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:55:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55:39 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 0x10caed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea1adb8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c901d30>, '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 0x10c727db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9b20>, '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.eQBXsDRynA/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.ARfKqb3ufF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 07:55:39 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 07:55:39 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 07:55:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQBXsDRynA/Render', 07:55:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:55:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:55:39 INFO - 'HOME': '/Users/cltbld', 07:55:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:55:39 INFO - 'LOGNAME': 'cltbld', 07:55:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:55:39 INFO - 'MOZ_NO_REMOTE': '1', 07:55:39 INFO - 'NO_EM_RESTART': '1', 07:55:39 INFO - 'PAGER': '/bin/cat', 07:55:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:55:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:55:39 INFO - 'PWD': '/builds/slave/test', 07:55:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:55:39 INFO - 'SHELL': '/bin/bash', 07:55:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARfKqb3ufF/Listeners', 07:55:39 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 07:55:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:55:39 INFO - 'USER': 'cltbld', 07:55:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:55:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:55:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:55:39 INFO - 'XPC_FLAGS': '0x0', 07:55:39 INFO - 'XPC_SERVICE_NAME': '0', 07:55:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:55:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:55:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:55:39 INFO - Cleaning up... 07:55:39 INFO - Return code: 0 07:55:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:55:39 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')]} 07:55:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:55:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55:39 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')]} 07:55:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:55:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55:39 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 0x10caed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea1adb8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c901d30>, '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 0x10c727db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9b20>, '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.eQBXsDRynA/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.ARfKqb3ufF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 07:55:39 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 07:55:39 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 07:55:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQBXsDRynA/Render', 07:55:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:55:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:55:39 INFO - 'HOME': '/Users/cltbld', 07:55:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:55:39 INFO - 'LOGNAME': 'cltbld', 07:55:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:55:39 INFO - 'MOZ_NO_REMOTE': '1', 07:55:39 INFO - 'NO_EM_RESTART': '1', 07:55:39 INFO - 'PAGER': '/bin/cat', 07:55:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:55:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:55:39 INFO - 'PWD': '/builds/slave/test', 07:55:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:55:39 INFO - 'SHELL': '/bin/bash', 07:55:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARfKqb3ufF/Listeners', 07:55:39 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 07:55:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:55:39 INFO - 'USER': 'cltbld', 07:55:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:55:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:55:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:55:39 INFO - 'XPC_FLAGS': '0x0', 07:55:39 INFO - 'XPC_SERVICE_NAME': '0', 07:55:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:55:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:55:40 INFO - Downloading/unpacking blobuploader==1.2.4 07:55:40 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 07:55:40 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 07:55:40 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 07:55:40 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 07:55:40 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 07:55:40 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 07:55:42 INFO - Downloading blobuploader-1.2.4.tar.gz 07:55:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:55:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:55:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:55:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:55:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:55:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:55:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:55:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:55:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:55:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:55:43 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 07:55:43 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 07:55:43 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 07:55:43 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 07:55:43 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 07:55:43 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 07:55:43 INFO - Downloading docopt-0.6.1.tar.gz 07:55:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:55:43 INFO - Installing collected packages: blobuploader, requests, docopt 07:55:43 INFO - Running setup.py install for blobuploader 07:55:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:55:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:55:44 INFO - Running setup.py install for requests 07:55:44 INFO - Running setup.py install for docopt 07:55:44 INFO - Successfully installed blobuploader requests docopt 07:55:44 INFO - Cleaning up... 07:55:44 INFO - Return code: 0 07:55:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:55: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')]} 07:55:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:55:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55: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')]} 07:55:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:55:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55:44 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 0x10caed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea1adb8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c901d30>, '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 0x10c727db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9b20>, '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.eQBXsDRynA/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.ARfKqb3ufF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 07:55:44 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 07:55:44 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 07:55:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQBXsDRynA/Render', 07:55:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:55:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:55:44 INFO - 'HOME': '/Users/cltbld', 07:55:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:55:44 INFO - 'LOGNAME': 'cltbld', 07:55:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:55:44 INFO - 'MOZ_NO_REMOTE': '1', 07:55:44 INFO - 'NO_EM_RESTART': '1', 07:55:44 INFO - 'PAGER': '/bin/cat', 07:55:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:55:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:55:44 INFO - 'PWD': '/builds/slave/test', 07:55:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:55:44 INFO - 'SHELL': '/bin/bash', 07:55:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARfKqb3ufF/Listeners', 07:55:44 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 07:55:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:55:44 INFO - 'USER': 'cltbld', 07:55:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:55:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:55:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:55:44 INFO - 'XPC_FLAGS': '0x0', 07:55:44 INFO - 'XPC_SERVICE_NAME': '0', 07:55:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:55:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:55:45 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-jROcWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:55:45 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-ccWN2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:55:45 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-JQOTCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:55:45 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-2QFeuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:55:45 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-3F_Ksz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:55:45 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-Vt5qd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:55:45 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-jb7cqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:55:45 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-lCABvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:55:45 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-RDWhTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:55:45 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-CUQDRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:55:46 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-juemOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:55:46 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-SslcjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:55:46 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-yBDtZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:55:46 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-Cvc6lp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:55:46 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-2tq__9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:55:46 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-E12ir7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:55:46 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-C9Aq8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:55:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:55:46 INFO - Running setup.py install for manifestparser 07:55:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:55:46 INFO - Running setup.py install for mozcrash 07:55:47 INFO - Running setup.py install for mozdebug 07:55:47 INFO - Running setup.py install for mozdevice 07:55:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:55:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:55:47 INFO - Running setup.py install for mozfile 07:55:47 INFO - Running setup.py install for mozhttpd 07:55:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:55:47 INFO - Running setup.py install for mozinfo 07:55:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:55:47 INFO - Running setup.py install for mozInstall 07:55:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:55:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:55:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:55:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:55:47 INFO - Running setup.py install for mozleak 07:55:48 INFO - Running setup.py install for mozlog 07:55:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:55:48 INFO - Running setup.py install for moznetwork 07:55:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:55:48 INFO - Running setup.py install for mozprocess 07:55:48 INFO - Running setup.py install for mozprofile 07:55:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:55:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:55:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:55:48 INFO - Running setup.py install for mozrunner 07:55:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:55:48 INFO - Running setup.py install for mozscreenshot 07:55:49 INFO - Running setup.py install for moztest 07:55:49 INFO - Running setup.py install for mozversion 07:55:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:55:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:55:49 INFO - Cleaning up... 07:55:49 INFO - Return code: 0 07:55:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:55:49 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')]} 07:55:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:55:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55:49 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')]} 07:55:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:55:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55:50 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 0x10caed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea1adb8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c901d30>, '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 0x10c727db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9b20>, '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.eQBXsDRynA/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.ARfKqb3ufF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 07:55:50 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 07:55:50 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 07:55:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQBXsDRynA/Render', 07:55:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:55:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:55:50 INFO - 'HOME': '/Users/cltbld', 07:55:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:55:50 INFO - 'LOGNAME': 'cltbld', 07:55:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:55:50 INFO - 'MOZ_NO_REMOTE': '1', 07:55:50 INFO - 'NO_EM_RESTART': '1', 07:55:50 INFO - 'PAGER': '/bin/cat', 07:55:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:55:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:55:50 INFO - 'PWD': '/builds/slave/test', 07:55:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:55:50 INFO - 'SHELL': '/bin/bash', 07:55:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARfKqb3ufF/Listeners', 07:55:50 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 07:55:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:55:50 INFO - 'USER': 'cltbld', 07:55:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:55:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:55:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:55:50 INFO - 'XPC_FLAGS': '0x0', 07:55:50 INFO - 'XPC_SERVICE_NAME': '0', 07:55:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:55:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:55:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:55:50 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-ZcYqvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:55:50 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)) 07:55:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:55:50 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-fwjtz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:55:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:55:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:55:50 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-RqE80L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:55:50 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)) 07:55:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:55:50 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-SrCzh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:55:50 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)) 07:55:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:55:50 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-npmA4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:55:50 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)) 07:55:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:55:50 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-MuxFXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:55:51 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)) 07:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:55:51 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-MCB2CN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:55:51 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)) 07:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:55:51 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-l6fdQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:55:51 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)) 07:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:55:51 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-Wf9zIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:55:51 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)) 07:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:55:51 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-ibQP3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:55:51 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)) 07:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:55:51 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-1E_iEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:55:51 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)) 07:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:55:51 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-A0eX55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:55:51 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)) 07:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:55:51 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-qxSrSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:55:51 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)) 07:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:55:51 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-lFxAgD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:55:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:55:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:55:51 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-oiCu2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:55:52 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)) 07:55:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:55:52 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-wE3Z3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:55:52 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)) 07:55:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:55:52 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-J6bTM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:55:52 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)) 07:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:55:52 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)) 07:55:52 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)) 07:55:52 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)) 07:55:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:55:52 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 07:55:52 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 07:55:52 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 07:55:52 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 07:55:52 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 07:55:52 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 07:55:55 INFO - Downloading blessings-1.6.tar.gz 07:55:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:55:55 INFO - Installing collected packages: blessings 07:55:55 INFO - Running setup.py install for blessings 07:55:55 INFO - Successfully installed blessings 07:55:55 INFO - Cleaning up... 07:55:55 INFO - Return code: 0 07:55:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:55:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:55:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:55:55 INFO - Reading from file tmpfile_stdout 07:55:55 INFO - Current package versions: 07:55:55 INFO - blessings == 1.6 07:55:55 INFO - blobuploader == 1.2.4 07:55:55 INFO - docopt == 0.6.1 07:55:55 INFO - functools32 == 3.2.3-2 07:55:55 INFO - jsonschema == 2.5.1 07:55:55 INFO - manifestparser == 1.1 07:55:55 INFO - mozInstall == 1.12 07:55:55 INFO - mozcrash == 0.17 07:55:55 INFO - mozdebug == 0.1 07:55:55 INFO - mozdevice == 0.48 07:55:55 INFO - mozfile == 1.2 07:55:55 INFO - mozhttpd == 0.7 07:55:55 INFO - mozinfo == 0.9 07:55:55 INFO - mozleak == 0.1 07:55:55 INFO - mozlog == 3.4 07:55:55 INFO - moznetwork == 0.27 07:55:55 INFO - mozprocess == 0.23 07:55:55 INFO - mozprofile == 0.28 07:55:55 INFO - mozrunner == 6.12 07:55:55 INFO - mozscreenshot == 0.1 07:55:55 INFO - mozsystemmonitor == 0.3 07:55:55 INFO - moztest == 0.7 07:55:55 INFO - mozversion == 1.4 07:55:55 INFO - psutil == 3.1.1 07:55:55 INFO - requests == 1.2.3 07:55:55 INFO - wsgiref == 0.1.2 07:55:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:55:55 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')]} 07:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:55:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55:55 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')]} 07:55:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:55:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55:55 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 0x10caed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea1adb8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c901d30>, '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 0x10c727db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9b20>, '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.eQBXsDRynA/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.ARfKqb3ufF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 07:55:55 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 07:55:55 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 07:55:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQBXsDRynA/Render', 07:55:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:55:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:55:55 INFO - 'HOME': '/Users/cltbld', 07:55:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:55:55 INFO - 'LOGNAME': 'cltbld', 07:55:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:55:55 INFO - 'MOZ_NO_REMOTE': '1', 07:55:55 INFO - 'NO_EM_RESTART': '1', 07:55:55 INFO - 'PAGER': '/bin/cat', 07:55:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:55:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:55:55 INFO - 'PWD': '/builds/slave/test', 07:55:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:55:55 INFO - 'SHELL': '/bin/bash', 07:55:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARfKqb3ufF/Listeners', 07:55:55 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 07:55:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:55:55 INFO - 'USER': 'cltbld', 07:55:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:55:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:55:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:55:55 INFO - 'XPC_FLAGS': '0x0', 07:55:55 INFO - 'XPC_SERVICE_NAME': '0', 07:55:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:55:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:55:56 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)) 07:55:56 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)) 07:55:56 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)) 07:55:56 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)) 07:55:56 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)) 07:55:56 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)) 07:55:56 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)) 07:55:56 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)) 07:55:56 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)) 07:55:56 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)) 07:55:56 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)) 07:55:56 INFO - Cleaning up... 07:55:56 INFO - Return code: 0 07:55:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:55:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:55:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:55:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:55:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:55:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10caed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea1adb8350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c901d30>, '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 0x10c727db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9b20>, '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.eQBXsDRynA/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.ARfKqb3ufF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 07:55:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:55:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:55:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQBXsDRynA/Render', 07:55:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:55:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:55:56 INFO - 'HOME': '/Users/cltbld', 07:55:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:55:56 INFO - 'LOGNAME': 'cltbld', 07:55:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:55:56 INFO - 'MOZ_NO_REMOTE': '1', 07:55:56 INFO - 'NO_EM_RESTART': '1', 07:55:56 INFO - 'PAGER': '/bin/cat', 07:55:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:55:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:55:56 INFO - 'PWD': '/builds/slave/test', 07:55:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:55:56 INFO - 'SHELL': '/bin/bash', 07:55:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARfKqb3ufF/Listeners', 07:55:56 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 07:55:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:55:56 INFO - 'USER': 'cltbld', 07:55:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:55:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:55:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:55:56 INFO - 'XPC_FLAGS': '0x0', 07:55:56 INFO - 'XPC_SERVICE_NAME': '0', 07:55:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:55:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:55:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:55:56 INFO - Cleaning up... 07:55:56 INFO - Return code: 0 07:55:56 INFO - Running post-action listener: _resource_record_post_action 07:55:56 INFO - Running post-action listener: _start_resource_monitoring 07:55:56 INFO - Starting resource monitoring. 07:55:56 INFO - [mozharness: 2016-10-12 14:55:56.382349Z] Finished create-virtualenv step (success) 07:55:56 INFO - [mozharness: 2016-10-12 14:55:56.383151Z] Running install step. 07:55:56 INFO - Running pre-action listener: _resource_record_pre_action 07:55:56 INFO - Running main action method: install 07:55:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:55:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:55:56 INFO - Reading from file tmpfile_stdout 07:55:56 INFO - Detecting whether we're running mozinstall >=1.0... 07:55:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:55:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:55:56 INFO - Reading from file tmpfile_stdout 07:55:56 INFO - Output received: 07:55:56 INFO - Usage: mozinstall [options] installer 07:55:56 INFO - Options: 07:55:56 INFO - -h, --help show this help message and exit 07:55:56 INFO - -d DEST, --destination=DEST 07:55:56 INFO - Directory to install application into. [default: 07:55:56 INFO - "/builds/slave/test"] 07:55:56 INFO - --app=APP Application being installed. [default: firefox] 07:55:56 INFO - mkdir: /builds/slave/test/build/application 07:55:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:55:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:56:30 INFO - Reading from file tmpfile_stdout 07:56:30 INFO - Output received: 07:56:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:56:30 INFO - Running post-action listener: _resource_record_post_action 07:56:30 INFO - [mozharness: 2016-10-12 14:56:30.956462Z] Finished install step (success) 07:56:30 INFO - [mozharness: 2016-10-12 14:56:30.956628Z] Running run-tests step. 07:56:30 INFO - Running pre-action listener: _resource_record_pre_action 07:56:30 INFO - Running main action method: run_tests 07:56:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:56:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:56:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:56:30 INFO - 2016-10-12 07:56:30.969 screenresolution[1642:7545] starting screenresolution argv=screenresolution get 07:56:30 INFO - 2016-10-12 07:56:30.971 screenresolution[1642:7545] Display 0: 1600x1200x32@60 07:56:30 INFO - 2016-10-12 07:56:30.977 screenresolution[1643:7547] starting screenresolution argv=screenresolution list 07:56:30 INFO - Available Modes on Display 0 07:56:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:56:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:56:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:56:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:56:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:56:31 INFO - Intel Iris: 07:56:31 INFO - Chipset Model: Intel Iris 07:56:31 INFO - Type: GPU 07:56:31 INFO - Bus: Built-In 07:56:31 INFO - VRAM (Dynamic, Max): 1536 MB 07:56:31 INFO - Vendor: Intel (0x8086) 07:56:31 INFO - Device ID: 0x0a2e 07:56:31 INFO - Revision ID: 0x0009 07:56:31 INFO - Displays: 07:56:31 INFO - Display: 07:56:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:56:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:56:31 INFO - Display Serial Number: 1600x1200 60 07:56:31 INFO - Main Display: Yes 07:56:31 INFO - Mirror: Off 07:56:31 INFO - Online: Yes 07:56:31 INFO - Rotation: Supported 07:56:31 INFO - Return code: 0 07:56:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:56:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:56:31 INFO - Python 2.7.3 07:56:31 INFO - Return code: 0 07:56:31 INFO - grabbing minidump binary from tooltool 07:56:31 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')]} 07:56:31 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 0x10c901d30>, '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 0x10c727db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:56:31 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 07:56:31 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 07:56:31 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 07:56:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:56:31 INFO - Return code: 0 07:56:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:56:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:56:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:56:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:56:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:56:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0153', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:56:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0153 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:56:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQBXsDRynA/Render', 07:56:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:56:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:56:31 INFO - 'HOME': '/Users/cltbld', 07:56:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:56:31 INFO - 'LOGNAME': 'cltbld', 07:56:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:56:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:56:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:31 INFO - 'MOZ_NO_REMOTE': '1', 07:56:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:56:31 INFO - 'NO_EM_RESTART': '1', 07:56:31 INFO - 'PAGER': '/bin/cat', 07:56:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:56:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:56:31 INFO - 'PWD': '/builds/slave/test', 07:56:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:56:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:56:31 INFO - 'SHELL': '/bin/bash', 07:56:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARfKqb3ufF/Listeners', 07:56:31 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 07:56:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:56:31 INFO - 'USER': 'cltbld', 07:56:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:56:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:56:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:31 INFO - 'XPC_FLAGS': '0x0', 07:56:31 INFO - 'XPC_SERVICE_NAME': '0', 07:56:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:56:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0153', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:56:31 INFO - mozversion application_buildid: 20161012065646 07:56:31 INFO - mozversion application_changeset: 26f2e65163e9f3cfab071a9823293217b1e1de8d 07:56:31 INFO - mozversion application_display_name: Nightly 07:56:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:56:31 INFO - mozversion application_name: Firefox 07:56:31 INFO - mozversion application_remotingname: firefox 07:56:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:56:31 INFO - mozversion application_vendor: Mozilla 07:56:31 INFO - mozversion application_version: 52.0a1 07:56:31 INFO - mozversion platform_buildid: 20161012065646 07:56:31 INFO - mozversion platform_changeset: 26f2e65163e9f3cfab071a9823293217b1e1de8d 07:56:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:56:31 INFO - mozversion platform_version: 52.0a1 07:56:31 INFO - using testdate: 1476284191 07:56:31 INFO - actual date: 1476284191 07:56:31 INFO - starting webserver on 'localhost:49276' 07:56:31 INFO - SUITE-START | Running 2 tests 07:56:31 INFO - TEST-START | dromaeo_css 07:56:31 INFO - operating with platform_type : mac_ 07:56:31 INFO - Initialising browser for dromaeo_css test... 07:56:31 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpQ1HvzI/profile http://localhost:49276/getInfo.html) 07:56:34 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 07:56:34 INFO - PROCESS | 1653 | colorDepth:24 07:56:34 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 07:56:34 INFO - PROCESS | 1653 | __metrics 07:56:34 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:56:34 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:56:34 INFO - TEST-INFO | 1653: exit 0 07:56:34 INFO - Browser initialized. 07:56:34 INFO - Running cycle 1/1 for dromaeo_css test... 07:56:34 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpQ1HvzI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:56:47 INFO - PROCESS | 1656 | RSS: Main: 235016192 07:56:47 INFO - PROCESS | 1656 | 07:57:37 INFO - PROCESS | 1656 | 1476284257865 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] 07:59:50 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 07:59:52 INFO - PROCESS | 1656 | RSS: Main: 431828992 07:59:52 INFO - PROCESS | 1656 | 08:02:55 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 08:02:56 INFO - PROCESS | 1656 | RSS: Main: 375226368 08:02:56 INFO - PROCESS | 1656 | 08:05:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 08:05:59 INFO - PROCESS | 1656 | RSS: Main: 365391872 08:05:59 INFO - PROCESS | 1656 | 08:09:01 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 08:09:02 INFO - PROCESS | 1656 | RSS: Main: 343977984 08:09:02 INFO - PROCESS | 1656 | 08:12:05 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 08:12:06 INFO - PROCESS | 1656 | RSS: Main: 327225344 08:12:06 INFO - PROCESS | 1656 | 08:15:09 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 08:15:09 INFO - PROCESS | 1656 | RSS: Main: 469909504 08:15:09 INFO - PROCESS | 1656 | 08:15:09 INFO - PROCESS | 1656 | __start_tp_report 08:15:09 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 08:15:09 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 08:15:09 INFO - PROCESS | 1656 | |i|pagename|runs| 08:15:09 INFO - PROCESS | 1656 | |0;dojo.html;2882.2938380096875;3300.174956260935;3309.966900330996;3335.2328964338217;3351.312980838928;5560.805371811987;5636.182753500745;5685.829425117253;5714.571407144465;5722.141678748189;5646.774129034834;5656.688882111483;5715.9428405715935;5738.196652468658;5780.786034932658;5143.408508021569;5193.221016847484;5221.71280579568;5250.028744682227;5268.236105764662;5142.331496905415;5226.08543504887;5271.841844744645;5274.182501712241;5277.076511610453;4405.8237670493145;5218.112920803473;5247.632665713401;5262.236975638531;5265.9473405266035;5103.540681338697;5144.305518754958;5233.528982391565;5242.842714718565;5324.520793128618;5106.412762532282;5158.587313014949;5164.974175129138;5166.560842328407;5198.558122559587;5605.019121653735;5631.8592035199035;5680.460356266148;5731.512821410185;5746.511546518551;6181.96909015452;6188.690565471754;6203.844903877394;6204.883121038211;6205.069239614094;6070.484008859252;6101.550206743894;6111.755529778803;6128.0169984388185;6206.348333425016;5753.769849206027;5755.395683453219;5755.453231942959;5760.193572899798;5761.654300741885;6290.874182516324;6296.527760417987;6307.306196318401;6307.621542707452;6317.241930968313;5904.5571582131515;5985.7904973326595;5995.52035837132;6036.366181550874;6058.485028772516;5850.824475265664;6179.196704428396;6207.068939659087;6230.065490176419;6230.875382492325;6097.359777223416;6159.137720719103;6170.50635949123;6170.969145154246;6197.225346831646;6069.15031895535;6096.950036952726;6216.595921265103;6221.844453888599;6248.187735594343;4171.958280417157;4871.80512779492;4904.239842824368;4909.803607855646;4966.726830024383;4404.4494438195225;4411.470623525133;4411.647068234533;4423.845165419195;4450.955490445119;5697.829065127969;5715.828525144252;5726.513246374022;5735.369109397963;5743.10981797822;6026.427489388501;6136.12084543884;6146.108814221912;6165.383461653799;6215.875682486325;4803.231482962709;5685.658860468385;5735.913961290584;5761.222234998306;5806.82579522615;5349.786008559692;5366.463353664602;5376.274202982548;5378.005069062236;5410.404855465896;6132.936844521141;6138.664296166039;6148.800983808114;6261.686915654108;6314.273858506331;6148.4773794227995;6197.349278325711;6232.719527621177;6264.5301602379095;6279.623222606659;5261.500157485033;6236.852493092043;6246.281677607133;6301.810945671454;6330.588511746721;6016.63900165974;6125.754969801158;6149.108379284889;6162.22972128484;6185.041318595625;6127.662978536223;6130.846728831815;6171.876562468636;6196.132541444112;6263.96868015657;6085.6348619083;6103.386738381593;6105.725242364193;6116.1836049259855;6134.263888333428;6032.3392381095455;6068.666223357758;6079.604825686316;6089.786857459968;6109.7861574844665;5782.1615865698395;5828.533717302521;5840.474357307863;5874.794382196603;5880.911786323294;5971.91042134377;6009.248843894513;6011.6994150293185;6020.1270815730595;6025.548083893638;5843.7370318336625;5856.355800861987;5861.472467477947;5885.646861188342;5906.143609176731 08:15:09 INFO - PROCESS | 1656 | |1;ext.html;431.8488529014844;450.7746126936532;464.03943836258946;519.8518422249657;545.926299949507;34280.4857927131;41387.793061034725;41408.17183656325;42193.57806421935;43183.78408107955;42070.36894446586;42780.35829462561;42817.571824281826;42925.57074429255;42962.78518607411;8530.573471326408;8763.737087887343;9267.39761915475;9561.713148605553;9563.330566860323;11357.432128393588;11712.472938717778;11716.414179291043;11821.586244481445;11830.704232394215;11137.777244455106;11202.66392008241;11273.830892536618;11283.435828208598;11350.432478376048;11483.196176267664;11553.133514986368;11555.942220288845;11557;11561.595344162914;11519.712007199803;11589.130815188895;11593.01459375954;11596.362200078993;11598.478068486938;37498;39122;39458.210835783124;39477.60522394768;39535;2948.5134952732774;3258.283177700902;3555.3244883472057;3933.2133573285173;4067.1255680028935;29974.85012574923;31189.798735306093;31484.527732084036;31502.36995260105;31530.527042094385;3168.2396224905856;3302.3065156317202;3331.6168640606397;3366.9494957575657;3370.5449764281752;471913;473596;473689.63155184005;474336;474619;62037.37962620316;62406;62459.06311405332;62471;62529.374706252354;64710.67644661841;65137;65193.67403162954;65194;65205.34794652087;14373.78439323411;16753.748693769605;16758.162091895356;16800.243989020524;16809.243584038748;11503.597374092034;11715.76568468626;11746.58886938953;12526;12711.872881271254;15373.769393459108;18724.15741291645;18890.149943252585;19119.52201194981;19181.904090479737;7596.8480630387085;7739.535627862347;7792.922070779219;7852.646630901623;7865.370770338359;728.2717282717283;918.2516249256835;921.9492927888896;948.8908775490769;1048.0672201740458;26822.5976610351;29296.41407171887;29895.70104298929;30135.547966780076;30743.23141921425;1006.8137394582026;1010.1161483701761;1035.5133087449037;1061.601899287767;1062.8565143705655;14113.85886141146;14648.633784155269;15615.76576351333;15641.687166256612;15997.440089596808;649121;654963;655157;656473;657529;46081.76959115183;58054.41945580574;58369.4163058364;58427.41572584305;58644;1103.9613613523488;1203.6449247472017;1249.5938819883393;1336.73265346931;1355.1394864261067;29768.55347169751;31125.844370778002;31153.68846311508;31248.687513125486;31900.36199275955;50568;51138.74430627823;51152.72118196926;51157;51192.72018199575;518743;520395;521650;522304;522478;25719.485610287316;26735;27251.182464526097;27315.043973460834;28125.31248125119;47003.29495057509;47117;47840.7607961958;47916;48164.759176203894;38854.4171837417;38854.80572597232;39091.609083909934;40630;41350;38887.13910761155;39066.80466597649;39151;40244;40744.185116298104;37540.24919501649;38599.6140038596;39037.609623903394;39075.21849562936;39157;27078.187654370402;32689.509657355633;34953.951381458595;35048.299034018666;35166.82416587901;23235.95438205251;25209.369765756004;25682;25758.356041098374;25902.87048564745 08:15:09 INFO - PROCESS | 1656 | |2;jquery.html;341.8974307707687;349.29268231830525;372.26477706529624;378.4304621544575;401.2676864721883;14099.929500352497;15033.624159396008;15744.921275393606;19948.70076948847;20441.591168176627;19543.90228048858;20565.794342056575;20692.96535173243;20747.273845415413;20921.686174707353;5754.136879468092;6046.062860256668;6047.667378294192;6049.48579370754;6052;4897.8040878365;6500.089987401768;6592.340765923417;6645.867082658344;6658.500612454061;5553;6303.495720342385;6522.7390904363765;6582.308857569958;6589.17635295588;6538.084668146464;6579.144711187515;6590.868182636321;6591.901121483182;6593.80218593443;5028.793089658492;5456.0997435423105;5528.612997090165;5630.859228519279;5638.689872057035;24207;24713;25398.365040874127;25504.744952550423;25727.356816079562;1993.8604297699167;2169.642009068502;2225.799678028985;2238.3732554884496;2386.9522609547885;9624.566894489764;9931.70204893847;9952.054554817354;10152.492375381202;10326.277160598762;1718.89686618802;1734.8983395543794;1791.578978939948;1830.3685228596114;1879.1731638079202;117859;121083.07544632304;122222.388888055;122788;123856;10988;11286.629807740303;11400.258983166068;11442.885571144348;11590.246633968765;14330.211838348783;14331.211783352015;14359.856401435853;14377.281135943162;14392.568222953329;7577.764559454974;7623.085229772352;8414.621342039623;8455.281301089353;8588.012378576545;7537.170911199763;7815.29662330393;8336.8332633347;8363.372747043995;8520.403571750043;4338;4468.207370393504;4472.642188624902;4474.194644963873;4515.074409745994;1493.873020793223;1655.5447252005795;1745.8865173763918;1787.3672681099406;1848.8352338026957;842.4566154830101;899.1817446123996;903.8689390038539;904.8868891388577;951.7430293820768;20081.39755807299;20916.790832091483;20948.790512094987;20949.581008379748;21134.56174203404;931.5481991234246;937.4984383354872;946.1201082992745;958.9760255993656;997.3816233935007;10590.046895779416;11081.5567377304;11177.273477223955;11177.66467005991;11196.104311655048;577390;581849;589028;594173;611021;75971.86042209371;124110.37944810218;128237.35881320534;129956;131744;942.641796117471;960.4621412009298;967.5645959318335;979.9314048016571;981.1365997921926;17555.473335799947;18525.351612693492;18846;18898.716519251946;18903.149358279185;53852;54965.450345496036;55335.446645533026;55700.77196912078;55706.442935571744;72175.27824721686;73638;75521;76731.84902226359;77082.2291777075;6469.514786391133;8012.399070069885;8174.959125204574;8179.222973817477;8355.83288334242;11433.371164586028;11482.598109066143;11818.940905295418;12854.5576340495;13130.671733206746;10864.511097000812;11080.39057851826;11322.32066076038;11337.886621133683;11378.658640240807;10712.410817405118;10839;10865.945670271914;11260.774784504429;11280.887191127984;10656.946715266375;11197.384143872165;11382.715432113931;11424.371659558805;11493.367864767519;8020.117787043538;8115.188481152074;8137.145599712182;8163.755087347389;8435.451695639764;8318.542480163649;10677.89322106769;11261.268017578832;11331.062758104496;11401.258918170292 08:15:09 INFO - PROCESS | 1656 | |3;mootools.html;1507.0882116319626;1507.886908481864;1712.400659769081;1796.9011704356255;1885.5757454572715;8359.665613375457;8899.199072083511;8913.019567847532;8918.375713700043;8953.328500362466;7779.844403111934;8086.71696490623;8099.55452450115;8450.746477605682;8703.99904011039;1991.9442695371476;2289.7366802817637;2290.9083636654555;2322.954670398319;2331.650252462136;1947.57153426246;2270.6139956207485;2274.465500607356;2319.9884000579973;2326.325365643961;2298.839081264312;2301.539692061586;2313.7570555091725;2315.80315673168;2325.5232677301196;2279.0655831109248;2281.2243837095302;2288.8397812153157;2290.393045842853;2293.529826385587;2287.8970446329954;2289.748127705951;2295.988520057406;2298.1152256381247;2300.3214051854743;8780.517071561126;8844.115588441104;8894.421862578976;8896.37725359225;8897.021327653953;5192.247124167013;5372.91940620891;5379.569634429236;5383.650062745909;5401.891962160774;3418.4359580669407;3489.0928358626684;3597.3165098631175;3641.2353405784816;3641.617630148849;1050.2648146297622;1058.904698577124;1059.2055958031476;1076.6931424544046;1081.6971248050559;11804.11469139818;11856.822147667792;11915.987141093105;11923.523059077626;11930.284182949052;4479.552044795494;4562.498125206224;4689.95310046902;4771.260454629538;4772.737499437564;9933;9941.751456213653;9957.502124893726;9962.452065136342;9964.402135871871;5293.258943747878;5295.285136506599;5295.629305948623;5301.443348448357;5351.197320401893;4311.676624253194;4350.58669426404;4808.63935204861;4848.806047758121;4856.975715121402;3830.8276127574327;3850.4983056607944;3927.92144157121;3947.7236593438756;3967.8016099194924;1252.6242127361754;1253.8620751717303;1262.2300396757971;1264.6585421936027;1273.4651446392538;778.8511944881254;819.5574389829426;901.7700486375932;908.318760929303;917.417439925652;5283.656562323436;5489.615726899158;5744.310682718017;5978.790742323998;6121;944.2257348973776;972.873526441558;979.5445118020154;1020.8468729690458;1045.764702941832;3918.3730603103368;3919.627635374692;3933.350997085449;4044.8786536403954;4047.736897101679;10588.15294776416;10761.354318740901;10842.566297348176;10869.347839129678;10870.293430926964;10817.459127043616;10823.67528974132;10824.24230303871;10827.566897324019;10865.076468500267;7876.448648594544;8131.878021829677;8308;8315.334773218128;8317.459365141367;4896.694830273625;5918.852028699317;5978.491828194478;5998.730057147526;6049.30433000193;10109.797804044025;10134.290599657954;10175.796484070424;10205.591776328863;10248.771636934342;9990.550425230736;10263.23025773055;10356.119729823049;10417.968496143483;10484.423356715453;6556.114924485324;6773.254941956282;6781.559198652072;6844.28135045813;6912.758053468105;7966;8070.192980701883;8087.757367278991;8141.959290203511;8459.280961118375;7377.557346559223;7482.2517748224745;7500.399968002655;7559.28186822251;7609.353204977639;7284.708611655475;7299.6350182489605;7322.3775979042375;7430.071241094864;7493.662785174572;5832.708364581669;7121.430285577245;7122.145342558926;7123.145222573325;7125.67934442941;5008.899822003613;5047.066292735855;5164.244156986642;5174.223866420067;5198.038362902875;4972.577330926912;5131.589472842238;5185.455527169593;5248.921266181087;5447.809326673548 08:15:09 INFO - PROCESS | 1656 | |4;prototype.html;297.4378424777171;297.7781552743205;306.84964367459946;312.6982461924243;314.97322727568155;5235.345581802275;5258.947410525893;5279.815206467774;5285.6300058995885;5295.749306544712;4039.9900024993754;4835.516448355172;4992.400911890569;5051.722155281458;5238.790448382069;3488.5522508159083;3563.465480177981;3773.3585290500682;3775.904987553613;3995.960040399588;3550.668499312758;3967.504061992251;3985.2228815380868;3999.5800440953717;4001.2597669431248;3081.630204375478;3911.882643520699;3946.427767973642;3989;4003;3585.7310701697215;3628.256207477461;3934.881953541398;3953.4779110042555;3976.4631774710333;2559.9104031358997;2872.784541159421;2902.535594304901;3395.219099607104;3416.7095796857047;4599.241125214336;4641.793133785206;5091.46539613339;5091.9236211456855;5184.377874655028;3452.7410444216785;3937.9015524612114;4209.65291106846;4336.609705126522;4535.342375355555;5295.629305948547;5389.407165211824;5471.425500322449;5493.917591236134;5499.532539734128;3761.9247615047548;3773.3208022555928;3773.5660399054186;3790.753601015925;3805.1438426353848;4607.631389488833;4897.485763994765;5751.654900705929;5782.097084467967;5828.970855145697;3171.444997125494;3190.409774191782;3647.9389145132914;3783.0353259919093;3796.4115562087923;3531.2054787673083;3645.045126038225;3802.448644946467;3866.8066596670615;3895.2988462076532;4320.746983374849;4537.705049171794;4552.385427967182;4555.33947577606;4563.155816173952;5065.924011139836;5281.894362112736;5515.834524964257;5548.861278468071;5601.411851755588;1196.7247533067443;1286.6783304173957;1294.838145231846;1306.5688322853437;1341.5377238809745;813.7680760983092;838.1869586501084;844.5186243840951;858.888344515209;882.6028287270753;4310.956890431118;5171.482851714798;5172.8878291167575;5175.301334319819;5184.937087896972;4884.949735806747;5448.318960129984;5483.396826349099;5636.2954630671165;5727;4873.561379475864;4941.158833670654;5035.395752509722;5076.466970968069;5107.310513080686;2427.5630386530593;2932.8240305581735;2933.266683329168;2942.4556457055514;2954.630671166104;4986.4514903360605;5142.945696132284;5332.2801421808335;5703.686297253608;5746.218672211628;4497.887552811206;4559.6808223424705;4748.174835972591;5372.838814835562;5759.366469688331;4726;4904.313396124474;4966.659002069421;5016.423111342169;5706.771729130871;5360.731963401892;5516.786307012451;5571.4428557143965;5596.244506991667;5598.86002849932;5039.521245481674;5503;5550.167474878801;5551;5727.828165155052;5252.161743389783;5350.5184533392185;5853.268341457318;5875.500582450369;5944.73248703818;3593.946090808692;3797.354449743495;3818.675412589961;3846.74996125251;3857.8071096444505;5186.844394668166;5702.6293290935955;5730.312362516524;5732.799352022659;5739.368669446441;5567.582431317586;5593.300837395325;5605.663660180402;5637.182608521659;5649.915251271152;5356.78572857097;5501.394846566955;5524.723763811874;5556.194351819045;5660.915086273627;4255.8297668094165;5413.675179489243;5437.4562543745305;5446.673199608036;5447.319085114361;4412.691111622284;5432.940576587527;5493.725313734377;5545.0018996581;5566.693831839126;4602.700824446534;4956.900861982812;5000.199968005102;5172.19830926207;5506.568292244126 08:15:09 INFO - PROCESS | 1656 | |5;yui.html;699.5837476701364;699.657167987686;756.1304499825201;786.4376970466116;804.8511025460289;15586.454474093409;15854.524364269091;15888.126153061577;15894.96682715624;15909.84090159098;14042.227677477735;14178.291085445739;14178.929105354458;14213.147211167337;14612.634684132901;662.344279163627;682.8975653651967;813.9837203255931;814.7555733279992;843.4433274039135;646.524804268862;832.4339449174558;834.3908946469081;841.5876220651897;844.0504432513422;878.1613559051123;878.7846977490524;879.7009016934242;881.418263945796;882.6116508736136;692.3353580562666;869.3827382558391;869.7999460124144;871.6600525794945;871.9040905500358;532.8960852633738;623.5230049012509;642.9517786166009;648.9545731798776;654.6955665615511;14186.787198192036;14235.074720143157;14236;14253.643658908506;14297;13626.523071692542;13986.720265594733;14100.717985640333;14124.36440360176;14141.151530908179;12368.690782730413;12707.174033687781;12714.682132946658;12805.295708736016;13017.28404937728;3934.1148241645687;4421.204183247014;4499.842505512292;4695.67130300879;4709.293605959099;115866;117306;117977.41011294974;118528.99999999914;119617;10768.40773757451;10774.569017239379;10814.29707069038;10942.781144377068;11083.778324433466;21560.784392155878;21666.783332166477;21696.783032169475;21737.45656358572;21763.347099587038;7778.183290754502;7856.096548896836;7866.567338796421;7941.086775020946;8045.678172873137;3599.5680518337967;3992.77478938914;3994.121293315466;4023.3160362738404;4047.838086476567;3369.4103531881824;3480.651934806499;3480.7215422766117;3484.7038001770134;3488.91277718054;556.8218170185502;625.6683957502531;642.5670754218027;654.8493846415352;655.636121952321;601.5368166511762;603.8169147925214;617.9104029915727;622.7260005597523;624.5565648389694;8788.296936245086;9186.502435740103;9248;9358.204552612968;9380.906190938003;597.8266302772234;618.9721462534196;620.43230444144;626.4330780643525;636.5003472274243;4278.29843570354;4313.676474264443;4336.371226172188;4650.697704649187;4666.836660716858;155183;157393;157978;158115.41884581238;159953;139135;139442;139815.30092349678;142960;143235.5676443243;97365.13174341113;100221;100948;103635;125001.12484814433;9336.346455748033;9695.224382049279;9977.351472154287;9992.750181245527;9998.550065246936;59412.70293648504;59438.70280648569;60397;60839.39160608337;62306.37693623006;62746;63531.729365411506;80624;84610;89089.66365504652;9055.501947392693;11420.428978551205;11423.200375973602;11447;11531.88468115308;14462.783058253925;14515.419383224553;14555.272236387926;14605.488807891672;14726.631834203801;13638.249896255802;13716.108452950526;13753.38109785042;13991.58025259244;14562;11781;13314.134581252187;13392.665183370495;14098.154110752977;14488.492902748354;13286.80069798973;13456.25990570528;13477.663058423617;13734.725305494034;14121.43514259418;10777.730556736144;11225.60710375133;11407.771844563229;11464.25482343645;11517.827232591686;9784.657536986171;10109.696709098738;10822.51298691545;11200.039794065515;11460.82808757886 08:15:09 INFO - PROCESS | 1656 | __end_tp_report 08:15:09 INFO - PROCESS | 1656 | __start_cc_report 08:15:09 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2195 08:15:09 INFO - PROCESS | 1656 | __end_cc_report 08:15:09 INFO - PROCESS | 1656 | __startTimestamp1476285309742__endTimestamp 08:15:09 INFO - PROCESS | 1656 | 08:15:09 INFO - PROCESS | 1656 | ------- Summary: start ------- 08:15:09 INFO - PROCESS | 1656 | Number of tests: 6 08:15:09 INFO - PROCESS | 1656 | 08:15:09 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5688.72 Median:5861.47 stddev:651.48 (11.1%) stddev-sans-first:615.16 08:15:09 INFO - PROCESS | 1656 | Values: 2882.3 3300.2 3310.0 3335.2 3351.3 5560.8 5636.2 5685.8 5714.6 5722.1 5646.8 5656.7 5715.9 5738.2 5780.8 5143.4 5193.2 5221.7 5250.0 5268.2 5142.3 5226.1 5271.8 5274.2 5277.1 4405.8 5218.1 5247.6 5262.2 5265.9 5103.5 5144.3 5233.5 5242.8 5324.5 5106.4 5158.6 5165.0 5166.6 5198.6 5605.0 5631.9 5680.5 5731.5 5746.5 6182.0 6188.7 6203.8 6204.9 6205.1 6070.5 6101.6 6111.8 6128.0 6206.3 5753.8 5755.4 5755.5 5760.2 5761.7 6290.9 6296.5 6307.3 6307.6 6317.2 5904.6 5985.8 5995.5 6036.4 6058.5 5850.8 6179.2 6207.1 6230.1 6230.9 6097.4 6159.1 6170.5 6171.0 6197.2 6069.2 6097.0 6216.6 6221.8 6248.2 4172.0 4871.8 4904.2 4909.8 4966.7 4404.4 4411.5 4411.6 4423.8 4451.0 5697.8 5715.8 5726.5 5735.4 5743.1 6026.4 6136.1 6146.1 6165.4 6215.9 4803.2 5685.7 5735.9 5761.2 5806.8 5349.8 5366.5 5376.3 5378.0 5410.4 6132.9 6138.7 6148.8 6261.7 6314.3 6148.5 6197.3 6232.7 6264.5 6279.6 5261.5 6236.9 6246.3 6301.8 6330.6 6016.6 6125.8 6149.1 6162.2 6185.0 6127.7 6130.8 6171.9 6196.1 6264.0 6085.6 6103.4 6105.7 6116.2 6134.3 6032.3 6068.7 6079.6 6089.8 6109.8 5782.2 5828.5 5840.5 5874.8 5880.9 5971.9 6009.2 6011.7 6020.1 6025.5 5843.7 5856.4 5861.5 5885.6 5906.1 08:15:09 INFO - PROCESS | 1656 | 08:15:09 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:68992.82 Median:37519.12 stddev:148158.17 (394.9%) stddev-sans-first:148484.37 08:15:09 INFO - PROCESS | 1656 | Values: 431.8 450.8 464.0 519.9 545.9 34280.5 41387.8 41408.2 42193.6 43183.8 42070.4 42780.4 42817.6 42925.6 42962.8 8530.6 8763.7 9267.4 9561.7 9563.3 11357.4 11712.5 11716.4 11821.6 11830.7 11137.8 11202.7 11273.8 11283.4 11350.4 11483.2 11553.1 11555.9 11557.0 11561.6 11519.7 11589.1 11593.0 11596.4 11598.5 37498.0 39122.0 39458.2 39477.6 39535.0 2948.5 3258.3 3555.3 3933.2 4067.1 29974.9 31189.8 31484.5 31502.4 31530.5 3168.2 3302.3 3331.6 3366.9 3370.5 471913.0 473596.0 473689.6 474336.0 474619.0 62037.4 62406.0 62459.1 62471.0 62529.4 64710.7 65137.0 65193.7 65194.0 65205.3 14373.8 16753.7 16758.2 16800.2 16809.2 11503.6 11715.8 11746.6 12526.0 12711.9 15373.8 18724.2 18890.1 19119.5 19181.9 7596.8 7739.5 7792.9 7852.6 7865.4 728.3 918.3 921.9 948.9 1048.1 26822.6 29296.4 29895.7 30135.5 30743.2 1006.8 1010.1 1035.5 1061.6 1062.9 14113.9 14648.6 15615.8 15641.7 15997.4 649121.0 654963.0 655157.0 656473.0 657529.0 46081.8 58054.4 58369.4 58427.4 58644.0 1104.0 1203.6 1249.6 1336.7 1355.1 29768.6 31125.8 31153.7 31248.7 31900.4 50568.0 51138.7 51152.7 51157.0 51192.7 518743.0 520395.0 521650.0 522304.0 522478.0 25719.5 26735.0 27251.2 27315.0 28125.3 47003.3 47117.0 47840.8 47916.0 48164.8 38854.4 38854.8 39091.6 40630.0 41350.0 38887.1 39066.8 39151.0 40244.0 40744.2 37540.2 38599.6 39037.6 39075.2 39157.0 27078.2 32689.5 34954.0 35048.3 35166.8 23236.0 25209.4 25682.0 25758.4 25902.9 08:15:09 INFO - PROCESS | 1656 | 08:15:09 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:34486.39 Median:345.60 stddev:98574.24 (28523.0%) stddev-sans-first:98817.43 08:15:09 INFO - PROCESS | 1656 | Values: 341.9 349.3 372.3 378.4 401.3 14099.9 15033.6 15744.9 19948.7 20441.6 19543.9 20565.8 20693.0 20747.3 20921.7 5754.1 6046.1 6047.7 6049.5 6052.0 4897.8 6500.1 6592.3 6645.9 6658.5 5553.0 6303.5 6522.7 6582.3 6589.2 6538.1 6579.1 6590.9 6591.9 6593.8 5028.8 5456.1 5528.6 5630.9 5638.7 24207.0 24713.0 25398.4 25504.7 25727.4 1993.9 2169.6 2225.8 2238.4 2387.0 9624.6 9931.7 9952.1 10152.5 10326.3 1718.9 1734.9 1791.6 1830.4 1879.2 117859.0 121083.1 122222.4 122788.0 123856.0 10988.0 11286.6 11400.3 11442.9 11590.2 14330.2 14331.2 14359.9 14377.3 14392.6 7577.8 7623.1 8414.6 8455.3 8588.0 7537.2 7815.3 8336.8 8363.4 8520.4 4338.0 4468.2 4472.6 4474.2 4515.1 1493.9 1655.5 1745.9 1787.4 1848.8 842.5 899.2 903.9 904.9 951.7 20081.4 20916.8 20948.8 20949.6 21134.6 931.5 937.5 946.1 959.0 997.4 10590.0 11081.6 11177.3 11177.7 11196.1 577390.0 581849.0 589028.0 594173.0 611021.0 75971.9 124110.4 128237.4 129956.0 131744.0 942.6 960.5 967.6 979.9 981.1 17555.5 18525.4 18846.0 18898.7 18903.1 53852.0 54965.5 55335.4 55700.8 55706.4 72175.3 73638.0 75521.0 76731.8 77082.2 6469.5 8012.4 8175.0 8179.2 8355.8 11433.4 11482.6 11818.9 12854.6 13130.7 10864.5 11080.4 11322.3 11337.9 11378.7 10712.4 10839.0 10865.9 11260.8 11280.9 10656.9 11197.4 11382.7 11424.4 11493.4 8020.1 8115.2 8137.1 8163.8 8435.5 8318.5 10677.9 11261.3 11331.1 11401.3 08:15:09 INFO - PROCESS | 1656 | 08:15:09 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5697.02 Median:4876.84 stddev:3227.97 (66.2%) stddev-sans-first:3221.67 08:15:09 INFO - PROCESS | 1656 | Values: 1507.1 1507.9 1712.4 1796.9 1885.6 8359.7 8899.2 8913.0 8918.4 8953.3 7779.8 8086.7 8099.6 8450.7 8704.0 1991.9 2289.7 2290.9 2323.0 2331.7 1947.6 2270.6 2274.5 2320.0 2326.3 2298.8 2301.5 2313.8 2315.8 2325.5 2279.1 2281.2 2288.8 2290.4 2293.5 2287.9 2289.7 2296.0 2298.1 2300.3 8780.5 8844.1 8894.4 8896.4 8897.0 5192.2 5372.9 5379.6 5383.7 5401.9 3418.4 3489.1 3597.3 3641.2 3641.6 1050.3 1058.9 1059.2 1076.7 1081.7 11804.1 11856.8 11916.0 11923.5 11930.3 4479.6 4562.5 4690.0 4771.3 4772.7 9933.0 9941.8 9957.5 9962.5 9964.4 5293.3 5295.3 5295.6 5301.4 5351.2 4311.7 4350.6 4808.6 4848.8 4857.0 3830.8 3850.5 3927.9 3947.7 3967.8 1252.6 1253.9 1262.2 1264.7 1273.5 778.9 819.6 901.8 908.3 917.4 5283.7 5489.6 5744.3 5978.8 6121.0 944.2 972.9 979.5 1020.8 1045.8 3918.4 3919.6 3933.4 4044.9 4047.7 10588.2 10761.4 10842.6 10869.3 10870.3 10817.5 10823.7 10824.2 10827.6 10865.1 7876.4 8131.9 8308.0 8315.3 8317.5 4896.7 5918.9 5978.5 5998.7 6049.3 10109.8 10134.3 10175.8 10205.6 10248.8 9990.6 10263.2 10356.1 10418.0 10484.4 6556.1 6773.3 6781.6 6844.3 6912.8 7966.0 8070.2 8087.8 8142.0 8459.3 7377.6 7482.3 7500.4 7559.3 7609.4 7284.7 7299.6 7322.4 7430.1 7493.7 5832.7 7121.4 7122.1 7123.1 7125.7 5008.9 5047.1 5164.2 5174.2 5198.0 4972.6 5131.6 5185.5 5248.9 5447.8 08:15:09 INFO - PROCESS | 1656 | 08:15:09 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4389.31 Median:4989.43 stddev:1371.02 (27.5%) stddev-sans-first:1340.03 08:15:09 INFO - PROCESS | 1656 | Values: 297.4 297.8 306.8 312.7 315.0 5235.3 5258.9 5279.8 5285.6 5295.7 4040.0 4835.5 4992.4 5051.7 5238.8 3488.6 3563.5 3773.4 3775.9 3996.0 3550.7 3967.5 3985.2 3999.6 4001.3 3081.6 3911.9 3946.4 3989.0 4003.0 3585.7 3628.3 3934.9 3953.5 3976.5 2559.9 2872.8 2902.5 3395.2 3416.7 4599.2 4641.8 5091.5 5091.9 5184.4 3452.7 3937.9 4209.7 4336.6 4535.3 5295.6 5389.4 5471.4 5493.9 5499.5 3761.9 3773.3 3773.6 3790.8 3805.1 4607.6 4897.5 5751.7 5782.1 5829.0 3171.4 3190.4 3647.9 3783.0 3796.4 3531.2 3645.0 3802.4 3866.8 3895.3 4320.7 4537.7 4552.4 4555.3 4563.2 5065.9 5281.9 5515.8 5548.9 5601.4 1196.7 1286.7 1294.8 1306.6 1341.5 813.8 838.2 844.5 858.9 882.6 4311.0 5171.5 5172.9 5175.3 5184.9 4884.9 5448.3 5483.4 5636.3 5727.0 4873.6 4941.2 5035.4 5076.5 5107.3 2427.6 2932.8 2933.3 2942.5 2954.6 4986.5 5142.9 5332.3 5703.7 5746.2 4497.9 4559.7 4748.2 5372.8 5759.4 4726.0 4904.3 4966.7 5016.4 5706.8 5360.7 5516.8 5571.4 5596.2 5598.9 5039.5 5503.0 5550.2 5551.0 5727.8 5252.2 5350.5 5853.3 5875.5 5944.7 3593.9 3797.4 3818.7 3846.7 3857.8 5186.8 5702.6 5730.3 5732.8 5739.4 5567.6 5593.3 5605.7 5637.2 5649.9 5356.8 5501.4 5524.7 5556.2 5660.9 4255.8 5413.7 5437.5 5446.7 5447.3 4412.7 5432.9 5493.7 5545.0 5566.7 4602.7 4956.9 5000.2 5172.2 5506.6 08:15:09 INFO - PROCESS | 1656 | 08:15:09 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:25007.97 Median:3486.81 stddev:40925.31 (1173.7%) stddev-sans-first:40999.42 08:15:09 INFO - PROCESS | 1656 | Values: 699.6 699.7 756.1 786.4 804.9 15586.5 15854.5 15888.1 15895.0 15909.8 14042.2 14178.3 14178.9 14213.1 14612.6 662.3 682.9 814.0 814.8 843.4 646.5 832.4 834.4 841.6 844.1 878.2 878.8 879.7 881.4 882.6 692.3 869.4 869.8 871.7 871.9 532.9 623.5 643.0 649.0 654.7 14186.8 14235.1 14236.0 14253.6 14297.0 13626.5 13986.7 14100.7 14124.4 14141.2 12368.7 12707.2 12714.7 12805.3 13017.3 3934.1 4421.2 4499.8 4695.7 4709.3 115866.0 117306.0 117977.4 118529.0 119617.0 10768.4 10774.6 10814.3 10942.8 11083.8 21560.8 21666.8 21696.8 21737.5 21763.3 7778.2 7856.1 7866.6 7941.1 8045.7 3599.6 3992.8 3994.1 4023.3 4047.8 3369.4 3480.7 3480.7 3484.7 3488.9 556.8 625.7 642.6 654.8 655.6 601.5 603.8 617.9 622.7 624.6 8788.3 9186.5 9248.0 9358.2 9380.9 597.8 619.0 620.4 626.4 636.5 4278.3 4313.7 4336.4 4650.7 4666.8 155183.0 157393.0 157978.0 158115.4 159953.0 139135.0 139442.0 139815.3 142960.0 143235.6 97365.1 100221.0 100948.0 103635.0 125001.1 9336.3 9695.2 9977.4 9992.8 9998.6 59412.7 59438.7 60397.0 60839.4 62306.4 62746.0 63531.7 80624.0 84610.0 89089.7 9055.5 11420.4 11423.2 11447.0 11531.9 14462.8 14515.4 14555.3 14605.5 14726.6 13638.2 13716.1 13753.4 13991.6 14562.0 11781.0 13314.1 13392.7 14098.2 14488.5 13286.8 13456.3 13477.7 13734.7 14121.4 10777.7 11225.6 11407.8 11464.3 11517.8 9784.7 10109.7 10822.5 11200.0 11460.8 08:15:09 INFO - PROCESS | 1656 | -------- Summary: end -------- 08:15:09 INFO - PROCESS | 1656 | 08:15:10 INFO - TEST-INFO | 1656: exit 0 08:15:10 INFO - TEST-OK | dromaeo_css | took 1118823ms 08:15:10 INFO - TEST-START | kraken 08:15:10 INFO - operating with platform_type : mac_ 08:15:10 INFO - Initialising browser for kraken test... 08:15:10 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpUf5tC0/profile http://localhost:49276/getInfo.html) 08:15:12 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 08:15:12 INFO - PROCESS | 1663 | colorDepth:24 08:15:12 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 08:15:12 INFO - PROCESS | 1663 | __metrics 08:15:12 INFO - PROCESS | 1663 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:15:12 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:15:13 INFO - TEST-INFO | 1663: exit 0 08:15:13 INFO - Browser initialized. 08:15:13 INFO - Running cycle 1/1 for kraken test... 08:15:13 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpUf5tC0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:15:25 INFO - PROCESS | 1664 | RSS: Main: 234889216 08:15:25 INFO - PROCESS | 1664 | 08:15:28 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 08:15:29 INFO - PROCESS | 1664 | RSS: Main: 293445632 08:15:29 INFO - PROCESS | 1664 | 08:15:31 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 08:15:32 INFO - PROCESS | 1664 | RSS: Main: 293527552 08:15:32 INFO - PROCESS | 1664 | 08:15:35 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 08:15:36 INFO - PROCESS | 1664 | RSS: Main: 385978368 08:15:36 INFO - PROCESS | 1664 | 08:15:38 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 08:15:38 INFO - PROCESS | 1664 | RSS: Main: 284078080 08:15:38 INFO - PROCESS | 1664 | 08:15:40 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:15:41 INFO - PROCESS | 1664 | RSS: Main: 289800192 08:15:41 INFO - PROCESS | 1664 | 08:15:44 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 08:15:44 INFO - PROCESS | 1664 | RSS: Main: 327184384 08:15:44 INFO - PROCESS | 1664 | 08:15:47 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 08:15:48 INFO - PROCESS | 1664 | RSS: Main: 327389184 08:15:48 INFO - PROCESS | 1664 | 08:15:51 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 08:15:51 INFO - PROCESS | 1664 | RSS: Main: 326709248 08:15:51 INFO - PROCESS | 1664 | 08:15:53 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:15:54 INFO - PROCESS | 1664 | RSS: Main: 300863488 08:15:54 INFO - PROCESS | 1664 | 08:15:55 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:15:56 INFO - PROCESS | 1664 | RSS: Main: 304939008 08:15:56 INFO - PROCESS | 1664 | 08:15:58 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:15:59 INFO - PROCESS | 1664 | RSS: Main: 311934976 08:15:59 INFO - PROCESS | 1664 | 08:16:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:16:03 INFO - PROCESS | 1664 | RSS: Main: 319172608 08:16:03 INFO - PROCESS | 1664 | 08:16:06 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:16:06 INFO - PROCESS | 1664 | RSS: Main: 319152128 08:16:06 INFO - PROCESS | 1664 | 08:16:08 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 08:16:08 INFO - PROCESS | 1664 | RSS: Main: 363257856 08:16:08 INFO - PROCESS | 1664 | 08:16:08 INFO - PROCESS | 1664 | __start_tp_report 08:16:08 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 08:16:08 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 08:16:08 INFO - PROCESS | 1664 | |i|pagename|runs| 08:16:08 INFO - PROCESS | 1664 | |0;ai-astar;112;112;111;111;111;111;111;111;111;111 08:16:08 INFO - PROCESS | 1664 | |1;audio-beat-detection;149;114;113;151;114;113;131;113;143;112 08:16:08 INFO - PROCESS | 1664 | |2;audio-dft;183;184;184;184;184;187;185;181;177;180 08:16:08 INFO - PROCESS | 1664 | |3;audio-fft;78;60;59;87;62;60;59;58;82;58 08:16:08 INFO - PROCESS | 1664 | |4;audio-oscillator;86;70;91;72;68;68;91;68;69;90 08:16:08 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;87;85;87;84;86;87;87;85;88;84 08:16:08 INFO - PROCESS | 1664 | |6;imaging-darkroom;93;92;110;151;108;92;92;102;91;92 08:16:08 INFO - PROCESS | 1664 | |7;imaging-desaturate;125;142;87;85;84;84;85;84;84;83 08:16:08 INFO - PROCESS | 1664 | |8;json-parse-financial;56;53;53;53;55;56;55;56;54;54 08:16:08 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;65;73;71;68;76;69;70;70;70;71 08:16:08 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;87;90;86;86;87;86;89;86;92;87 08:16:08 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;146;146;146;146;146;173;154;163;213;142 08:16:08 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;172;175;172;232;207;171;172;173;171;171 08:16:08 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;68;68;69;68;68;66;68;68;67;70 08:16:08 INFO - PROCESS | 1664 | __end_tp_report 08:16:08 INFO - PROCESS | 1664 | __start_cc_report 08:16:08 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1962 08:16:08 INFO - PROCESS | 1664 | __end_cc_report 08:16:08 INFO - PROCESS | 1664 | __startTimestamp1476285368761__endTimestamp 08:16:08 INFO - PROCESS | 1664 | 08:16:08 INFO - PROCESS | 1664 | ------- Summary: start ------- 08:16:08 INFO - PROCESS | 1664 | Number of tests: 14 08:16:08 INFO - PROCESS | 1664 | 08:16:08 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:111.20 Median:111.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 08:16:08 INFO - PROCESS | 1664 | Values: 112.0 112.0 111.0 111.0 111.0 111.0 111.0 111.0 111.0 111.0 08:16:08 INFO - PROCESS | 1664 | 08:16:08 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:125.30 Median:122.50 stddev:16.51 (13.5%) stddev-sans-first:15.12 08:16:08 INFO - PROCESS | 1664 | Values: 149.0 114.0 113.0 151.0 114.0 113.0 131.0 113.0 143.0 112.0 08:16:08 INFO - PROCESS | 1664 | 08:16:08 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:182.90 Median:184.00 stddev:2.85 (1.5%) stddev-sans-first:3.02 08:16:08 INFO - PROCESS | 1664 | Values: 183.0 184.0 184.0 184.0 184.0 187.0 185.0 181.0 177.0 180.0 08:16:08 INFO - PROCESS | 1664 | 08:16:08 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:66.30 Median:61.00 stddev:11.32 (18.6%) stddev-sans-first:11.19 08:16:08 INFO - PROCESS | 1664 | Values: 78.0 60.0 59.0 87.0 62.0 60.0 59.0 58.0 82.0 58.0 08:16:08 INFO - PROCESS | 1664 | 08:16:08 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:79.00 stddev:10.66 (13.5%) stddev-sans-first:10.83 08:16:08 INFO - PROCESS | 1664 | Values: 86.0 70.0 91.0 72.0 68.0 68.0 91.0 68.0 69.0 90.0 08:16:08 INFO - PROCESS | 1664 | 08:16:08 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.41 (1.6%) stddev-sans-first:1.45 08:16:08 INFO - PROCESS | 1664 | Values: 87.0 85.0 87.0 84.0 86.0 87.0 87.0 85.0 88.0 84.0 08:16:08 INFO - PROCESS | 1664 | 08:16:08 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:102.30 Median:92.00 stddev:18.57 (20.2%) stddev-sans-first:19.38 08:16:08 INFO - PROCESS | 1664 | Values: 93.0 92.0 110.0 151.0 108.0 92.0 92.0 102.0 91.0 92.0 08:16:08 INFO - PROCESS | 1664 | 08:16:08 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:84.00 stddev:21.07 (25.1%) stddev-sans-first:19.20 08:16:08 INFO - PROCESS | 1664 | Values: 125.0 142.0 87.0 85.0 84.0 84.0 85.0 84.0 84.0 83.0 08:16:08 INFO - PROCESS | 1664 | 08:16:08 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.27 (2.3%) stddev-sans-first:1.22 08:16:08 INFO - PROCESS | 1664 | Values: 56.0 53.0 53.0 53.0 55.0 56.0 55.0 56.0 54.0 54.0 08:16:08 INFO - PROCESS | 1664 | 08:16:08 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:70.50 stddev:2.91 (4.1%) stddev-sans-first:2.37 08:16:08 INFO - PROCESS | 1664 | Values: 65.0 73.0 71.0 68.0 76.0 69.0 70.0 70.0 70.0 71.0 08:16:08 INFO - PROCESS | 1664 | 08:16:08 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:87.00 stddev:2.07 (2.4%) stddev-sans-first:2.18 08:16:08 INFO - PROCESS | 1664 | Values: 87.0 90.0 86.0 86.0 87.0 86.0 89.0 86.0 92.0 87.0 08:16:08 INFO - PROCESS | 1664 | 08:16:08 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:157.50 Median:150.00 stddev:21.77 (14.5%) stddev-sans-first:22.69 08:16:08 INFO - PROCESS | 1664 | Values: 146.0 146.0 146.0 146.0 146.0 173.0 154.0 163.0 213.0 142.0 08:16:08 INFO - PROCESS | 1664 | 08:16:08 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.60 Median:172.50 stddev:20.86 (12.1%) stddev-sans-first:21.83 08:16:08 INFO - PROCESS | 1664 | Values: 172.0 175.0 172.0 232.0 207.0 171.0 172.0 173.0 171.0 171.0 08:16:08 INFO - PROCESS | 1664 | 08:16:08 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:1.05 (1.6%) stddev-sans-first:1.12 08:16:08 INFO - PROCESS | 1664 | Values: 68.0 68.0 69.0 68.0 68.0 66.0 68.0 68.0 67.0 70.0 08:16:08 INFO - PROCESS | 1664 | -------- Summary: end -------- 08:16:08 INFO - PROCESS | 1664 | 08:16:09 INFO - TEST-INFO | 1664: exit 0 08:16:09 INFO - TEST-OK | kraken | took 59250ms 08:16:09 INFO - SUITE-END | took 1178s 08:16:09 INFO - Completed test suite (00:19:38) 08:16:09 INFO - javascript benchmark 08:16:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2882.2938380096875, 3300.174956260935, 3309.966900330996, 3335.2328964338217, 3351.312980838928, 5560.805371811987, 5636.182753500745, 5685.829425117253, 5714.571407144465, 5722.141678748189, 5646.774129034834, 5656.688882111483, 5715.9428405715935, 5738.196652468658, 5780.786034932658, 5143.408508021569, 5193.221016847484, 5221.71280579568, 5250.028744682227, 5268.236105764662, 5142.331496905415, 5226.08543504887, 5271.841844744645, 5274.182501712241, 5277.076511610453, 4405.8237670493145, 5218.112920803473, 5247.632665713401, 5262.236975638531, 5265.9473405266035, 5103.540681338697, 5144.305518754958, 5233.528982391565, 5242.842714718565, 5324.520793128618, 5106.412762532282, 5158.587313014949, 5164.974175129138, 5166.560842328407, 5198.558122559587, 5605.019121653735, 5631.8592035199035, 5680.460356266148, 5731.512821410185, 5746.511546518551, 6181.96909015452, 6188.690565471754, 6203.844903877394, 6204.883121038211, 6205.069239614094, 6070.484008859252, 6101.550206743894, 6111.755529778803, 6128.0169984388185, 6206.348333425016, 5753.769849206027, 5755.395683453219, 5755.453231942959, 5760.193572899798, 5761.654300741885, 6290.874182516324, 6296.527760417987, 6307.306196318401, 6307.621542707452, 6317.241930968313, 5904.5571582131515, 5985.7904973326595, 5995.52035837132, 6036.366181550874, 6058.485028772516, 5850.824475265664, 6179.196704428396, 6207.068939659087, 6230.065490176419, 6230.875382492325, 6097.359777223416, 6159.137720719103, 6170.50635949123, 6170.969145154246, 6197.225346831646, 6069.15031895535, 6096.950036952726, 6216.595921265103, 6221.844453888599, 6248.187735594343, 4171.958280417157, 4871.80512779492, 4904.239842824368, 4909.803607855646, 4966.726830024383, 4404.4494438195225, 4411.470623525133, 4411.647068234533, 4423.845165419195, 4450.955490445119, 5697.829065127969, 5715.828525144252, 5726.513246374022, 5735.369109397963, 5743.10981797822, 6026.427489388501, 6136.12084543884, 6146.108814221912, 6165.383461653799, 6215.875682486325, 4803.231482962709, 5685.658860468385, 5735.913961290584, 5761.222234998306, 5806.82579522615, 5349.786008559692, 5366.463353664602, 5376.274202982548, 5378.005069062236, 5410.404855465896, 6132.936844521141, 6138.664296166039, 6148.800983808114, 6261.686915654108, 6314.273858506331, 6148.4773794227995, 6197.349278325711, 6232.719527621177, 6264.5301602379095, 6279.623222606659, 5261.500157485033, 6236.852493092043, 6246.281677607133, 6301.810945671454, 6330.588511746721, 6016.63900165974, 6125.754969801158, 6149.108379284889, 6162.22972128484, 6185.041318595625, 6127.662978536223, 6130.846728831815, 6171.876562468636, 6196.132541444112, 6263.96868015657, 6085.6348619083, 6103.386738381593, 6105.725242364193, 6116.1836049259855, 6134.263888333428, 6032.3392381095455, 6068.666223357758, 6079.604825686316, 6089.786857459968, 6109.7861574844665, 5782.1615865698395, 5828.533717302521, 5840.474357307863, 5874.794382196603, 5880.911786323294, 5971.91042134377, 6009.248843894513, 6011.6994150293185, 6020.1270815730595, 6025.548083893638, 5843.7370318336625, 5856.355800861987, 5861.472467477947, 5885.646861188342, 5906.143609176731], "value": 5645.738007406299, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.8488529014844, 450.7746126936532, 464.03943836258946, 519.8518422249657, 545.926299949507, 34280.4857927131, 41387.793061034725, 41408.17183656325, 42193.57806421935, 43183.78408107955, 42070.36894446586, 42780.35829462561, 42817.571824281826, 42925.57074429255, 42962.78518607411, 8530.573471326408, 8763.737087887343, 9267.39761915475, 9561.713148605553, 9563.330566860323, 11357.432128393588, 11712.472938717778, 11716.414179291043, 11821.586244481445, 11830.704232394215, 11137.777244455106, 11202.66392008241, 11273.830892536618, 11283.435828208598, 11350.432478376048, 11483.196176267664, 11553.133514986368, 11555.942220288845, 11557.0, 11561.595344162914, 11519.712007199803, 11589.130815188895, 11593.01459375954, 11596.362200078993, 11598.478068486938, 37498.0, 39122.0, 39458.210835783124, 39477.60522394768, 39535.0, 2948.5134952732774, 3258.283177700902, 3555.3244883472057, 3933.2133573285173, 4067.1255680028935, 29974.85012574923, 31189.798735306093, 31484.527732084036, 31502.36995260105, 31530.527042094385, 3168.2396224905856, 3302.3065156317202, 3331.6168640606397, 3366.9494957575657, 3370.5449764281752, 471913.0, 473596.0, 473689.63155184005, 474336.0, 474619.0, 62037.37962620316, 62406.0, 62459.06311405332, 62471.0, 62529.374706252354, 64710.67644661841, 65137.0, 65193.67403162954, 65194.0, 65205.34794652087, 14373.78439323411, 16753.748693769605, 16758.162091895356, 16800.243989020524, 16809.243584038748, 11503.597374092034, 11715.76568468626, 11746.58886938953, 12526.0, 12711.872881271254, 15373.769393459108, 18724.15741291645, 18890.149943252585, 19119.52201194981, 19181.904090479737, 7596.8480630387085, 7739.535627862347, 7792.922070779219, 7852.646630901623, 7865.370770338359, 728.2717282717283, 918.2516249256835, 921.9492927888896, 948.8908775490769, 1048.0672201740458, 26822.5976610351, 29296.41407171887, 29895.70104298929, 30135.547966780076, 30743.23141921425, 1006.8137394582026, 1010.1161483701761, 1035.5133087449037, 1061.601899287767, 1062.8565143705655, 14113.85886141146, 14648.633784155269, 15615.76576351333, 15641.687166256612, 15997.440089596808, 649121.0, 654963.0, 655157.0, 656473.0, 657529.0, 46081.76959115183, 58054.41945580574, 58369.4163058364, 58427.41572584305, 58644.0, 1103.9613613523488, 1203.6449247472017, 1249.5938819883393, 1336.73265346931, 1355.1394864261067, 29768.55347169751, 31125.844370778002, 31153.68846311508, 31248.687513125486, 31900.36199275955, 50568.0, 51138.74430627823, 51152.72118196926, 51157.0, 51192.72018199575, 518743.0, 520395.0, 521650.0, 522304.0, 522478.0, 25719.485610287316, 26735.0, 27251.182464526097, 27315.043973460834, 28125.31248125119, 47003.29495057509, 47117.0, 47840.7607961958, 47916.0, 48164.759176203894, 38854.4171837417, 38854.80572597232, 39091.609083909934, 40630.0, 41350.0, 38887.13910761155, 39066.80466597649, 39151.0, 40244.0, 40744.185116298104, 37540.24919501649, 38599.6140038596, 39037.609623903394, 39075.21849562936, 39157.0, 27078.187654370402, 32689.509657355633, 34953.951381458595, 35048.299034018666, 35166.82416587901, 23235.95438205251, 25209.369765756004, 25682.0, 25758.356041098374, 25902.87048564745], "value": 20246.50130672457, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.8974307707687, 349.29268231830525, 372.26477706529624, 378.4304621544575, 401.2676864721883, 14099.929500352497, 15033.624159396008, 15744.921275393606, 19948.70076948847, 20441.591168176627, 19543.90228048858, 20565.794342056575, 20692.96535173243, 20747.273845415413, 20921.686174707353, 5754.136879468092, 6046.062860256668, 6047.667378294192, 6049.48579370754, 6052.0, 4897.8040878365, 6500.089987401768, 6592.340765923417, 6645.867082658344, 6658.500612454061, 5553.0, 6303.495720342385, 6522.7390904363765, 6582.308857569958, 6589.17635295588, 6538.084668146464, 6579.144711187515, 6590.868182636321, 6591.901121483182, 6593.80218593443, 5028.793089658492, 5456.0997435423105, 5528.612997090165, 5630.859228519279, 5638.689872057035, 24207.0, 24713.0, 25398.365040874127, 25504.744952550423, 25727.356816079562, 1993.8604297699167, 2169.642009068502, 2225.799678028985, 2238.3732554884496, 2386.9522609547885, 9624.566894489764, 9931.70204893847, 9952.054554817354, 10152.492375381202, 10326.277160598762, 1718.89686618802, 1734.8983395543794, 1791.578978939948, 1830.3685228596114, 1879.1731638079202, 117859.0, 121083.07544632304, 122222.388888055, 122788.0, 123856.0, 10988.0, 11286.629807740303, 11400.258983166068, 11442.885571144348, 11590.246633968765, 14330.211838348783, 14331.211783352015, 14359.856401435853, 14377.281135943162, 14392.568222953329, 7577.764559454974, 7623.085229772352, 8414.621342039623, 8455.281301089353, 8588.012378576545, 7537.170911199763, 7815.29662330393, 8336.8332633347, 8363.372747043995, 8520.403571750043, 4338.0, 4468.207370393504, 4472.642188624902, 4474.194644963873, 4515.074409745994, 1493.873020793223, 1655.5447252005795, 1745.8865173763918, 1787.3672681099406, 1848.8352338026957, 842.4566154830101, 899.1817446123996, 903.8689390038539, 904.8868891388577, 951.7430293820768, 20081.39755807299, 20916.790832091483, 20948.790512094987, 20949.581008379748, 21134.56174203404, 931.5481991234246, 937.4984383354872, 946.1201082992745, 958.9760255993656, 997.3816233935007, 10590.046895779416, 11081.5567377304, 11177.273477223955, 11177.66467005991, 11196.104311655048, 577390.0, 581849.0, 589028.0, 594173.0, 611021.0, 75971.86042209371, 124110.37944810218, 128237.35881320534, 129956.0, 131744.0, 942.641796117471, 960.4621412009298, 967.5645959318335, 979.9314048016571, 981.1365997921926, 17555.473335799947, 18525.351612693492, 18846.0, 18898.716519251946, 18903.149358279185, 53852.0, 54965.450345496036, 55335.446645533026, 55700.77196912078, 55706.442935571744, 72175.27824721686, 73638.0, 75521.0, 76731.84902226359, 77082.2291777075, 6469.514786391133, 8012.399070069885, 8174.959125204574, 8179.222973817477, 8355.83288334242, 11433.371164586028, 11482.598109066143, 11818.940905295418, 12854.5576340495, 13130.671733206746, 10864.511097000812, 11080.39057851826, 11322.32066076038, 11337.886621133683, 11378.658640240807, 10712.410817405118, 10839.0, 10865.945670271914, 11260.774784504429, 11280.887191127984, 10656.946715266375, 11197.384143872165, 11382.715432113931, 11424.371659558805, 11493.367864767519, 8020.117787043538, 8115.188481152074, 8137.145599712182, 8163.755087347389, 8435.451695639764, 8318.542480163649, 10677.89322106769, 11261.268017578832, 11331.062758104496, 11401.258918170292], "value": 9440.529171876473, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1507.0882116319626, 1507.886908481864, 1712.400659769081, 1796.9011704356255, 1885.5757454572715, 8359.665613375457, 8899.199072083511, 8913.019567847532, 8918.375713700043, 8953.328500362466, 7779.844403111934, 8086.71696490623, 8099.55452450115, 8450.746477605682, 8703.99904011039, 1991.9442695371476, 2289.7366802817637, 2290.9083636654555, 2322.954670398319, 2331.650252462136, 1947.57153426246, 2270.6139956207485, 2274.465500607356, 2319.9884000579973, 2326.325365643961, 2298.839081264312, 2301.539692061586, 2313.7570555091725, 2315.80315673168, 2325.5232677301196, 2279.0655831109248, 2281.2243837095302, 2288.8397812153157, 2290.393045842853, 2293.529826385587, 2287.8970446329954, 2289.748127705951, 2295.988520057406, 2298.1152256381247, 2300.3214051854743, 8780.517071561126, 8844.115588441104, 8894.421862578976, 8896.37725359225, 8897.021327653953, 5192.247124167013, 5372.91940620891, 5379.569634429236, 5383.650062745909, 5401.891962160774, 3418.4359580669407, 3489.0928358626684, 3597.3165098631175, 3641.2353405784816, 3641.617630148849, 1050.2648146297622, 1058.904698577124, 1059.2055958031476, 1076.6931424544046, 1081.6971248050559, 11804.11469139818, 11856.822147667792, 11915.987141093105, 11923.523059077626, 11930.284182949052, 4479.552044795494, 4562.498125206224, 4689.95310046902, 4771.260454629538, 4772.737499437564, 9933.0, 9941.751456213653, 9957.502124893726, 9962.452065136342, 9964.402135871871, 5293.258943747878, 5295.285136506599, 5295.629305948623, 5301.443348448357, 5351.197320401893, 4311.676624253194, 4350.58669426404, 4808.63935204861, 4848.806047758121, 4856.975715121402, 3830.8276127574327, 3850.4983056607944, 3927.92144157121, 3947.7236593438756, 3967.8016099194924, 1252.6242127361754, 1253.8620751717303, 1262.2300396757971, 1264.6585421936027, 1273.4651446392538, 778.8511944881254, 819.5574389829426, 901.7700486375932, 908.318760929303, 917.417439925652, 5283.656562323436, 5489.615726899158, 5744.310682718017, 5978.790742323998, 6121.0, 944.2257348973776, 972.873526441558, 979.5445118020154, 1020.8468729690458, 1045.764702941832, 3918.3730603103368, 3919.627635374692, 3933.350997085449, 4044.8786536403954, 4047.736897101679, 10588.15294776416, 10761.354318740901, 10842.566297348176, 10869.347839129678, 10870.293430926964, 10817.459127043616, 10823.67528974132, 10824.24230303871, 10827.566897324019, 10865.076468500267, 7876.448648594544, 8131.878021829677, 8308.0, 8315.334773218128, 8317.459365141367, 4896.694830273625, 5918.852028699317, 5978.491828194478, 5998.730057147526, 6049.30433000193, 10109.797804044025, 10134.290599657954, 10175.796484070424, 10205.591776328863, 10248.771636934342, 9990.550425230736, 10263.23025773055, 10356.119729823049, 10417.968496143483, 10484.423356715453, 6556.114924485324, 6773.254941956282, 6781.559198652072, 6844.28135045813, 6912.758053468105, 7966.0, 8070.192980701883, 8087.757367278991, 8141.959290203511, 8459.280961118375, 7377.557346559223, 7482.2517748224745, 7500.399968002655, 7559.28186822251, 7609.353204977639, 7284.708611655475, 7299.6350182489605, 7322.3775979042375, 7430.071241094864, 7493.662785174572, 5832.708364581669, 7121.430285577245, 7122.145342558926, 7123.145222573325, 7125.67934442941, 5008.899822003613, 5047.066292735855, 5164.244156986642, 5174.223866420067, 5198.038362902875, 4972.577330926912, 5131.589472842238, 5185.455527169593, 5248.921266181087, 5447.809326673548], "value": 4567.1951971489125, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.4378424777171, 297.7781552743205, 306.84964367459946, 312.6982461924243, 314.97322727568155, 5235.345581802275, 5258.947410525893, 5279.815206467774, 5285.6300058995885, 5295.749306544712, 4039.9900024993754, 4835.516448355172, 4992.400911890569, 5051.722155281458, 5238.790448382069, 3488.5522508159083, 3563.465480177981, 3773.3585290500682, 3775.904987553613, 3995.960040399588, 3550.668499312758, 3967.504061992251, 3985.2228815380868, 3999.5800440953717, 4001.2597669431248, 3081.630204375478, 3911.882643520699, 3946.427767973642, 3989.0, 4003.0, 3585.7310701697215, 3628.256207477461, 3934.881953541398, 3953.4779110042555, 3976.4631774710333, 2559.9104031358997, 2872.784541159421, 2902.535594304901, 3395.219099607104, 3416.7095796857047, 4599.241125214336, 4641.793133785206, 5091.46539613339, 5091.9236211456855, 5184.377874655028, 3452.7410444216785, 3937.9015524612114, 4209.65291106846, 4336.609705126522, 4535.342375355555, 5295.629305948547, 5389.407165211824, 5471.425500322449, 5493.917591236134, 5499.532539734128, 3761.9247615047548, 3773.3208022555928, 3773.5660399054186, 3790.753601015925, 3805.1438426353848, 4607.631389488833, 4897.485763994765, 5751.654900705929, 5782.097084467967, 5828.970855145697, 3171.444997125494, 3190.409774191782, 3647.9389145132914, 3783.0353259919093, 3796.4115562087923, 3531.2054787673083, 3645.045126038225, 3802.448644946467, 3866.8066596670615, 3895.2988462076532, 4320.746983374849, 4537.705049171794, 4552.385427967182, 4555.33947577606, 4563.155816173952, 5065.924011139836, 5281.894362112736, 5515.834524964257, 5548.861278468071, 5601.411851755588, 1196.7247533067443, 1286.6783304173957, 1294.838145231846, 1306.5688322853437, 1341.5377238809745, 813.7680760983092, 838.1869586501084, 844.5186243840951, 858.888344515209, 882.6028287270753, 4310.956890431118, 5171.482851714798, 5172.8878291167575, 5175.301334319819, 5184.937087896972, 4884.949735806747, 5448.318960129984, 5483.396826349099, 5636.2954630671165, 5727.0, 4873.561379475864, 4941.158833670654, 5035.395752509722, 5076.466970968069, 5107.310513080686, 2427.5630386530593, 2932.8240305581735, 2933.266683329168, 2942.4556457055514, 2954.630671166104, 4986.4514903360605, 5142.945696132284, 5332.2801421808335, 5703.686297253608, 5746.218672211628, 4497.887552811206, 4559.6808223424705, 4748.174835972591, 5372.838814835562, 5759.366469688331, 4726.0, 4904.313396124474, 4966.659002069421, 5016.423111342169, 5706.771729130871, 5360.731963401892, 5516.786307012451, 5571.4428557143965, 5596.244506991667, 5598.86002849932, 5039.521245481674, 5503.0, 5550.167474878801, 5551.0, 5727.828165155052, 5252.161743389783, 5350.5184533392185, 5853.268341457318, 5875.500582450369, 5944.73248703818, 3593.946090808692, 3797.354449743495, 3818.675412589961, 3846.74996125251, 3857.8071096444505, 5186.844394668166, 5702.6293290935955, 5730.312362516524, 5732.799352022659, 5739.368669446441, 5567.582431317586, 5593.300837395325, 5605.663660180402, 5637.182608521659, 5649.915251271152, 5356.78572857097, 5501.394846566955, 5524.723763811874, 5556.194351819045, 5660.915086273627, 4255.8297668094165, 5413.675179489243, 5437.4562543745305, 5446.673199608036, 5447.319085114361, 4412.691111622284, 5432.940576587527, 5493.725313734377, 5545.0018996581, 5566.693831839126, 4602.700824446534, 4956.900861982812, 5000.199968005102, 5172.19830926207, 5506.568292244126], "value": 3956.0523564126497, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.5837476701364, 699.657167987686, 756.1304499825201, 786.4376970466116, 804.8511025460289, 15586.454474093409, 15854.524364269091, 15888.126153061577, 15894.96682715624, 15909.84090159098, 14042.227677477735, 14178.291085445739, 14178.929105354458, 14213.147211167337, 14612.634684132901, 662.344279163627, 682.8975653651967, 813.9837203255931, 814.7555733279992, 843.4433274039135, 646.524804268862, 832.4339449174558, 834.3908946469081, 841.5876220651897, 844.0504432513422, 878.1613559051123, 878.7846977490524, 879.7009016934242, 881.418263945796, 882.6116508736136, 692.3353580562666, 869.3827382558391, 869.7999460124144, 871.6600525794945, 871.9040905500358, 532.8960852633738, 623.5230049012509, 642.9517786166009, 648.9545731798776, 654.6955665615511, 14186.787198192036, 14235.074720143157, 14236.0, 14253.643658908506, 14297.0, 13626.523071692542, 13986.720265594733, 14100.717985640333, 14124.36440360176, 14141.151530908179, 12368.690782730413, 12707.174033687781, 12714.682132946658, 12805.295708736016, 13017.28404937728, 3934.1148241645687, 4421.204183247014, 4499.842505512292, 4695.67130300879, 4709.293605959099, 115866.0, 117306.0, 117977.41011294974, 118528.99999999914, 119617.0, 10768.40773757451, 10774.569017239379, 10814.29707069038, 10942.781144377068, 11083.778324433466, 21560.784392155878, 21666.783332166477, 21696.783032169475, 21737.45656358572, 21763.347099587038, 7778.183290754502, 7856.096548896836, 7866.567338796421, 7941.086775020946, 8045.678172873137, 3599.5680518337967, 3992.77478938914, 3994.121293315466, 4023.3160362738404, 4047.838086476567, 3369.4103531881824, 3480.651934806499, 3480.7215422766117, 3484.7038001770134, 3488.91277718054, 556.8218170185502, 625.6683957502531, 642.5670754218027, 654.8493846415352, 655.636121952321, 601.5368166511762, 603.8169147925214, 617.9104029915727, 622.7260005597523, 624.5565648389694, 8788.296936245086, 9186.502435740103, 9248.0, 9358.204552612968, 9380.906190938003, 597.8266302772234, 618.9721462534196, 620.43230444144, 626.4330780643525, 636.5003472274243, 4278.29843570354, 4313.676474264443, 4336.371226172188, 4650.697704649187, 4666.836660716858, 155183.0, 157393.0, 157978.0, 158115.41884581238, 159953.0, 139135.0, 139442.0, 139815.30092349678, 142960.0, 143235.5676443243, 97365.13174341113, 100221.0, 100948.0, 103635.0, 125001.12484814433, 9336.346455748033, 9695.224382049279, 9977.351472154287, 9992.750181245527, 9998.550065246936, 59412.70293648504, 59438.70280648569, 60397.0, 60839.39160608337, 62306.37693623006, 62746.0, 63531.729365411506, 80624.0, 84610.0, 89089.66365504652, 9055.501947392693, 11420.428978551205, 11423.200375973602, 11447.0, 11531.88468115308, 14462.783058253925, 14515.419383224553, 14555.272236387926, 14605.488807891672, 14726.631834203801, 13638.249896255802, 13716.108452950526, 13753.38109785042, 13991.58025259244, 14562.0, 11781.0, 13314.134581252187, 13392.665183370495, 14098.154110752977, 14488.492902748354, 13286.80069798973, 13456.25990570528, 13477.663058423617, 13734.725305494034, 14121.43514259418, 10777.730556736144, 11225.60710375133, 11407.771844563229, 11464.25482343645, 11517.827232591686, 9784.657536986171, 10109.696709098738, 10822.51298691545, 11200.039794065515, 11460.82808757886], "value": 7697.807841936567, "unit": "score"}], "name": "dromaeo_css", "value": 7290.080452495982, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 111.0, 111.0, 111.0, 111.0, 111.0, 111.0, 111.0, 111.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 114.0, 113.0, 151.0, 114.0, 113.0, 131.0, 113.0, 143.0, 112.0], "value": 125.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 184.0, 184.0, 184.0, 187.0, 185.0, 181.0, 177.0, 180.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 60.0, 59.0, 87.0, 62.0, 60.0, 59.0, 58.0, 82.0, 58.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 91.0, 72.0, 68.0, 68.0, 91.0, 68.0, 69.0, 90.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 84.0, 86.0, 87.0, 87.0, 85.0, 88.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 110.0, 151.0, 108.0, 92.0, 92.0, 102.0, 91.0, 92.0], "value": 102.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 142.0, 87.0, 85.0, 84.0, 84.0, 85.0, 84.0, 84.0, 83.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 53.0, 53.0, 55.0, 56.0, 55.0, 56.0, 54.0, 54.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 71.0, 68.0, 76.0, 69.0, 70.0, 70.0, 70.0, 71.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 86.0, 86.0, 87.0, 86.0, 89.0, 86.0, 92.0, 87.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 173.0, 154.0, 163.0, 213.0, 142.0], "value": 157.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 175.0, 172.0, 232.0, 207.0, 171.0, 172.0, 173.0, 171.0, 171.0], "value": 181.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 68.0, 68.0, 66.0, 68.0, 68.0, 67.0, 70.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1465.0999999999997, "alertThreshold": 2.0}]} 08:16:10 INFO - Return code: 0 08:16:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:16:10 INFO - # TBPL SUCCESS # 08:16:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:16:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:16:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:16:10 INFO - 2016-10-12 08:16:10.103 screenresolution[1668:9828] starting screenresolution argv=screenresolution get 08:16:10 INFO - 2016-10-12 08:16:10.106 screenresolution[1668:9828] Display 0: 1600x1200x32@60 08:16:10 INFO - 2016-10-12 08:16:10.112 screenresolution[1669:9831] starting screenresolution argv=screenresolution list 08:16:10 INFO - Available Modes on Display 0 08:16:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:16:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:16:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:16:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:16:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:16:10 INFO - Intel Iris: 08:16:10 INFO - Chipset Model: Intel Iris 08:16:10 INFO - Type: GPU 08:16:10 INFO - Bus: Built-In 08:16:10 INFO - VRAM (Dynamic, Max): 1536 MB 08:16:10 INFO - Vendor: Intel (0x8086) 08:16:10 INFO - Device ID: 0x0a2e 08:16:10 INFO - Revision ID: 0x0009 08:16:10 INFO - Displays: 08:16:10 INFO - Display: 08:16:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:16:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:16:10 INFO - Display Serial Number: 1600x1200 60 08:16:10 INFO - Main Display: Yes 08:16:10 INFO - Mirror: Off 08:16:10 INFO - Online: Yes 08:16:10 INFO - Rotation: Supported 08:16:10 INFO - Return code: 0 08:16:10 INFO - Running post-action listener: _resource_record_post_action 08:16:10 INFO - [mozharness: 2016-10-12 15:16:10.345812Z] Finished run-tests step (success) 08:16:10 INFO - Running post-run listener: _resource_record_post_run 08:16:10 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 38392832; Write bytes: 708370432; Read time: 380; Write time: 2091 08:16:10 INFO - TinderboxPrint: CPU usage
53.3% 08:16:10 INFO - TinderboxPrint: I/O read bytes / time
38,392,832 / 380 08:16:10 INFO - TinderboxPrint: I/O write bytes / time
708,370,432 / 2,091 08:16:10 INFO - TinderboxPrint: CPU idle
2,267.8 (46.7%) 08:16:10 INFO - TinderboxPrint: CPU system
966.7 (19.9%) 08:16:10 INFO - TinderboxPrint: CPU user
1,619.2 (33.4%) 08:16:10 INFO - TinderboxPrint: Swap in / out
459,755,520 / 0 08:16:10 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 399630848; Write bytes: 393500672; Read time: 29789; Write time: 899 08:16:10 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 31108096; Write bytes: 297986048; Read time: 291; Write time: 1162 08:16:10 INFO - Running post-run listener: _upload_blobber_files 08:16:10 INFO - Blob upload gear active. 08:16:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:16:10 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 08:16:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:16:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:16:11 INFO - (blobuploader) - INFO - Open directory for files ... 08:16:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:16:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:16:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:16:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:16:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:16:11 INFO - (blobuploader) - INFO - Done attempting. 08:16:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:16:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:16:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:16:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:16:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:16:12 INFO - (blobuploader) - INFO - Done attempting. 08:16:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:16:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:16:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:16:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:16:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:16:16 INFO - (blobuploader) - INFO - Done attempting. 08:16:16 INFO - (blobuploader) - INFO - Iteration through files over. 08:16:16 INFO - Return code: 0 08:16:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:16:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:16:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f8ea8b10ce3e45cc9a5a40bb3a95bc1712f43ce7f98da6cd840336c6449fd27b1804792eecae5baa4860d6d6045f11ae9e4d637fe571261f7831b1a15ba74e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8ca851916f2c8cda78b69d6129e7134e826c140d9c3dfeb6d1fa09b31c11ce8ed666c053eb1a4487ab17f7f91d33bcaf5234fc547cef47e806f2b65c630e4f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da3be193655bad2a5bfdac9045b656d8b4f3347bc50df519d242682c6c882d8f98d1336394c3a2d9ddaa6aec2c94788973833315d58df8c93a24283004d5afca"} 08:16:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:16:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:16:16 INFO - Contents: 08:16:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f8ea8b10ce3e45cc9a5a40bb3a95bc1712f43ce7f98da6cd840336c6449fd27b1804792eecae5baa4860d6d6045f11ae9e4d637fe571261f7831b1a15ba74e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8ca851916f2c8cda78b69d6129e7134e826c140d9c3dfeb6d1fa09b31c11ce8ed666c053eb1a4487ab17f7f91d33bcaf5234fc547cef47e806f2b65c630e4f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da3be193655bad2a5bfdac9045b656d8b4f3347bc50df519d242682c6c882d8f98d1336394c3a2d9ddaa6aec2c94788973833315d58df8c93a24283004d5afca"} 08:16:16 INFO - Running post-run listener: copy_logs_to_upload_dir 08:16:16 INFO - Copying logs to upload dir... 08:16:16 INFO - mkdir: /builds/slave/test/build/upload/logs 08:16:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.686098 ========= master_lag: 0.79 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-12 08:16:17.155332) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 08:16:17.160336) ========= 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.eQBXsDRynA/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.ARfKqb3ufF/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f8ea8b10ce3e45cc9a5a40bb3a95bc1712f43ce7f98da6cd840336c6449fd27b1804792eecae5baa4860d6d6045f11ae9e4d637fe571261f7831b1a15ba74e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8ca851916f2c8cda78b69d6129e7134e826c140d9c3dfeb6d1fa09b31c11ce8ed666c053eb1a4487ab17f7f91d33bcaf5234fc547cef47e806f2b65c630e4f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da3be193655bad2a5bfdac9045b656d8b4f3347bc50df519d242682c6c882d8f98d1336394c3a2d9ddaa6aec2c94788973833315d58df8c93a24283004d5afca"} build_url:https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010883 build_url: 'https://queue.taskcluster.net/v1/task/cjPtCzUqQc6F1ftgo9LFhQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f8ea8b10ce3e45cc9a5a40bb3a95bc1712f43ce7f98da6cd840336c6449fd27b1804792eecae5baa4860d6d6045f11ae9e4d637fe571261f7831b1a15ba74e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8ca851916f2c8cda78b69d6129e7134e826c140d9c3dfeb6d1fa09b31c11ce8ed666c053eb1a4487ab17f7f91d33bcaf5234fc547cef47e806f2b65c630e4f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da3be193655bad2a5bfdac9045b656d8b4f3347bc50df519d242682c6c882d8f98d1336394c3a2d9ddaa6aec2c94788973833315d58df8c93a24283004d5afca"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 08:16:17.199991) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 08:16:17.200392) ========= 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.eQBXsDRynA/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.ARfKqb3ufF/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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.004327 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 08:16:17.248923) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 08:16:17.249267) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 08:16:17.249640) ========= ========= Total master_lag: 2.15 =========