builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0039 starttime: 1478449929.92 results: success (0) buildid: 20161106062327 builduid: 47d125c8ce274190b99233ecf0ede7d5 revision: 673b5327afe1d489c41f683c79c6e8ad22040526 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-06 08:32:09.921373) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-06 08:32:09.921819) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-06 08:32:09.922096) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-06 08:32:09.948485) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 08:32:09.949028) ========= 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.2GrBiVO1FW/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.NN6z6lgvHz/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-06 08:32:09-- 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% 53.3M=0s 2016-11-06 08:32:10 (53.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.604609 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 08:32:10.577663) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 08:32:10.577982) ========= 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.2GrBiVO1FW/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.NN6z6lgvHz/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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.029364 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 08:32:10.628480) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-06 08:32:10.628834) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 673b5327afe1d489c41f683c79c6e8ad22040526 --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 673b5327afe1d489c41f683c79c6e8ad22040526 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2GrBiVO1FW/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.NN6z6lgvHz/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-06 08:32:10,711 truncating revision to first 12 chars 2016-11-06 08:32:10,711 Setting DEBUG logging. 2016-11-06 08:32:10,711 attempt 1/10 2016-11-06 08:32:10,711 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/673b5327afe1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-06 08:32:13,028 unpacking tar archive at: mozilla-central-673b5327afe1/testing/mozharness/ program finished with exit code 0 elapsedTime=2.576159 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-06 08:32:13.228175) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-06 08:32:13.228484) ========= script_repo_revision: 673b5327afe1d489c41f683c79c6e8ad22040526 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-06 08:32:13.228888) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-06 08:32:13.229168) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-06 08:32:13.248366) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-06 08:32:13.248694) ========= /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.2GrBiVO1FW/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.NN6z6lgvHz/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:32:13 INFO - MultiFileLogger online at 20161106 08:32:13 in /builds/slave/test 08:32:13 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 08:32:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:32:13 INFO - {'append_to_log': False, 08:32:13 INFO - 'base_work_dir': '/builds/slave/test', 08:32:13 INFO - 'blob_upload_branch': 'Firefox', 08:32:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:32:13 INFO - 'branch': 'Firefox', 08:32:13 INFO - 'buildbot_json_path': 'buildprops.json', 08:32:13 INFO - 'config_files': ('talos/mac_config.py',), 08:32:13 INFO - 'default_actions': ('clobber', 08:32:13 INFO - 'read-buildbot-config', 08:32:13 INFO - 'download-and-extract', 08:32:13 INFO - 'populate-webroot', 08:32:13 INFO - 'create-virtualenv', 08:32:13 INFO - 'install', 08:32:13 INFO - 'run-tests'), 08:32:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:32:13 INFO - 'download_minidump_stackwalk': True, 08:32:13 INFO - 'download_symbols': 'ondemand', 08:32:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:32:13 INFO - 'tooltool.py': '/tools/tooltool.py', 08:32:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:32:13 INFO - '/tools/misc-python/virtualenv.py')}, 08:32:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:32:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:32:13 INFO - 'installer_path': 'installer.exe', 08:32:13 INFO - 'log_level': 'info', 08:32:13 INFO - 'log_name': 'talos', 08:32:13 INFO - 'log_to_console': True, 08:32:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:32:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:32:13 INFO - 'opt_config_files': (), 08:32:13 INFO - 'pip_index': False, 08:32:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:32:13 INFO - 'cmd': ('bash', 08:32:13 INFO - '-c', 08:32:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:32:13 INFO - 'enabled': True, 08:32:13 INFO - 'halt_on_failure': False, 08:32:13 INFO - 'name': 'check_screen_resolution'},), 08:32:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:32:13 INFO - 'cmd': ('bash', 08:32:13 INFO - '-c', 08:32:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:32:13 INFO - 'enabled': True, 08:32:13 INFO - 'halt_on_failure': False, 08:32:13 INFO - 'name': 'check_screen_resolution'},), 08:32:13 INFO - 'run_cmd_checks_enabled': True, 08:32:13 INFO - 'sps_profile': False, 08:32:13 INFO - 'sps_profile_interval': 0, 08:32:13 INFO - 'suite': 'dromaeojs', 08:32:13 INFO - 'system_bits': '32', 08:32:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:32:13 INFO - 'title': 't-yosemite-r7-0039', 08:32:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:32:13 INFO - 'use_talos_json': True, 08:32:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:32:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:32:13 INFO - 'work_dir': 'build'} 08:32:13 INFO - [mozharness: 2016-11-06 16:32:13.459569Z] Running clobber step. 08:32:13 INFO - Running pre-action listener: _resource_record_pre_action 08:32:13 INFO - Running main action method: clobber 08:32:13 INFO - rmtree: /builds/slave/test/build 08:32:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:32:15 INFO - Running post-action listener: _resource_record_post_action 08:32:15 INFO - [mozharness: 2016-11-06 16:32:15.588585Z] Finished clobber step (success) 08:32:15 INFO - [mozharness: 2016-11-06 16:32:15.588724Z] Running read-buildbot-config step. 08:32:15 INFO - Running pre-action listener: _resource_record_pre_action 08:32:15 INFO - Running main action method: read_buildbot_config 08:32:15 INFO - Using buildbot properties: 08:32:15 INFO - { 08:32:15 INFO - "project": "", 08:32:15 INFO - "product": "firefox", 08:32:15 INFO - "script_repo_revision": "production", 08:32:15 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:32:15 INFO - "repository": "", 08:32:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:32:15 INFO - "buildid": "20161106062327", 08:32:15 INFO - "pgo_build": "False", 08:32:15 INFO - "basedir": "/builds/slave/test", 08:32:15 INFO - "buildnumber": 132, 08:32:15 INFO - "slavename": "t-yosemite-r7-0039", 08:32:15 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:32:15 INFO - "platform": "yosemite_r7", 08:32:15 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:32:15 INFO - "branch": "mozilla-central", 08:32:15 INFO - "repo_path": "mozilla-central", 08:32:15 INFO - "revision": "673b5327afe1d489c41f683c79c6e8ad22040526", 08:32:15 INFO - "stage_platform": "macosx64", 08:32:15 INFO - "builduid": "47d125c8ce274190b99233ecf0ede7d5", 08:32:15 INFO - "slavebuilddir": "test" 08:32:15 INFO - } 08:32:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 08:32:15 INFO - Running post-action listener: _resource_record_post_action 08:32:15 INFO - [mozharness: 2016-11-06 16:32:15.592054Z] Finished read-buildbot-config step (success) 08:32:15 INFO - [mozharness: 2016-11-06 16:32:15.592200Z] Running download-and-extract step. 08:32:15 INFO - Running pre-action listener: _resource_record_pre_action 08:32:15 INFO - Running main action method: download_and_extract 08:32:15 INFO - mkdir: /builds/slave/test/build/tests 08:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:15 INFO - trying https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 08:32:15 INFO - Downloading https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/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 08:32:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/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 08:32:16 INFO - Downloaded 1199 bytes. 08:32:16 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:32:16 INFO - Using the following test package requirements: 08:32:16 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:32:16 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:32:16 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 08:32:16 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:32:16 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 08:32:16 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:32:16 INFO - u'jsshell-mac.zip'], 08:32:16 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:32:16 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 08:32:16 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:32:16 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:32:16 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 08:32:16 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:32:16 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 08:32:16 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:32:16 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 08:32:16 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:32:16 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 08:32:16 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:32:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:32:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:32:16 INFO - Fetch https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:32:17 INFO - Http code: 200 08:32:17 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 08:32:17 INFO - x-amz-cf-id: 99uTeFEi-CRWYitlwfz8Xyps4gtEHUCP4NtjCKMfF1MMrr7dWdhgSQ== 08:32:17 INFO - x-amz-version-id: _vmv5enEhZiDidlfNnZeUlGQCBHCrSj1 08:32:17 INFO - Expected file size: 16308037 08:32:17 INFO - Obtained file size: 16308037 08:32:24 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 08:32:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:32:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:32:24 INFO - Fetch https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:32:25 INFO - Http code: 200 08:32:25 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 08:32:25 INFO - x-amz-cf-id: JrNORRZuZp_T2ruShhVu8Zr9wW9AsbOkZQdDRi-es5wWP_N3JcPoew== 08:32:25 INFO - x-amz-version-id: _vmv5enEhZiDidlfNnZeUlGQCBHCrSj1 08:32:25 INFO - Expected file size: 16308037 08:32:25 INFO - Obtained file size: 16308037 08:32:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 08:32:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:32:31 INFO - Fetch https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 08:32:32 INFO - Http code: 200 08:32:32 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 08:32:32 INFO - x-amz-cf-id: C1naJNP0y17B7QT5BLoJcKtXc6P4ghvYeKZtZDqVQUlROph-2xkdcw== 08:32:32 INFO - x-amz-version-id: NjkcEvJC2Qjk3lF_vnx2aXtA0ViGa8Bc 08:32:32 INFO - Expected file size: 14409038 08:32:32 INFO - Obtained file size: 14409038 08:32:32 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')]} 08:32:32 INFO - trying https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:32:32 INFO - Downloading https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 08:32:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/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 08:32:35 INFO - Downloaded 111505996 bytes. 08:32:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:32:35 INFO - mkdir: /builds/slave/test/properties 08:32:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:32:35 INFO - Writing to file /builds/slave/test/properties/build_url 08:32:35 INFO - Contents: 08:32:35 INFO - build_url:https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:32:36 INFO - Running post-action listener: _resource_record_post_action 08:32:36 INFO - Running post-action listener: set_extra_try_arguments 08:32:36 INFO - [mozharness: 2016-11-06 16:32:36.386866Z] Finished download-and-extract step (success) 08:32:36 INFO - [mozharness: 2016-11-06 16:32:36.387000Z] Running populate-webroot step. 08:32:36 INFO - Running pre-action listener: _resource_record_pre_action 08:32:36 INFO - Running main action method: populate_webroot 08:32:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:32:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:32:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:32:36 INFO - '--tppagecycles', 08:32:36 INFO - '7'], 08:32:36 INFO - 'tests': ['tsvgm']}}, 08:32:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:32:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:32:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:32:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:32:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:32:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:32:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:36 INFO - 'talos_options': ['--disable-e10s'], 08:32:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:32:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:32:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:36 INFO - 'talos_options': ['--disable-e10s'], 08:32:36 INFO - 'tests': ['damp', 'tps']}, 08:32:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:36 INFO - 'tests': ['damp', 'tps']}, 08:32:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:32:36 INFO - 'tests': ['dromaeo_dom']}, 08:32:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:32:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:32:36 INFO - 'tests': ['basic_compositor_video']}, 08:32:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:32:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:32:36 INFO - 'tests': ['a11yr', 08:32:36 INFO - 'ts_paint', 08:32:36 INFO - 'tpaint', 08:32:36 INFO - 'sessionrestore', 08:32:36 INFO - 'sessionrestore_no_auto_restore', 08:32:36 INFO - 'tabpaint']}, 08:32:36 INFO - 'other-e10s': {'tests': ['a11yr', 08:32:36 INFO - 'ts_paint', 08:32:36 INFO - 'tpaint', 08:32:36 INFO - 'sessionrestore', 08:32:36 INFO - 'sessionrestore_no_auto_restore', 08:32:36 INFO - 'tabpaint']}, 08:32:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:32:36 INFO - 'ts_paint', 08:32:36 INFO - 'tpaint', 08:32:36 INFO - 'sessionrestore', 08:32:36 INFO - 'sessionrestore_no_auto_restore', 08:32:36 INFO - 'tabpaint']}, 08:32:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:32:36 INFO - 'ts_paint', 08:32:36 INFO - 'tpaint', 08:32:36 INFO - 'sessionrestore', 08:32:36 INFO - 'sessionrestore_no_auto_restore', 08:32:36 INFO - 'tabpaint']}, 08:32:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:32:36 INFO - 'tests': ['a11yr', 08:32:36 INFO - 'ts_paint', 08:32:36 INFO - 'tpaint', 08:32:36 INFO - 'sessionrestore', 08:32:36 INFO - 'sessionrestore_no_auto_restore', 08:32:36 INFO - 'tabpaint']}, 08:32:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:32:36 INFO - 'tests': ['a11yr', 08:32:36 INFO - 'ts_paint', 08:32:36 INFO - 'tpaint', 08:32:36 INFO - 'sessionrestore', 08:32:36 INFO - 'sessionrestore_no_auto_restore', 08:32:36 INFO - 'tabpaint']}, 08:32:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:32:36 INFO - 'tests': ['tsvgx', 08:32:36 INFO - 'tsvgr_opacity', 08:32:36 INFO - 'tart', 08:32:36 INFO - 'tscrollx', 08:32:36 INFO - 'cart']}, 08:32:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:32:36 INFO - 'tsvgr_opacity', 08:32:36 INFO - 'tart', 08:32:36 INFO - 'tscrollx', 08:32:36 INFO - 'cart']}, 08:32:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:36 INFO - 'talos_options': ['--disable-e10s'], 08:32:36 INFO - 'tests': ['tp5o']}, 08:32:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:36 INFO - 'tests': ['tp5o']}, 08:32:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:36 INFO - 'talos_options': ['--disable-e10s', 08:32:36 INFO - '--xperf_path', 08:32:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:32:36 INFO - 'tests': ['tp5n']}, 08:32:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:32:36 INFO - 'talos_options': ['--xperf_path', 08:32:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:32:36 INFO - 'tests': ['tp5n']}}, 08:32:36 INFO - 'talos.zip': {'path': '', 08:32:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:32:36 INFO - Running post-action listener: _resource_record_post_action 08:32:36 INFO - [mozharness: 2016-11-06 16:32:36.406080Z] Finished populate-webroot step (success) 08:32:36 INFO - [mozharness: 2016-11-06 16:32:36.406192Z] Running create-virtualenv step. 08:32:36 INFO - Running pre-action listener: _resource_record_pre_action 08:32:36 INFO - Running main action method: create_virtualenv 08:32:36 INFO - Creating virtualenv /builds/slave/test/build/venv 08:32:36 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:32:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:32:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:32:36 INFO - Using real prefix '/tools/python27' 08:32:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:32:37 INFO - Installing distribute.............................................................................................................................................................................................done. 08:32:40 INFO - Installing pip.................done. 08:32:40 INFO - Return code: 0 08:32:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:32:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:32:40 INFO - Reading from file tmpfile_stdout 08:32:40 INFO - Output received: 08:32:40 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:32:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:32:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:40 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 0x10d1a1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d117570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84934c9660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb5d30>, '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 0x10cddbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfadb20>, '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.2GrBiVO1FW/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.NN6z6lgvHz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:32:40 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 08:32:40 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 08:32:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GrBiVO1FW/Render', 08:32:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:40 INFO - 'HOME': '/Users/cltbld', 08:32:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:40 INFO - 'LOGNAME': 'cltbld', 08:32:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:40 INFO - 'MOZ_NO_REMOTE': '1', 08:32:40 INFO - 'NO_EM_RESTART': '1', 08:32:40 INFO - 'PAGER': '/bin/cat', 08:32:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:40 INFO - 'PWD': '/builds/slave/test', 08:32:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:40 INFO - 'SHELL': '/bin/bash', 08:32:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NN6z6lgvHz/Listeners', 08:32:40 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:32:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:40 INFO - 'USER': 'cltbld', 08:32:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:40 INFO - 'XPC_FLAGS': '0x0', 08:32:40 INFO - 'XPC_SERVICE_NAME': '0', 08:32:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:40 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 08:32:40 INFO - Cleaning up... 08:32:40 INFO - Return code: 0 08:32:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:32:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:40 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 0x10d1a1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d117570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84934c9660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb5d30>, '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 0x10cddbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfadb20>, '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.2GrBiVO1FW/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.NN6z6lgvHz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:32:40 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 08:32:40 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 08:32:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GrBiVO1FW/Render', 08:32:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:40 INFO - 'HOME': '/Users/cltbld', 08:32:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:40 INFO - 'LOGNAME': 'cltbld', 08:32:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:40 INFO - 'MOZ_NO_REMOTE': '1', 08:32:40 INFO - 'NO_EM_RESTART': '1', 08:32:40 INFO - 'PAGER': '/bin/cat', 08:32:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:40 INFO - 'PWD': '/builds/slave/test', 08:32:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:40 INFO - 'SHELL': '/bin/bash', 08:32:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NN6z6lgvHz/Listeners', 08:32:40 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:32:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:40 INFO - 'USER': 'cltbld', 08:32:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:40 INFO - 'XPC_FLAGS': '0x0', 08:32:40 INFO - 'XPC_SERVICE_NAME': '0', 08:32:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:40 INFO - Downloading/unpacking psutil>=3.1.1 08:32: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 08:32: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 08:32: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 08:32: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 08:32: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 08:32: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 08:32:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:32:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:32:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:32:44 INFO - Installing collected packages: psutil 08:32:44 INFO - Running setup.py install for psutil 08:32:44 INFO - building 'psutil._psutil_osx' extension 08:32:44 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 08:32:45 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 08:32:45 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 08:32:45 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 08:32:45 INFO - building 'psutil._psutil_posix' extension 08:32:45 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 08:32:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:32:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:32:45 INFO - ^ 08:32:45 INFO - 1 warning generated. 08:32:45 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 08:32:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:32:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:32:46 INFO - Successfully installed psutil 08:32:46 INFO - Cleaning up... 08:32:46 INFO - Return code: 0 08:32:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:32:46 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')]} 08:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:46 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')]} 08:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:46 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 0x10d1a1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d117570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84934c9660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb5d30>, '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 0x10cddbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfadb20>, '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.2GrBiVO1FW/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.NN6z6lgvHz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:32:46 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 08:32:46 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 08:32:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GrBiVO1FW/Render', 08:32:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:46 INFO - 'HOME': '/Users/cltbld', 08:32:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:46 INFO - 'LOGNAME': 'cltbld', 08:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:46 INFO - 'MOZ_NO_REMOTE': '1', 08:32:46 INFO - 'NO_EM_RESTART': '1', 08:32:46 INFO - 'PAGER': '/bin/cat', 08:32:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:46 INFO - 'PWD': '/builds/slave/test', 08:32:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:46 INFO - 'SHELL': '/bin/bash', 08:32:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NN6z6lgvHz/Listeners', 08:32:46 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:32:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:46 INFO - 'USER': 'cltbld', 08:32:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:46 INFO - 'XPC_FLAGS': '0x0', 08:32:46 INFO - 'XPC_SERVICE_NAME': '0', 08:32:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:32:46 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 08:32:46 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 08:32:46 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 08:32:46 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 08:32:46 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 08:32:46 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 08:32:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:32:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:32:49 INFO - Installing collected packages: mozsystemmonitor 08:32:49 INFO - Running setup.py install for mozsystemmonitor 08:32:49 INFO - Successfully installed mozsystemmonitor 08:32:49 INFO - Cleaning up... 08:32:49 INFO - Return code: 0 08:32:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:32: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')]} 08:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32: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')]} 08:32:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:49 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 0x10d1a1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d117570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84934c9660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb5d30>, '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 0x10cddbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfadb20>, '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.2GrBiVO1FW/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.NN6z6lgvHz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:32:49 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 08:32:49 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 08:32:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GrBiVO1FW/Render', 08:32:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:49 INFO - 'HOME': '/Users/cltbld', 08:32:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:49 INFO - 'LOGNAME': 'cltbld', 08:32:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:49 INFO - 'MOZ_NO_REMOTE': '1', 08:32:49 INFO - 'NO_EM_RESTART': '1', 08:32:49 INFO - 'PAGER': '/bin/cat', 08:32:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:49 INFO - 'PWD': '/builds/slave/test', 08:32:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:49 INFO - 'SHELL': '/bin/bash', 08:32:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NN6z6lgvHz/Listeners', 08:32:49 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:32:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:49 INFO - 'USER': 'cltbld', 08:32:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:49 INFO - 'XPC_FLAGS': '0x0', 08:32:49 INFO - 'XPC_SERVICE_NAME': '0', 08:32:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:49 INFO - Downloading/unpacking jsonschema==2.5.1 08:32:49 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 08:32:49 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 08:32:49 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 08:32:49 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 08:32:49 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 08:32:49 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 08:32:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:32:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:32: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 08:32: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 08:32: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 08:32: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 08:32: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 08:32: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 08:32:52 INFO - Downloading functools32-3.2.3-2.tar.gz 08:32:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:32:52 INFO - warning: no files found matching '*.txt' 08:32:52 INFO - no previously-included directories found matching 'build' 08:32:52 INFO - no previously-included directories found matching 'dist' 08:32:52 INFO - no previously-included directories found matching '.git*' 08:32:52 INFO - Installing collected packages: jsonschema, functools32 08:32:52 INFO - Running setup.py install for functools32 08:32:52 INFO - warning: no files found matching '*.txt' 08:32:52 INFO - no previously-included directories found matching 'build' 08:32:52 INFO - no previously-included directories found matching 'dist' 08:32:52 INFO - no previously-included directories found matching '.git*' 08:32:52 INFO - Successfully installed jsonschema functools32 08:32:52 INFO - Cleaning up... 08:32:52 INFO - Return code: 0 08:32:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:32:52 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')]} 08:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:52 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')]} 08:32:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:52 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 0x10d1a1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d117570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84934c9660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb5d30>, '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 0x10cddbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfadb20>, '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.2GrBiVO1FW/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.NN6z6lgvHz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:32:52 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 08:32:52 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 08:32:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GrBiVO1FW/Render', 08:32:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:52 INFO - 'HOME': '/Users/cltbld', 08:32:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:52 INFO - 'LOGNAME': 'cltbld', 08:32:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:52 INFO - 'MOZ_NO_REMOTE': '1', 08:32:52 INFO - 'NO_EM_RESTART': '1', 08:32:52 INFO - 'PAGER': '/bin/cat', 08:32:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:52 INFO - 'PWD': '/builds/slave/test', 08:32:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:52 INFO - 'SHELL': '/bin/bash', 08:32:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NN6z6lgvHz/Listeners', 08:32:52 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:32:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:52 INFO - 'USER': 'cltbld', 08:32:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:52 INFO - 'XPC_FLAGS': '0x0', 08:32:52 INFO - 'XPC_SERVICE_NAME': '0', 08:32:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:32:53 INFO - Cleaning up... 08:32:53 INFO - Return code: 0 08:32:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:32:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d1a1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d117570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84934c9660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb5d30>, '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 0x10cddbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfadb20>, '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.2GrBiVO1FW/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.NN6z6lgvHz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:32:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:32:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:32:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GrBiVO1FW/Render', 08:32:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:53 INFO - 'HOME': '/Users/cltbld', 08:32:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:53 INFO - 'LOGNAME': 'cltbld', 08:32:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:53 INFO - 'MOZ_NO_REMOTE': '1', 08:32:53 INFO - 'NO_EM_RESTART': '1', 08:32:53 INFO - 'PAGER': '/bin/cat', 08:32:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:53 INFO - 'PWD': '/builds/slave/test', 08:32:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:53 INFO - 'SHELL': '/bin/bash', 08:32:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NN6z6lgvHz/Listeners', 08:32:53 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:32:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:53 INFO - 'USER': 'cltbld', 08:32:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:53 INFO - 'XPC_FLAGS': '0x0', 08:32:53 INFO - 'XPC_SERVICE_NAME': '0', 08:32:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:53 INFO - Downloading/unpacking blobuploader==1.2.4 08:32:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:55 INFO - Downloading blobuploader-1.2.4.tar.gz 08:32:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:32:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:32:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:57 INFO - Downloading docopt-0.6.1.tar.gz 08:32:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:32:57 INFO - Installing collected packages: blobuploader, requests, docopt 08:32:57 INFO - Running setup.py install for blobuploader 08:32:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:32:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:32:57 INFO - Running setup.py install for requests 08:32:57 INFO - Running setup.py install for docopt 08:32:57 INFO - Successfully installed blobuploader requests docopt 08:32:57 INFO - Cleaning up... 08:32:58 INFO - Return code: 0 08:32:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:32:58 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')]} 08:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:58 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')]} 08:32:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:58 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 0x10d1a1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d117570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84934c9660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb5d30>, '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 0x10cddbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfadb20>, '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.2GrBiVO1FW/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.NN6z6lgvHz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:32:58 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 08:32:58 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 08:32:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GrBiVO1FW/Render', 08:32:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:58 INFO - 'HOME': '/Users/cltbld', 08:32:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:58 INFO - 'LOGNAME': 'cltbld', 08:32:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:58 INFO - 'MOZ_NO_REMOTE': '1', 08:32:58 INFO - 'NO_EM_RESTART': '1', 08:32:58 INFO - 'PAGER': '/bin/cat', 08:32:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:58 INFO - 'PWD': '/builds/slave/test', 08:32:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:58 INFO - 'SHELL': '/bin/bash', 08:32:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NN6z6lgvHz/Listeners', 08:32:58 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:32:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:58 INFO - 'USER': 'cltbld', 08:32:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:58 INFO - 'XPC_FLAGS': '0x0', 08:32:58 INFO - 'XPC_SERVICE_NAME': '0', 08:32:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:32:58 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-BKD6Lh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:32:58 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ok1eX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:32:58 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-43gjex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:32:58 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-cboHFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:32:58 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-_CzFZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:32:58 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-tUHtpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:32:58 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-gosrtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:32:59 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-SfJQyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:32:59 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ZRu4RK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:32:59 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-pfheTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:32:59 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-Rs6bM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:32:59 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-LeEHcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:32:59 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-IndCF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:32:59 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-UBAzio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:59 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-jTjkTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:32:59 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-62KDjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:33:00 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-CvwTlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:33:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:33:00 INFO - Running setup.py install for manifestparser 08:33:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:33:00 INFO - Running setup.py install for mozcrash 08:33:00 INFO - Running setup.py install for mozdebug 08:33:00 INFO - Running setup.py install for mozdevice 08:33:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:33:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:33:00 INFO - Running setup.py install for mozfile 08:33:00 INFO - Running setup.py install for mozhttpd 08:33:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:33:00 INFO - Running setup.py install for mozinfo 08:33:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:33:01 INFO - Running setup.py install for mozInstall 08:33:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:33:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:33:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:33:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:33:01 INFO - Running setup.py install for mozleak 08:33:01 INFO - Running setup.py install for mozlog 08:33:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:33:01 INFO - Running setup.py install for moznetwork 08:33:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:33:01 INFO - Running setup.py install for mozprocess 08:33:01 INFO - Running setup.py install for mozprofile 08:33:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:33:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:33:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:33:02 INFO - Running setup.py install for mozrunner 08:33:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:33:02 INFO - Running setup.py install for mozscreenshot 08:33:02 INFO - Running setup.py install for moztest 08:33:02 INFO - Running setup.py install for mozversion 08:33:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:33:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:33:02 INFO - Cleaning up... 08:33:02 INFO - Return code: 0 08:33:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:02 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 0x10d1a1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d117570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84934c9660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb5d30>, '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 0x10cddbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfadb20>, '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.2GrBiVO1FW/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.NN6z6lgvHz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:33:02 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 08:33:02 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 08:33:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GrBiVO1FW/Render', 08:33:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:33:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:33:02 INFO - 'HOME': '/Users/cltbld', 08:33:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:33:02 INFO - 'LOGNAME': 'cltbld', 08:33:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:02 INFO - 'MOZ_NO_REMOTE': '1', 08:33:02 INFO - 'NO_EM_RESTART': '1', 08:33:02 INFO - 'PAGER': '/bin/cat', 08:33:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:33:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:02 INFO - 'PWD': '/builds/slave/test', 08:33:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:33:02 INFO - 'SHELL': '/bin/bash', 08:33:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NN6z6lgvHz/Listeners', 08:33:02 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:33:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:33:02 INFO - 'USER': 'cltbld', 08:33:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:33:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:33:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:02 INFO - 'XPC_FLAGS': '0x0', 08:33:02 INFO - 'XPC_SERVICE_NAME': '0', 08:33:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:33:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:33:02 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-AloDXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:33:03 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)) 08:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:33:03 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-rXiUcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:33:03 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)) 08:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:33:03 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-uZr4gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:33:03 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)) 08:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:33:03 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ONnt_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:33:03 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)) 08:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:33:03 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-OBJN2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:33:03 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)) 08:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:33:03 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-wu0Obg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:33:03 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)) 08:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:33:03 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-wrXgs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:33:03 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)) 08:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:33:03 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-62g44q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:33:03 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)) 08:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:33:03 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-zjHP7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:33:03 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)) 08:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:33:03 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-tuTTab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:33:04 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)) 08:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:33:04 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-_Fp5yG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:33:04 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)) 08:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:33:04 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-WDv_5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:33:04 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)) 08:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:33:04 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-vvMp6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:33:04 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)) 08:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:33:04 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-UZ1G1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:33:04 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)) 08:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:33:04 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-1FYXwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:33:04 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)) 08:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:33:04 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-_w7uqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:33:04 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)) 08:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:33:04 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-e5RV41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:33:04 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)) 08:33:04 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)) 08:33:04 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)) 08:33:04 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)) 08:33:04 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)) 08:33:04 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)) 08:33:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:33:04 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 08:33:04 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 08:33:04 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 08:33:04 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 08:33:04 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 08:33:04 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 08:33:07 INFO - Downloading blessings-1.6.tar.gz 08:33:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:33:07 INFO - Installing collected packages: blessings 08:33:07 INFO - Running setup.py install for blessings 08:33:07 INFO - Successfully installed blessings 08:33:07 INFO - Cleaning up... 08:33:07 INFO - Return code: 0 08:33:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:33:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:33:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:33:07 INFO - Reading from file tmpfile_stdout 08:33:07 INFO - Current package versions: 08:33:07 INFO - blessings == 1.6 08:33:07 INFO - blobuploader == 1.2.4 08:33:07 INFO - docopt == 0.6.1 08:33:07 INFO - functools32 == 3.2.3-2 08:33:07 INFO - jsonschema == 2.5.1 08:33:07 INFO - manifestparser == 1.1 08:33:07 INFO - mozInstall == 1.12 08:33:07 INFO - mozcrash == 0.17 08:33:07 INFO - mozdebug == 0.1 08:33:07 INFO - mozdevice == 0.48 08:33:07 INFO - mozfile == 1.2 08:33:07 INFO - mozhttpd == 0.7 08:33:07 INFO - mozinfo == 0.9 08:33:07 INFO - mozleak == 0.1 08:33:07 INFO - mozlog == 3.4 08:33:07 INFO - moznetwork == 0.27 08:33:07 INFO - mozprocess == 0.23 08:33:07 INFO - mozprofile == 0.28 08:33:07 INFO - mozrunner == 6.12 08:33:07 INFO - mozscreenshot == 0.1 08:33:07 INFO - mozsystemmonitor == 0.3 08:33:07 INFO - moztest == 0.7 08:33:07 INFO - mozversion == 1.4 08:33:07 INFO - psutil == 3.1.1 08:33:07 INFO - requests == 1.2.3 08:33:07 INFO - wsgiref == 0.1.2 08:33:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:33:07 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')]} 08:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:07 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')]} 08:33:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:07 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 0x10d1a1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d117570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84934c9660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb5d30>, '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 0x10cddbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfadb20>, '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.2GrBiVO1FW/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.NN6z6lgvHz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:33:07 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 08:33:07 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 08:33:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GrBiVO1FW/Render', 08:33:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:33:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:33:07 INFO - 'HOME': '/Users/cltbld', 08:33:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:33:07 INFO - 'LOGNAME': 'cltbld', 08:33:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:07 INFO - 'MOZ_NO_REMOTE': '1', 08:33:07 INFO - 'NO_EM_RESTART': '1', 08:33:07 INFO - 'PAGER': '/bin/cat', 08:33:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:33:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:07 INFO - 'PWD': '/builds/slave/test', 08:33:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:33:07 INFO - 'SHELL': '/bin/bash', 08:33:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NN6z6lgvHz/Listeners', 08:33:07 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:33:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:33:07 INFO - 'USER': 'cltbld', 08:33:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:33:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:33:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:07 INFO - 'XPC_FLAGS': '0x0', 08:33:07 INFO - 'XPC_SERVICE_NAME': '0', 08:33:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:33:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:08 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)) 08:33:08 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)) 08:33:08 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)) 08:33:08 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)) 08:33:08 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)) 08:33:08 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)) 08:33:08 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)) 08:33:08 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)) 08:33:08 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)) 08:33:08 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)) 08:33:08 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)) 08:33:08 INFO - Cleaning up... 08:33:08 INFO - Return code: 0 08:33:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:33:08 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')]} 08:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:08 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')]} 08:33:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:08 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 0x10d1a1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d117570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84934c9660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb5d30>, '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 0x10cddbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfadb20>, '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.2GrBiVO1FW/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.NN6z6lgvHz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:33:08 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 08:33:08 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 08:33:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GrBiVO1FW/Render', 08:33:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:33:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:33:08 INFO - 'HOME': '/Users/cltbld', 08:33:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:33:08 INFO - 'LOGNAME': 'cltbld', 08:33:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:08 INFO - 'MOZ_NO_REMOTE': '1', 08:33:08 INFO - 'NO_EM_RESTART': '1', 08:33:08 INFO - 'PAGER': '/bin/cat', 08:33:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:33:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:08 INFO - 'PWD': '/builds/slave/test', 08:33:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:33:08 INFO - 'SHELL': '/bin/bash', 08:33:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NN6z6lgvHz/Listeners', 08:33:08 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:33:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:33:08 INFO - 'USER': 'cltbld', 08:33:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:33:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:33:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:08 INFO - 'XPC_FLAGS': '0x0', 08:33:08 INFO - 'XPC_SERVICE_NAME': '0', 08:33:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:33:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:33:08 INFO - Cleaning up... 08:33:08 INFO - Return code: 0 08:33:08 INFO - Running post-action listener: _resource_record_post_action 08:33:08 INFO - Running post-action listener: _start_resource_monitoring 08:33:08 INFO - Starting resource monitoring. 08:33:08 INFO - [mozharness: 2016-11-06 16:33:08.499257Z] Finished create-virtualenv step (success) 08:33:08 INFO - [mozharness: 2016-11-06 16:33:08.500337Z] Running install step. 08:33:08 INFO - Running pre-action listener: _resource_record_pre_action 08:33:08 INFO - Running main action method: install 08:33:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:33:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:33:08 INFO - Reading from file tmpfile_stdout 08:33:08 INFO - Detecting whether we're running mozinstall >=1.0... 08:33:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:33:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:33:08 INFO - Reading from file tmpfile_stdout 08:33:08 INFO - Output received: 08:33:08 INFO - Usage: mozinstall [options] installer 08:33:08 INFO - Options: 08:33:08 INFO - -h, --help show this help message and exit 08:33:08 INFO - -d DEST, --destination=DEST 08:33:08 INFO - Directory to install application into. [default: 08:33:08 INFO - "/builds/slave/test"] 08:33:08 INFO - --app=APP Application being installed. [default: firefox] 08:33:08 INFO - mkdir: /builds/slave/test/build/application 08:33:08 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'] 08:33:08 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 08:33:42 INFO - Reading from file tmpfile_stdout 08:33:42 INFO - Output received: 08:33:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:33:42 INFO - Running post-action listener: _resource_record_post_action 08:33:42 INFO - [mozharness: 2016-11-06 16:33:42.596208Z] Finished install step (success) 08:33:42 INFO - [mozharness: 2016-11-06 16:33:42.596361Z] Running run-tests step. 08:33:42 INFO - Running pre-action listener: _resource_record_pre_action 08:33:42 INFO - Running main action method: run_tests 08:33:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:33:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:33:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:33:42 INFO - 2016-11-06 08:33:42.610 screenresolution[1652:10011] starting screenresolution argv=screenresolution get 08:33:42 INFO - 2016-11-06 08:33:42.613 screenresolution[1652:10011] Display 0: 1600x1200x32@60 08:33:42 INFO - 2016-11-06 08:33:42.619 screenresolution[1653:10013] starting screenresolution argv=screenresolution list 08:33:42 INFO - Available Modes on Display 0 08:33:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:33:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:33:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:33:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:33:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:33:42 INFO - Intel Iris: 08:33:42 INFO - Chipset Model: Intel Iris 08:33:42 INFO - Type: GPU 08:33:42 INFO - Bus: Built-In 08:33:42 INFO - VRAM (Dynamic, Max): 1536 MB 08:33:42 INFO - Vendor: Intel (0x8086) 08:33:42 INFO - Device ID: 0x0a2e 08:33:42 INFO - Revision ID: 0x0009 08:33:42 INFO - Displays: 08:33:42 INFO - Display: 08:33:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:33:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:33:42 INFO - Display Serial Number: 1600x1200 60 08:33:42 INFO - Main Display: Yes 08:33:42 INFO - Mirror: Off 08:33:42 INFO - Online: Yes 08:33:42 INFO - Rotation: Supported 08:33:42 INFO - Return code: 0 08:33:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:33:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:33:42 INFO - Python 2.7.3 08:33:42 INFO - Return code: 0 08:33:42 INFO - grabbing minidump binary from tooltool 08:33:42 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')]} 08:33:42 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 0x10cfb5d30>, '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 0x10cddbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfadb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:33:42 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 08:33:42 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 08:33:42 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 08:33:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:33:42 INFO - Return code: 0 08:33:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:33:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:33:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:33:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:33:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:33:42 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/CjrCC5fVQqqxJ7D5jme8dQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--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 08:33:42 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/CjrCC5fVQqqxJ7D5jme8dQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0039 --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 08:33:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GrBiVO1FW/Render', 08:33:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:33:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:33:42 INFO - 'HOME': '/Users/cltbld', 08:33:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:33:42 INFO - 'LOGNAME': 'cltbld', 08:33:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:33:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:33:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:42 INFO - 'MOZ_NO_REMOTE': '1', 08:33:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:33:42 INFO - 'NO_EM_RESTART': '1', 08:33:42 INFO - 'PAGER': '/bin/cat', 08:33:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:33:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:42 INFO - 'PWD': '/builds/slave/test', 08:33:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:33:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:33:42 INFO - 'SHELL': '/bin/bash', 08:33:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NN6z6lgvHz/Listeners', 08:33:42 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:33:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:33:42 INFO - 'USER': 'cltbld', 08:33:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:33:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:33:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:42 INFO - 'XPC_FLAGS': '0x0', 08:33:42 INFO - 'XPC_SERVICE_NAME': '0', 08:33:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:33:42 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/CjrCC5fVQqqxJ7D5jme8dQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--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 08:33:43 INFO - mozversion application_buildid: 20161106062327 08:33:43 INFO - mozversion application_changeset: 673b5327afe1d489c41f683c79c6e8ad22040526 08:33:43 INFO - mozversion application_display_name: Nightly 08:33:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:33:43 INFO - mozversion application_name: Firefox 08:33:43 INFO - mozversion application_remotingname: firefox 08:33:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:33:43 INFO - mozversion application_vendor: Mozilla 08:33:43 INFO - mozversion application_version: 52.0a1 08:33:43 INFO - mozversion platform_buildid: 20161106062327 08:33:43 INFO - mozversion platform_changeset: 673b5327afe1d489c41f683c79c6e8ad22040526 08:33:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:33:43 INFO - mozversion platform_version: 52.0a1 08:33:43 INFO - using testdate: 1478450023 08:33:43 INFO - actual date: 1478450023 08:33:43 INFO - starting webserver on 'localhost:49280' 08:33:43 INFO - SUITE-START | Running 2 tests 08:33:43 INFO - TEST-START | dromaeo_css 08:33:43 INFO - operating with platform_type : mac_ 08:33:43 INFO - Initialising browser for dromaeo_css test... 08:33:43 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpcrxoXe/profile http://localhost:49280/getInfo.html) 08:33:45 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 08:33:45 INFO - PROCESS | 1663 | colorDepth:24 08:33:45 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 08:33:45 INFO - PROCESS | 1663 | __metrics 08:33:45 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:33:45 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:33:46 INFO - TEST-INFO | 1663: exit 0 08:33:46 INFO - Browser initialized. 08:33:46 INFO - Running cycle 1/1 for dromaeo_css test... 08:33:46 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpcrxoXe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:33:58 INFO - PROCESS | 1666 | RSS: Main: 239300608 08:33:58 INFO - PROCESS | 1666 | 08:34:50 INFO - PROCESS | 1666 | 1478450090022 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] 08:37:02 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 08:37:04 INFO - PROCESS | 1666 | RSS: Main: 396894208 08:37:04 INFO - PROCESS | 1666 | 08:40:07 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 08:40:07 INFO - PROCESS | 1666 | RSS: Main: 369586176 08:40:07 INFO - PROCESS | 1666 | 08:43:10 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 08:43:10 INFO - PROCESS | 1666 | RSS: Main: 366084096 08:43:10 INFO - PROCESS | 1666 | 08:46:13 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 08:46:13 INFO - PROCESS | 1666 | RSS: Main: 344563712 08:46:13 INFO - PROCESS | 1666 | 08:49:17 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 08:49:17 INFO - PROCESS | 1666 | RSS: Main: 336216064 08:49:17 INFO - PROCESS | 1666 | 08:52:21 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 08:52:21 INFO - PROCESS | 1666 | RSS: Main: 423862272 08:52:21 INFO - PROCESS | 1666 | 08:52:21 INFO - PROCESS | 1666 | __start_tp_report 08:52:21 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 08:52:21 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 08:52:21 INFO - PROCESS | 1666 | |i|pagename|runs| 08:52:21 INFO - PROCESS | 1666 | |0;dojo.html;2717.904873329433;2774.9445011099765;3301.0261972718044;3358.2611825398403;3361.462166053432;5447.509724124838;5584.832455026346;5612.961602103609;5667.773289068437;5695.971520142404;5563.471470210334;5571.8328450146455;5613.07384281594;5620.887582248352;5687.772489100431;5103.744812759366;5138.691678499284;5158.664686795365;5174.637775355726;5177.119889618773;5133.358330208724;5199.038177937075;5220.2691623172595;5224.3730752309775;5231.319928409302;5057.140286151363;5191.195364718475;5223.764930578114;5282.99623071617;5300.469953004688;5016.573591244749;5178.948210517884;5193.091209038422;5198.714070726109;5235.345581802275;5067.847964561069;5068.18908974566;5132.255822905695;5132.794688212467;5143.922841157386;5556.444355564452;5587.189857470685;5602.439756024365;5616.971915140439;5627.155926611;5878.323992740847;6062.181605483247;6068.33248342682;6106.694665266763;6128.816135515942;6101;6105.053716673922;6141.140240366352;6164.167837341946;6167.352427995085;5401.297831281987;5621.072523033695;5644.548436125141;5649.604527683105;5651.067573850269;4632.976835115803;5483;6048.546359023091;6123.418275263932;6181.103739957681;6044.909326360108;6061.666608336495;6062.211912451441;6080.847978800565;6089.421504957022;5602.243697100921;6173.197484327009;6184.876302474016;6200.193974783339;6221.097940798559;5463.180522921593;5716.771329146787;5990.73041713124;6005.309389420273;6086.026235802339;6033.637981721111;6082.939170608326;6084.60450070744;6106.297775755756;6114.205153330038;4810.326554282403;4840.15297322967;4863.8540843774745;4865.099956508057;4868.440129385095;4326.264535029052;4333.978330108329;4373.803178856959;4416.050549131952;4421.624161946206;5686.744096515584;5687.488126068673;5687.658740475585;5696.373398926197;5702.45826646468;6087.026075827935;6123.785667501706;6137.631742095488;6141.6007959482495;6147.047207682816;5607.859803504945;5636.943630563724;5664.20701101846;5671.602987790898;5697.487226149667;5300.31095957523;5320.255164277004;5325.627206095536;5367.087595108841;5367.785288588413;6237.220347456568;6248.718807653576;6248.812535623939;6249.78125765596;6255.0617407388345;6082.574219804748;6128.50971922254;6195.535334849995;6207.6275423474735;6214.564980451325;6127.556056270569;6147.754089836357;6164.845878853064;6193.318734939243;6234.906476402948;6122.142899994094;6131.908021379593;6135.84660383494;6139.2325959255095;6147.354527774519;6129.049997250433;6133.294671112879;6151.938480615316;6155.2921414037955;6183.81448556544;5077.339945807022;5835.299764028344;5856.385079566755;5906.232189815297;5947.732352044082;5960.821175364746;5966.313873904556;5971.044632858721;5974.970125149346;6017.217761690952;5641.294838145232;5799.130130480462;5837.153612726216;5847.26909136358;5856.677882716321;5852.824415267549;5923.85190370227;5940.227770389822;5948.8810223796145;5960.672163031075;5088.542031217208;5807.651540907559;5826.475617194473;5832.795852145155;5847.707614619337 08:52:21 INFO - PROCESS | 1666 | |1;ext.html;456.1788780195647;474.2672570877995;481.17478524330767;505.3481009497748;507.75120191106345;29904.551431728516;31362.84318578404;31431.05706828793;31472.213194670116;31517.68482315176;40361;41550.37674434886;41928.580714192845;43321.783391083;43594.7820260899;9231.446113233194;9406.153446189875;9410.482423466707;9431.575579098922;9440.24478041755;9341.53292335384;9395.248380129573;9398.953005234964;9401.153896149344;9414.340996130273;9387.10822471864;9437.245020398352;9442.716718498456;9448.338616296827;9452.10205030521;9313.16181543661;9374.578143983537;9375.296852736072;9394.248460123174;9398.577064032135;8364.163583641648;9023.691564723144;9341.953290233574;9351.859722104175;9385.953070234606;38553.421698674545;38763.22473550513;38814.41778373327;38859.805700971316;38891.416628750594;3252.96747032529;3594.3889538778208;3632.636736326346;3704.481372607837;3839.884803455901;29716.55425168624;30225.546616800766;30246.24384390386;30321.393572128654;30929.690703092907;3174.4286028514857;3176.039496646468;3181.363727254535;3195.360927814423;3308.5698859148397;309562;312196.87803121906;312695;312755;312948;32379.676203237665;54894;55253.579713622814;55278.17082743762;55346.44653553493;33225.833870830495;34610.307793844484;34714.30571388558;34824.65175348265;34914.82542587271;13666;13825.308734563432;13831.654208644864;13841.446342146402;13875.722485550232;11567.595134170264;11681.474333654836;11687.649370518899;11688.298702077902;11705.941470292593;7708;7729.381649468029;7815.140334563193;7817.296443320129;7819;3209.534617480499;3263.666478467907;3264.8041117533025;3267.5752152220534;3301.7688761786926;918.4432387103928;973.2068364283086;992.5087081894509;995.9452230127267;1017.7099526634873;28420;28586.571201431994;29039;29261;29528.96648617288;864.321507616518;961.6826447272383;1018.709667744704;1035;1096.7641956979287;14316.785248221286;14758.852411475747;14793.482228121964;15108.320125594375;15190;644640;652248;652970;653740;653975;53662.73168634132;53971.46028539743;54085.45914540883;54090.45909540933;54184.72907635516;1197.862245841722;1230.0467137968146;1301.5249433956537;1319.089828018664;1339.1028011232304;28689.42621147607;29572.408551829274;29596.704032959395;30247.546286806155;30458.086257412313;45619;46218;46427.76786116048;46430.767846160554;46499.30251046304;503924;506210;508334;508471.4576427094;512201;22278.554428911655;22775;24503.264902052968;25064.874675626506;25182.614956177586;42950.78524607357;43080.138397231254;43615.781921090194;44071.55928440803;44086.33870492009;29689.851550742107;36182.858856758074;36498.8175059123;37395.81302093472;37504.437433437975;34032.65967340394;35159.01148402312;35844.28311433809;36931.8153409242;37118.072048199014;35397.46903796497;35776.28447431089;36012.63987360093;36122.63877361193;36147;31272;32541.511877321387;32619.510707339883;32639.836800815843;32771.50842737409;24008.55948643087;24342.39144021343;24384.756152438957;24591.75408245966;24802.751972480764 08:52:21 INFO - PROCESS | 1666 | |2;jquery.html;344.139650872818;349.9002784206502;371.5819702834313;378.7689509399266;402.17152665509053;14599;14815.851841481583;15514.362558365063;19090.904545477253;20510;15716.135612541306;18707.71938420925;19572.11925463354;19815.900920495413;19850.205991760347;5573.526250268722;5921.822345329647;5925.614835035731;5926.555508336892;5927.970360148193;5564;5773.942260577403;5802.303723553179;5836.883262334751;5852.882942341151;5797.217375654275;5798;5798.478136967672;5800.738966746507;5803.82588522345;5492.697901615409;5646.520045796112;5708.571857110734;5715.085586306171;5724.398938111522;5015.774290156952;5016.347874776292;5017.172166592508;5020.071286811952;5025.572826309732;13040.347982600832;13285.867141328656;22964.425889352733;23604.527909441887;23992.880035599886;2137.668661357492;2139.5613899150635;2490.489449662833;2716.6739991200984;2827.618271533358;9510;9521.194268310977;9945.90054099457;10002.49987500622;10339.689809305733;1695.4828777222942;1827.7715285589302;1859.470051035462;1859.6094820087662;1885.1705249690092;108859;110470.34294485589;110940.33589496164;115430.6913861718;119693;10122.696319110291;10317.555542224094;10940.288881222696;11137.777244455065;11311.604093856675;11882.702932426759;12485.001199903985;14327.426902923766;14378.496752613817;14382.640433989234;7842.176571460029;7945.523268603903;8106.189381061847;8262.380321475914;8300.2114799094;7933.127355990837;7956.443048986631;7968.960155199187;8138.104808471064;8158.67365305393;4369.169857727022;4462.174497717932;4483.327500874895;4540.750258735801;4546.158960592301;1885.77370715515;1910.2397036282716;1910.8757930734457;1922.2311075569885;1958.3158483704003;890.528020149322;897.4435849773182;912.5847739278612;937.5827756648363;995.7859060301925;17733.290668373378;20113.899430502755;20633.38099857007;21551;21788.128474861143;897.533282693009;917.6650522559218;918.7749001494676;940.3041749105613;978.4373984958677;9803.754906127262;10783.083437907866;11242.93192146761;11254.662360129209;11286.871312868812;503345.96654033725;510816;512047;600682;610516;113911;116705;123408;129219.35390322989;141524;946.9763255918657;967.4485543240426;1009.8687170667913;1012.6303899076784;1015.669907280137;18841;18949.715754263972;19037;19057.71413428772;19104.426867194008;53865.53613566397;54133.72933135309;54924.705531534855;55308.72345638246;56897;81140.5942970305;81506.59246703729;82588.58705706433;82924;85084.57457712672;7795.883061753965;7896.09194942589;8048.396370272136;8050.718224862102;8180.9590952044855;11947.522099116286;12270.87729122733;12312.75374492523;12654.746905061664;12799.936000319938;10891.836622450512;10993.560257589606;11062.668119956414;11157.218994670295;11240.88759112431;10483.108935740547;10670.62652807148;10781.407022613832;10792.028717415471;10991.39547324873;5597.412271711411;7559.433042521723;11043.337399756041;11191.104711623213;11248.381339026431;6501.577397469149;7732.9226707732205;7976.202379761978;7984.403119376032;8112.9188708114525;10877.347359158475;10881.597005614472;10996.80348674271;11039.503222355173;11088.778224435304 08:52:21 INFO - PROCESS | 1666 | |3;mootools.html;1311.9212847229162;1457.271364317841;1478.5490425420248;1639.721247387944;1831.3864855273482;6509.511786616005;7932.722354717579;8162.714304999319;8491.78770530737;8519.91480085199;8362.205590468911;8441.831163376728;8443.071262161156;8453.239208471236;8475.83048339033;2141.850070495062;2343.496148328109;2344.5076533927895;2346.3547524430746;2347.1902193743217;2319.4433335999324;2368.692070030894;2378.3826997641595;2380.345405013618;2386.7493913139133;1974.82226599606;2301.9309420717404;2306.8039216666602;2338.0063473023984;2339.2397470821966;2270.182734215681;2297.069686776858;2308.734495533002;2310.5609934112463;2314.0859360552654;2306.1121468234683;2320.4198950262435;2330.5804955108074;2332.4402143485613;2335.7781010803947;8235.29999950005;8384.538850363228;8461.619227134794;8468.745937621881;8471.70349037787;5031.4465408805;5216.37403511581;5230.816921407771;5236.293100431431;5241.947580524184;3312.5031245313394;3395.6774106459848;3398.422268214385;3471.7743346682637;3478.7564870459078;1036.440322225997;1062.1449732964961;1724.1551639696584;1763.4180720362249;1832.8991905445196;10821.350718956888;11069.889301106967;11191.32852028881;11196.720081997933;11223.046041086518;3797.9620203798163;4365.454318210223;4501.662375321864;4520.547945205453;4539.795709193094;8835.911640883638;8845.336599755045;8848.823023539493;8856.911430885608;8860.8670869937;5130.33305670268;5137;5143.305653736773;5143.588512918957;5144.408393034849;3803.088157284775;4677.859664210079;4812.855614331575;4820.493848145894;4829.975850120727;3588.336157810761;3631.8728844490315;3684.7604905681046;3731.7760934343482;3746.325661380923;1136.431784107946;1139.8632164140356;1149.8217776244696;1153.907687385024;1157.4907040901976;828.9544075075929;837.7277384849948;840.3991146330403;912.717057712098;919.2829592917535;5329.653572517774;5475.342958844964;5736.368999410061;5799.304083509922;5806.506446952057;351.3955995687414;392.4682055814387;422.89850435895164;424.2871975081892;424.4609346130397;3464.9951514061145;3767.8869633911027;3844.8462061517785;3904;3905.1799122184093;8710.606302991491;9828.803423931482;10066.848997265048;10163.847542286872;10373.118284945864;9315.627374904929;9557.092076252746;10325.690229293134;10338.53476593555;10347.275690701728;7443.702251909863;7691;7742.10965738936;7752.3798096153305;7756.573388463576;5385.73071346439;5448.318960129984;5695.231143795535;5697.373288938131;5714.514266287412;9435.57539910692;9444.33889627747;9607.375520591138;9608.991055939305;9621.470819105016;9542.618295268388;9584.28117891147;9742;9831.803363932539;9918.256130790076;5976.820695378971;6572.178477690289;6776.93223067763;6779.966100169468;7395.408367330493;7830.608469576612;8021.799455013671;8132.43072984909;8172.8365432689825;8184.140665230303;6968.198657154492;7072;7127.358537731422;7155.141383034069;7283.8179045522165;6785.287544807724;6898.551594146365;6993;7007.614581198083;7032.753853615099;6655.434288085373;6718.630475323904;6795.93204067953;6822.761203357859;6824.146981627297;4707.4821769606;4758.928616070831;4774.785134669018;4781.784819683053;4900.436449808175;4152.854650087233;4664.440267167962;4797.232442809134;4956.752162391938;5054.16606259963 08:52:21 INFO - PROCESS | 1666 | |4;prototype.html;296.2696952013288;298.7714398485158;304.392736490701;310.25229197633695;315.6835993905414;4156.729812562181;4388.97805510972;4446.288593824985;5116.925445656417;5145.536901678848;4584.243599806028;4930.6055515558755;4959.619382696308;5073.315102461167;5223.86940326492;3935.9016024599327;3962.3065963456424;3989.6435212027905;3991.2217117662067;3999.9800150886185;3507.929841403183;3761.4357846323132;4005.3991901214904;4016.0160760613544;4017.1965606878593;3297.686719761619;3932.2304962766725;3959.742616729903;3968.1071758854173;3987.9202415951663;3424.9143771405666;3938.9015274618077;3950.2692001979726;3951.308521008812;3968.9404658929993;2991.670916199216;3245.2535916739284;3275.5741753571883;3366.831658417094;3446.0867870014467;4399.296112621997;5077.517635824591;5095.923561146586;5124.871878203037;5134.486551344873;4523.02467285986;4541.751832842764;4563.931541026887;4570.486232572105;4570.954290457119;4107.104491333603;5069.949300506984;5432.392451623243;5460.645058071213;5516.365617953907;3142.4186525492855;3379.026456718097;3603.4234522476277;3750.2499500099816;3770.472133901256;4702.365180700596;5144.639875208737;5806.303243610751;5812.244408226946;5833.416658334133;3385.2890892912765;3589.3898037333715;3818.3127037133027;3850.6534411903062;3866;3170.1599076244815;3820.465134881119;3864.8067596620617;3890.513685789276;3895.3572660511263;3853.7109716771356;4384.956150438455;4491;4495.640348772155;4520;4573.794179261941;5252.579793616578;5436.429174936575;5451.454854514596;5451.48210919962;1070.9357438553711;1187.216437151476;1189.8869607387285;1203.711109333771;1245.439552201513;689.36233983565;768.7194174126515;769.6338998277989;783.7460063897831;860.0754189246585;4195.307774217281;4433.290673492225;4784.665073444826;4825.758712064453;4883.584895283941;5092.668976516588;5200.583953283728;5298.284731561267;5300.681959082391;5310.867228319323;3853.9421908671393;4129.628333450004;4344.218040752632;4866.4403593586485;4892.535209155125;2260.344500094954;2452.779249867485;2782.749552540281;2794.2595212268766;2813.7467627913584;4999.425066117443;5004.2493625956395;5509.641873278224;5519.586031047607;5525.668459892339;4781.569658730731;5225.5819534437915;5277.762500687479;5281.728856591702;5327.440618735054;5828.358880523139;5909.4044607956475;5931.851703707442;5954.196183515343;5971.552133589998;4774.689645173053;4960.454350021422;5722.570807189394;5739.2252045973255;5841.795537156179;4899.926501102557;5265.947340526545;5559.722013899208;5588.972055139699;5620.915686264628;4704.976475117603;5822.583627794418;5836.678982655994;5852.502537284378;5866.6480011199465;3454.9827250863586;3639.526861508093;3768.1333293342136;3778.7543809652284;3816.2176753765966;4712.434507859079;5404.5406140476925;5540.058190107772;5615.479538971167;5646.604737668488;5183.922241166461;5382.461753824587;5387.218853266333;5395.595330350161;5524.4199359068325;5434.673919564839;5449.3188351456065;5457.863553411196;5461;5471;4219.894502637459;5284.392294886137;5400.756965936621;5404.351477822686;5430.429804870431;4842.539958703917;5472.823342981278;5483.204935284281;5517.420670829666;5608.85977850557;4552.406657669789;4915.721912302755;5334.546563542142;5464.726763661881;5498.780048798003 08:52:21 INFO - PROCESS | 1666 | |5;yui.html;685.9108315918932;689.5035574386442;716;737.8966944627756;774.907011158661;14051.929740351297;14504.202268875208;14529.927350363247;14540.709185816277;14566.417343306255;10430.478476076203;12263.834935681118;12790.104692671493;12958.287294198815;13084.869151308483;531.3464438740342;675.554134271381;677.474956908395;680.3944489404435;681.4582406986458;670.8088194864465;814.6374863185885;835.4152093534519;835.7367429259764;841.5329492131864;720.9134903811524;875.264777586827;892.5046599137509;894.0344428017758;899.9685011024616;883.9701747464196;887.7913690282778;889.2397000564489;889.4663202078766;891.9420237684529;514.5600511562598;527.2039220776617;529.3462573721451;656.3829999800221;658.6476235214137;10642.946785266102;11577.710557236052;11598.420078996016;13301.002424818178;13302.93348533251;13197.27414992175;13444.663883402894;13645.249511276876;13711.177329360271;13712.862871371259;11026.393548354838;11111.777764444665;11141.442927853655;11302.208845380826;11455.59905403307;3474.9826250868837;3491.6682915123024;3543.85824567017;3589.3898037333715;3617.674409303149;80950;82515.58742206251;84994.57502712446;85242.57378713066;87106.56446717726;10429.530671119817;10432.791344173225;10574.577016919391;10580.259381843198;10910.836337454944;18719.90640046791;18871.7733347332;20468.79531204669;20755.481112972295;20773.688394674093;7422.96288518554;7428.85142297151;7513.323800857949;7586.544807311579;7673.386129109658;4095.0690609104386;4169.8957526062095;4191.119864828359;4239.894002649897;4262.147570485915;3299.356625458013;3424.70889974355;3437.810920399352;3441.5525981622227;3446.052335607678;617.0497433951664;621.6580880515788;631.8704665543553;631.8767840271195;640.6700549217157;445.9130469558405;499.9850004499798;568.8679527739768;590.1973316289842;641.3137942401679;7914.327282181079;8092.2486354639705;8304.667813287522;8561.14388561139;8608.328545377257;558.1906235957878;566.1988286574533;580.9099589563624;592.9238432245461;599.2958274028;3803.7147213959065;4019.9397009044887;4036.3138266494766;4097.7541347519245;4134.607212314825;88044;108282;112829.43585282021;113866.43066784613;114032;65705.67147164329;66059.33940660532;79564.40871182544;82001.58999205085;93079;55253;81419;81422.59288703519;82178;84195.5790221045;8576.914230857861;8689.391742578211;8821.823563528566;9042.683506077256;9081.395696502565;48241.75879120582;48782.5121748778;55366.72316638391;56091;58034;62018;67878.32121678918;71187.37282076648;74011;75619;9901.702948911545;11079.390633514911;11559.364234967157;11680.948714615726;11880.643580692593;12738.617841464771;13299.601011969657;13318.067735258439;13390.330483475593;13568.525101621395;12176.147669663038;12249.755004899675;12332.445039973043;12819.935900320439;12933.805992910302;11865.584704535646;12179.817302740643;12204.04735242357;12613.621591352274;13180.143290686075;12073.698157546132;12074.939625301817;12215.572454964034;12264.632061038183;12404.875951240732;10099.394036357842;10141.746456338651;10267.7433064174;10448.373097614167;10583.735406614896;9171.862422063808;10677.89322106769;10697.946510267398;10707;10855.891441085489 08:52:21 INFO - PROCESS | 1666 | __end_tp_report 08:52:21 INFO - PROCESS | 1666 | __start_cc_report 08:52:21 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1721 08:52:21 INFO - PROCESS | 1666 | __end_cc_report 08:52:21 INFO - PROCESS | 1666 | __startTimestamp1478451141415__endTimestamp 08:52:21 INFO - PROCESS | 1666 | 08:52:21 INFO - PROCESS | 1666 | ------- Summary: start ------- 08:52:21 INFO - PROCESS | 1666 | Number of tests: 6 08:52:21 INFO - PROCESS | 1666 | 08:52:21 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5615.67 Median:5716.77 stddev:643.94 (11.3%) stddev-sans-first:604.46 08:52:21 INFO - PROCESS | 1666 | Values: 2717.9 2774.9 3301.0 3358.3 3361.5 5447.5 5584.8 5613.0 5667.8 5696.0 5563.5 5571.8 5613.1 5620.9 5687.8 5103.7 5138.7 5158.7 5174.6 5177.1 5133.4 5199.0 5220.3 5224.4 5231.3 5057.1 5191.2 5223.8 5283.0 5300.5 5016.6 5178.9 5193.1 5198.7 5235.3 5067.8 5068.2 5132.3 5132.8 5143.9 5556.4 5587.2 5602.4 5617.0 5627.2 5878.3 6062.2 6068.3 6106.7 6128.8 6101.0 6105.1 6141.1 6164.2 6167.4 5401.3 5621.1 5644.5 5649.6 5651.1 4633.0 5483.0 6048.5 6123.4 6181.1 6044.9 6061.7 6062.2 6080.8 6089.4 5602.2 6173.2 6184.9 6200.2 6221.1 5463.2 5716.8 5990.7 6005.3 6086.0 6033.6 6082.9 6084.6 6106.3 6114.2 4810.3 4840.2 4863.9 4865.1 4868.4 4326.3 4334.0 4373.8 4416.1 4421.6 5686.7 5687.5 5687.7 5696.4 5702.5 6087.0 6123.8 6137.6 6141.6 6147.0 5607.9 5636.9 5664.2 5671.6 5697.5 5300.3 5320.3 5325.6 5367.1 5367.8 6237.2 6248.7 6248.8 6249.8 6255.1 6082.6 6128.5 6195.5 6207.6 6214.6 6127.6 6147.8 6164.8 6193.3 6234.9 6122.1 6131.9 6135.8 6139.2 6147.4 6129.0 6133.3 6151.9 6155.3 6183.8 5077.3 5835.3 5856.4 5906.2 5947.7 5960.8 5966.3 5971.0 5975.0 6017.2 5641.3 5799.1 5837.2 5847.3 5856.7 5852.8 5923.9 5940.2 5948.9 5960.7 5088.5 5807.7 5826.5 5832.8 5847.7 08:52:21 INFO - PROCESS | 1666 | 08:52:21 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:61049.49 Median:36164.93 stddev:136842.84 (378.4%) stddev-sans-first:137151.10 08:52:21 INFO - PROCESS | 1666 | Values: 456.2 474.3 481.2 505.3 507.8 29904.6 31362.8 31431.1 31472.2 31517.7 40361.0 41550.4 41928.6 43321.8 43594.8 9231.4 9406.2 9410.5 9431.6 9440.2 9341.5 9395.2 9399.0 9401.2 9414.3 9387.1 9437.2 9442.7 9448.3 9452.1 9313.2 9374.6 9375.3 9394.2 9398.6 8364.2 9023.7 9342.0 9351.9 9386.0 38553.4 38763.2 38814.4 38859.8 38891.4 3253.0 3594.4 3632.6 3704.5 3839.9 29716.6 30225.5 30246.2 30321.4 30929.7 3174.4 3176.0 3181.4 3195.4 3308.6 309562.0 312196.9 312695.0 312755.0 312948.0 32379.7 54894.0 55253.6 55278.2 55346.4 33225.8 34610.3 34714.3 34824.7 34914.8 13666.0 13825.3 13831.7 13841.4 13875.7 11567.6 11681.5 11687.6 11688.3 11705.9 7708.0 7729.4 7815.1 7817.3 7819.0 3209.5 3263.7 3264.8 3267.6 3301.8 918.4 973.2 992.5 995.9 1017.7 28420.0 28586.6 29039.0 29261.0 29529.0 864.3 961.7 1018.7 1035.0 1096.8 14316.8 14758.9 14793.5 15108.3 15190.0 644640.0 652248.0 652970.0 653740.0 653975.0 53662.7 53971.5 54085.5 54090.5 54184.7 1197.9 1230.0 1301.5 1319.1 1339.1 28689.4 29572.4 29596.7 30247.5 30458.1 45619.0 46218.0 46427.8 46430.8 46499.3 503924.0 506210.0 508334.0 508471.5 512201.0 22278.6 22775.0 24503.3 25064.9 25182.6 42950.8 43080.1 43615.8 44071.6 44086.3 29689.9 36182.9 36498.8 37395.8 37504.4 34032.7 35159.0 35844.3 36931.8 37118.1 35397.5 35776.3 36012.6 36122.6 36147.0 31272.0 32541.5 32619.5 32639.8 32771.5 24008.6 24342.4 24384.8 24591.8 24802.8 08:52:21 INFO - PROCESS | 1666 | 08:52:21 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:33079.30 Median:2772.15 stddev:92207.52 (3326.2%) stddev-sans-first:92433.43 08:52:21 INFO - PROCESS | 1666 | Values: 344.1 349.9 371.6 378.8 402.2 14599.0 14815.9 15514.4 19090.9 20510.0 15716.1 18707.7 19572.1 19815.9 19850.2 5573.5 5921.8 5925.6 5926.6 5928.0 5564.0 5773.9 5802.3 5836.9 5852.9 5797.2 5798.0 5798.5 5800.7 5803.8 5492.7 5646.5 5708.6 5715.1 5724.4 5015.8 5016.3 5017.2 5020.1 5025.6 13040.3 13285.9 22964.4 23604.5 23992.9 2137.7 2139.6 2490.5 2716.7 2827.6 9510.0 9521.2 9945.9 10002.5 10339.7 1695.5 1827.8 1859.5 1859.6 1885.2 108859.0 110470.3 110940.3 115430.7 119693.0 10122.7 10317.6 10940.3 11137.8 11311.6 11882.7 12485.0 14327.4 14378.5 14382.6 7842.2 7945.5 8106.2 8262.4 8300.2 7933.1 7956.4 7969.0 8138.1 8158.7 4369.2 4462.2 4483.3 4540.8 4546.2 1885.8 1910.2 1910.9 1922.2 1958.3 890.5 897.4 912.6 937.6 995.8 17733.3 20113.9 20633.4 21551.0 21788.1 897.5 917.7 918.8 940.3 978.4 9803.8 10783.1 11242.9 11254.7 11286.9 503346.0 510816.0 512047.0 600682.0 610516.0 113911.0 116705.0 123408.0 129219.4 141524.0 947.0 967.4 1009.9 1012.6 1015.7 18841.0 18949.7 19037.0 19057.7 19104.4 53865.5 54133.7 54924.7 55308.7 56897.0 81140.6 81506.6 82588.6 82924.0 85084.6 7795.9 7896.1 8048.4 8050.7 8181.0 11947.5 12270.9 12312.8 12654.7 12799.9 10891.8 10993.6 11062.7 11157.2 11240.9 10483.1 10670.6 10781.4 10792.0 10991.4 5597.4 7559.4 11043.3 11191.1 11248.4 6501.6 7732.9 7976.2 7984.4 8112.9 10877.3 10881.6 10996.8 11039.5 11088.8 08:52:21 INFO - PROCESS | 1666 | 08:52:21 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5414.23 Median:5042.81 stddev:3001.74 (59.5%) stddev-sans-first:2994.33 08:52:21 INFO - PROCESS | 1666 | Values: 1311.9 1457.3 1478.5 1639.7 1831.4 6509.5 7932.7 8162.7 8491.8 8519.9 8362.2 8441.8 8443.1 8453.2 8475.8 2141.9 2343.5 2344.5 2346.4 2347.2 2319.4 2368.7 2378.4 2380.3 2386.7 1974.8 2301.9 2306.8 2338.0 2339.2 2270.2 2297.1 2308.7 2310.6 2314.1 2306.1 2320.4 2330.6 2332.4 2335.8 8235.3 8384.5 8461.6 8468.7 8471.7 5031.4 5216.4 5230.8 5236.3 5241.9 3312.5 3395.7 3398.4 3471.8 3478.8 1036.4 1062.1 1724.2 1763.4 1832.9 10821.4 11069.9 11191.3 11196.7 11223.0 3798.0 4365.5 4501.7 4520.5 4539.8 8835.9 8845.3 8848.8 8856.9 8860.9 5130.3 5137.0 5143.3 5143.6 5144.4 3803.1 4677.9 4812.9 4820.5 4830.0 3588.3 3631.9 3684.8 3731.8 3746.3 1136.4 1139.9 1149.8 1153.9 1157.5 829.0 837.7 840.4 912.7 919.3 5329.7 5475.3 5736.4 5799.3 5806.5 351.4 392.5 422.9 424.3 424.5 3465.0 3767.9 3844.8 3904.0 3905.2 8710.6 9828.8 10066.8 10163.8 10373.1 9315.6 9557.1 10325.7 10338.5 10347.3 7443.7 7691.0 7742.1 7752.4 7756.6 5385.7 5448.3 5695.2 5697.4 5714.5 9435.6 9444.3 9607.4 9609.0 9621.5 9542.6 9584.3 9742.0 9831.8 9918.3 5976.8 6572.2 6776.9 6780.0 7395.4 7830.6 8021.8 8132.4 8172.8 8184.1 6968.2 7072.0 7127.4 7155.1 7283.8 6785.3 6898.6 6993.0 7007.6 7032.8 6655.4 6718.6 6795.9 6822.8 6824.1 4707.5 4758.9 4774.8 4781.8 4900.4 4152.9 4664.4 4797.2 4956.8 5054.2 08:52:21 INFO - PROCESS | 1666 | 08:52:21 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4349.92 Median:4834.15 stddev:1366.77 (28.3%) stddev-sans-first:1336.31 08:52:21 INFO - PROCESS | 1666 | Values: 296.3 298.8 304.4 310.3 315.7 4156.7 4389.0 4446.3 5116.9 5145.5 4584.2 4930.6 4959.6 5073.3 5223.9 3935.9 3962.3 3989.6 3991.2 4000.0 3507.9 3761.4 4005.4 4016.0 4017.2 3297.7 3932.2 3959.7 3968.1 3987.9 3424.9 3938.9 3950.3 3951.3 3968.9 2991.7 3245.3 3275.6 3366.8 3446.1 4399.3 5077.5 5095.9 5124.9 5134.5 4523.0 4541.8 4563.9 4570.5 4571.0 4107.1 5069.9 5432.4 5460.6 5516.4 3142.4 3379.0 3603.4 3750.2 3770.5 4702.4 5144.6 5806.3 5812.2 5833.4 3385.3 3589.4 3818.3 3850.7 3866.0 3170.2 3820.5 3864.8 3890.5 3895.4 3853.7 4385.0 4491.0 4495.6 4520.0 4573.8 5252.6 5436.4 5451.5 5451.5 1070.9 1187.2 1189.9 1203.7 1245.4 689.4 768.7 769.6 783.7 860.1 4195.3 4433.3 4784.7 4825.8 4883.6 5092.7 5200.6 5298.3 5300.7 5310.9 3853.9 4129.6 4344.2 4866.4 4892.5 2260.3 2452.8 2782.7 2794.3 2813.7 4999.4 5004.2 5509.6 5519.6 5525.7 4781.6 5225.6 5277.8 5281.7 5327.4 5828.4 5909.4 5931.9 5954.2 5971.6 4774.7 4960.5 5722.6 5739.2 5841.8 4899.9 5265.9 5559.7 5589.0 5620.9 4705.0 5822.6 5836.7 5852.5 5866.6 3455.0 3639.5 3768.1 3778.8 3816.2 4712.4 5404.5 5540.1 5615.5 5646.6 5183.9 5382.5 5387.2 5395.6 5524.4 5434.7 5449.3 5457.9 5461.0 5471.0 4219.9 5284.4 5400.8 5404.4 5430.4 4842.5 5472.8 5483.2 5517.4 5608.9 4552.4 4915.7 5334.5 5464.7 5498.8 08:52:21 INFO - PROCESS | 1666 | 08:52:21 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:19213.45 Median:4251.02 stddev:27924.06 (656.9%) stddev-sans-first:27967.74 08:52:21 INFO - PROCESS | 1666 | Values: 685.9 689.5 716.0 737.9 774.9 14051.9 14504.2 14529.9 14540.7 14566.4 10430.5 12263.8 12790.1 12958.3 13084.9 531.3 675.6 677.5 680.4 681.5 670.8 814.6 835.4 835.7 841.5 720.9 875.3 892.5 894.0 900.0 884.0 887.8 889.2 889.5 891.9 514.6 527.2 529.3 656.4 658.6 10642.9 11577.7 11598.4 13301.0 13302.9 13197.3 13444.7 13645.2 13711.2 13712.9 11026.4 11111.8 11141.4 11302.2 11455.6 3475.0 3491.7 3543.9 3589.4 3617.7 80950.0 82515.6 84994.6 85242.6 87106.6 10429.5 10432.8 10574.6 10580.3 10910.8 18719.9 18871.8 20468.8 20755.5 20773.7 7423.0 7428.9 7513.3 7586.5 7673.4 4095.1 4169.9 4191.1 4239.9 4262.1 3299.4 3424.7 3437.8 3441.6 3446.1 617.0 621.7 631.9 631.9 640.7 445.9 500.0 568.9 590.2 641.3 7914.3 8092.2 8304.7 8561.1 8608.3 558.2 566.2 580.9 592.9 599.3 3803.7 4019.9 4036.3 4097.8 4134.6 88044.0 108282.0 112829.4 113866.4 114032.0 65705.7 66059.3 79564.4 82001.6 93079.0 55253.0 81419.0 81422.6 82178.0 84195.6 8576.9 8689.4 8821.8 9042.7 9081.4 48241.8 48782.5 55366.7 56091.0 58034.0 62018.0 67878.3 71187.4 74011.0 75619.0 9901.7 11079.4 11559.4 11680.9 11880.6 12738.6 13299.6 13318.1 13390.3 13568.5 12176.1 12249.8 12332.4 12819.9 12933.8 11865.6 12179.8 12204.0 12613.6 13180.1 12073.7 12074.9 12215.6 12264.6 12404.9 10099.4 10141.7 10267.7 10448.4 10583.7 9171.9 10677.9 10697.9 10707.0 10855.9 08:52:21 INFO - PROCESS | 1666 | -------- Summary: end -------- 08:52:21 INFO - PROCESS | 1666 | 08:52:22 INFO - TEST-INFO | 1666: exit 0 08:52:22 INFO - TEST-OK | dromaeo_css | took 1118961ms 08:52:22 INFO - TEST-START | kraken 08:52:22 INFO - operating with platform_type : mac_ 08:52:22 INFO - Initialising browser for kraken test... 08:52:22 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmp0ZbC5L/profile http://localhost:49280/getInfo.html) 08:52:24 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 08:52:24 INFO - PROCESS | 1672 | colorDepth:24 08:52:24 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 08:52:24 INFO - PROCESS | 1672 | __metrics 08:52:24 INFO - PROCESS | 1672 | 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:52:24 INFO - PROCESS | 1672 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:52:25 INFO - TEST-INFO | 1672: exit 0 08:52:25 INFO - Browser initialized. 08:52:25 INFO - Running cycle 1/1 for kraken test... 08:52:25 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmp0ZbC5L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:52:37 INFO - PROCESS | 1673 | RSS: Main: 239276032 08:52:37 INFO - PROCESS | 1673 | 08:52:39 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 08:52:40 INFO - PROCESS | 1673 | RSS: Main: 290308096 08:52:40 INFO - PROCESS | 1673 | 08:52:43 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 08:52:44 INFO - PROCESS | 1673 | RSS: Main: 290320384 08:52:44 INFO - PROCESS | 1673 | 08:52:47 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 08:52:48 INFO - PROCESS | 1673 | RSS: Main: 380452864 08:52:48 INFO - PROCESS | 1673 | 08:52:50 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 08:52:51 INFO - PROCESS | 1673 | RSS: Main: 283922432 08:52:51 INFO - PROCESS | 1673 | 08:52:53 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:52:53 INFO - PROCESS | 1673 | RSS: Main: 288346112 08:52:53 INFO - PROCESS | 1673 | 08:52:56 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 08:52:57 INFO - PROCESS | 1673 | RSS: Main: 325419008 08:52:57 INFO - PROCESS | 1673 | 08:52:59 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 08:53:00 INFO - PROCESS | 1673 | RSS: Main: 325767168 08:53:00 INFO - PROCESS | 1673 | 08:53:03 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 08:53:03 INFO - PROCESS | 1673 | RSS: Main: 325443584 08:53:03 INFO - PROCESS | 1673 | 08:53:05 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:53:06 INFO - PROCESS | 1673 | RSS: Main: 301498368 08:53:06 INFO - PROCESS | 1673 | 08:53:08 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:53:08 INFO - PROCESS | 1673 | RSS: Main: 304971776 08:53:08 INFO - PROCESS | 1673 | 08:53:10 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:53:11 INFO - PROCESS | 1673 | RSS: Main: 312037376 08:53:11 INFO - PROCESS | 1673 | 08:53:14 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:53:15 INFO - PROCESS | 1673 | RSS: Main: 317538304 08:53:15 INFO - PROCESS | 1673 | 08:53:18 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:53:19 INFO - PROCESS | 1673 | RSS: Main: 317661184 08:53:19 INFO - PROCESS | 1673 | 08:53:20 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 08:53:21 INFO - PROCESS | 1673 | RSS: Main: 360845312 08:53:21 INFO - PROCESS | 1673 | 08:53:21 INFO - PROCESS | 1673 | __start_tp_report 08:53:21 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 08:53:21 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 08:53:21 INFO - PROCESS | 1673 | |i|pagename|runs| 08:53:21 INFO - PROCESS | 1673 | |0;ai-astar;113;115;114;113;113;115;114;114;114;113 08:53:21 INFO - PROCESS | 1673 | |1;audio-beat-detection;152;115;114;145;141;113;111;112;152;141 08:53:21 INFO - PROCESS | 1673 | |2;audio-dft;185;186;194;264;222;184;180;183;185;181 08:53:21 INFO - PROCESS | 1673 | |3;audio-fft;79;62;58;86;61;60;58;59;83;58 08:53:21 INFO - PROCESS | 1673 | |4;audio-oscillator;89;71;91;70;69;69;90;67;68;92 08:53:21 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;88;86;87;86;87;85;89;85;88;84 08:53:21 INFO - PROCESS | 1673 | |6;imaging-darkroom;97;102;95;95;95;96;96;96;96;104 08:53:21 INFO - PROCESS | 1673 | |7;imaging-desaturate;82;82;82;81;83;81;82;85;80;80 08:53:21 INFO - PROCESS | 1673 | |8;json-parse-financial;56;53;54;53;53;54;56;53;53;54 08:53:21 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;74;71;81;74;75;71;72;73;77;107 08:53:21 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;92;90;87;85;90;84;87;87;88;86 08:53:21 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;150;149;145;154;157;151;168;159;218;155 08:53:21 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;176;177;169;240;160;217;235;226;176;175 08:53:21 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;68;70;64;67;68;68;67;67;69;74 08:53:21 INFO - PROCESS | 1673 | __end_tp_report 08:53:21 INFO - PROCESS | 1673 | __start_cc_report 08:53:21 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1958 08:53:21 INFO - PROCESS | 1673 | __end_cc_report 08:53:21 INFO - PROCESS | 1673 | __startTimestamp1478451201124__endTimestamp 08:53:21 INFO - PROCESS | 1673 | 08:53:21 INFO - PROCESS | 1673 | ------- Summary: start ------- 08:53:21 INFO - PROCESS | 1673 | Number of tests: 14 08:53:21 INFO - PROCESS | 1673 | 08:53:21 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.00 stddev:0.79 (0.7%) stddev-sans-first:0.78 08:53:21 INFO - PROCESS | 1673 | Values: 113.0 115.0 114.0 113.0 113.0 115.0 114.0 114.0 114.0 113.0 08:53:21 INFO - PROCESS | 1673 | 08:53:21 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:141.00 stddev:17.91 (12.7%) stddev-sans-first:17.07 08:53:21 INFO - PROCESS | 1673 | Values: 152.0 115.0 114.0 145.0 141.0 113.0 111.0 112.0 152.0 141.0 08:53:21 INFO - PROCESS | 1673 | 08:53:21 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:196.40 Median:185.50 stddev:26.75 (14.4%) stddev-sans-first:28.05 08:53:21 INFO - PROCESS | 1673 | Values: 185.0 186.0 194.0 264.0 222.0 184.0 180.0 183.0 185.0 181.0 08:53:21 INFO - PROCESS | 1673 | 08:53:21 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:66.40 Median:61.50 stddev:11.42 (18.6%) stddev-sans-first:11.17 08:53:21 INFO - PROCESS | 1673 | Values: 79.0 62.0 58.0 86.0 61.0 60.0 58.0 59.0 83.0 58.0 08:53:21 INFO - PROCESS | 1673 | 08:53:21 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:80.00 stddev:11.18 (14.0%) stddev-sans-first:11.07 08:53:21 INFO - PROCESS | 1673 | Values: 89.0 71.0 91.0 70.0 69.0 69.0 90.0 67.0 68.0 92.0 08:53:21 INFO - PROCESS | 1673 | 08:53:21 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:87.00 stddev:1.58 (1.8%) stddev-sans-first:1.58 08:53:21 INFO - PROCESS | 1673 | Values: 88.0 86.0 87.0 86.0 87.0 85.0 89.0 85.0 88.0 84.0 08:53:21 INFO - PROCESS | 1673 | 08:53:21 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:97.20 Median:96.00 stddev:3.16 (3.3%) stddev-sans-first:3.35 08:53:21 INFO - PROCESS | 1673 | Values: 97.0 102.0 95.0 95.0 95.0 96.0 96.0 96.0 96.0 104.0 08:53:21 INFO - PROCESS | 1673 | 08:53:21 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:81.80 Median:82.00 stddev:1.48 (1.8%) stddev-sans-first:1.56 08:53:21 INFO - PROCESS | 1673 | Values: 82.0 82.0 82.0 81.0 83.0 81.0 82.0 85.0 80.0 80.0 08:53:21 INFO - PROCESS | 1673 | 08:53:21 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:1.20 (2.2%) stddev-sans-first:1.00 08:53:21 INFO - PROCESS | 1673 | Values: 56.0 53.0 54.0 53.0 53.0 54.0 56.0 53.0 53.0 54.0 08:53:21 INFO - PROCESS | 1673 | 08:53:21 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:77.50 Median:74.00 stddev:10.79 (14.6%) stddev-sans-first:11.37 08:53:21 INFO - PROCESS | 1673 | Values: 74.0 71.0 81.0 74.0 75.0 71.0 72.0 73.0 77.0 107.0 08:53:21 INFO - PROCESS | 1673 | 08:53:21 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:87.50 stddev:2.46 (2.8%) stddev-sans-first:2.03 08:53:21 INFO - PROCESS | 1673 | Values: 92.0 90.0 87.0 85.0 90.0 84.0 87.0 87.0 88.0 86.0 08:53:21 INFO - PROCESS | 1673 | 08:53:21 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:160.60 Median:156.00 stddev:21.14 (13.6%) stddev-sans-first:22.07 08:53:21 INFO - PROCESS | 1673 | Values: 150.0 149.0 145.0 154.0 157.0 151.0 168.0 159.0 218.0 155.0 08:53:21 INFO - PROCESS | 1673 | 08:53:21 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.10 Median:197.00 stddev:30.58 (15.5%) stddev-sans-first:31.65 08:53:21 INFO - PROCESS | 1673 | Values: 176.0 177.0 169.0 240.0 160.0 217.0 235.0 226.0 176.0 175.0 08:53:21 INFO - PROCESS | 1673 | 08:53:21 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.00 stddev:2.57 (3.8%) stddev-sans-first:2.73 08:53:21 INFO - PROCESS | 1673 | Values: 68.0 70.0 64.0 67.0 68.0 68.0 67.0 67.0 69.0 74.0 08:53:21 INFO - PROCESS | 1673 | -------- Summary: end -------- 08:53:21 INFO - PROCESS | 1673 | 08:53:22 INFO - TEST-INFO | 1673: exit 0 08:53:22 INFO - TEST-OK | kraken | took 59974ms 08:53:22 INFO - SUITE-END | took 1178s 08:53:22 INFO - Completed test suite (00:19:39) 08:53:22 INFO - javascript benchmark 08:53:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2717.904873329433, 2774.9445011099765, 3301.0261972718044, 3358.2611825398403, 3361.462166053432, 5447.509724124838, 5584.832455026346, 5612.961602103609, 5667.773289068437, 5695.971520142404, 5563.471470210334, 5571.8328450146455, 5613.07384281594, 5620.887582248352, 5687.772489100431, 5103.744812759366, 5138.691678499284, 5158.664686795365, 5174.637775355726, 5177.119889618773, 5133.358330208724, 5199.038177937075, 5220.2691623172595, 5224.3730752309775, 5231.319928409302, 5057.140286151363, 5191.195364718475, 5223.764930578114, 5282.99623071617, 5300.469953004688, 5016.573591244749, 5178.948210517884, 5193.091209038422, 5198.714070726109, 5235.345581802275, 5067.847964561069, 5068.18908974566, 5132.255822905695, 5132.794688212467, 5143.922841157386, 5556.444355564452, 5587.189857470685, 5602.439756024365, 5616.971915140439, 5627.155926611, 5878.323992740847, 6062.181605483247, 6068.33248342682, 6106.694665266763, 6128.816135515942, 6101.0, 6105.053716673922, 6141.140240366352, 6164.167837341946, 6167.352427995085, 5401.297831281987, 5621.072523033695, 5644.548436125141, 5649.604527683105, 5651.067573850269, 4632.976835115803, 5483.0, 6048.546359023091, 6123.418275263932, 6181.103739957681, 6044.909326360108, 6061.666608336495, 6062.211912451441, 6080.847978800565, 6089.421504957022, 5602.243697100921, 6173.197484327009, 6184.876302474016, 6200.193974783339, 6221.097940798559, 5463.180522921593, 5716.771329146787, 5990.73041713124, 6005.309389420273, 6086.026235802339, 6033.637981721111, 6082.939170608326, 6084.60450070744, 6106.297775755756, 6114.205153330038, 4810.326554282403, 4840.15297322967, 4863.8540843774745, 4865.099956508057, 4868.440129385095, 4326.264535029052, 4333.978330108329, 4373.803178856959, 4416.050549131952, 4421.624161946206, 5686.744096515584, 5687.488126068673, 5687.658740475585, 5696.373398926197, 5702.45826646468, 6087.026075827935, 6123.785667501706, 6137.631742095488, 6141.6007959482495, 6147.047207682816, 5607.859803504945, 5636.943630563724, 5664.20701101846, 5671.602987790898, 5697.487226149667, 5300.31095957523, 5320.255164277004, 5325.627206095536, 5367.087595108841, 5367.785288588413, 6237.220347456568, 6248.718807653576, 6248.812535623939, 6249.78125765596, 6255.0617407388345, 6082.574219804748, 6128.50971922254, 6195.535334849995, 6207.6275423474735, 6214.564980451325, 6127.556056270569, 6147.754089836357, 6164.845878853064, 6193.318734939243, 6234.906476402948, 6122.142899994094, 6131.908021379593, 6135.84660383494, 6139.2325959255095, 6147.354527774519, 6129.049997250433, 6133.294671112879, 6151.938480615316, 6155.2921414037955, 6183.81448556544, 5077.339945807022, 5835.299764028344, 5856.385079566755, 5906.232189815297, 5947.732352044082, 5960.821175364746, 5966.313873904556, 5971.044632858721, 5974.970125149346, 6017.217761690952, 5641.294838145232, 5799.130130480462, 5837.153612726216, 5847.26909136358, 5856.677882716321, 5852.824415267549, 5923.85190370227, 5940.227770389822, 5948.8810223796145, 5960.672163031075, 5088.542031217208, 5807.651540907559, 5826.475617194473, 5832.795852145155, 5847.707614619337], "value": 5572.954718135827, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.1788780195647, 474.2672570877995, 481.17478524330767, 505.3481009497748, 507.75120191106345, 29904.551431728516, 31362.84318578404, 31431.05706828793, 31472.213194670116, 31517.68482315176, 40361.0, 41550.37674434886, 41928.580714192845, 43321.783391083, 43594.7820260899, 9231.446113233194, 9406.153446189875, 9410.482423466707, 9431.575579098922, 9440.24478041755, 9341.53292335384, 9395.248380129573, 9398.953005234964, 9401.153896149344, 9414.340996130273, 9387.10822471864, 9437.245020398352, 9442.716718498456, 9448.338616296827, 9452.10205030521, 9313.16181543661, 9374.578143983537, 9375.296852736072, 9394.248460123174, 9398.577064032135, 8364.163583641648, 9023.691564723144, 9341.953290233574, 9351.859722104175, 9385.953070234606, 38553.421698674545, 38763.22473550513, 38814.41778373327, 38859.805700971316, 38891.416628750594, 3252.96747032529, 3594.3889538778208, 3632.636736326346, 3704.481372607837, 3839.884803455901, 29716.55425168624, 30225.546616800766, 30246.24384390386, 30321.393572128654, 30929.690703092907, 3174.4286028514857, 3176.039496646468, 3181.363727254535, 3195.360927814423, 3308.5698859148397, 309562.0, 312196.87803121906, 312695.0, 312755.0, 312948.0, 32379.676203237665, 54894.0, 55253.579713622814, 55278.17082743762, 55346.44653553493, 33225.833870830495, 34610.307793844484, 34714.30571388558, 34824.65175348265, 34914.82542587271, 13666.0, 13825.308734563432, 13831.654208644864, 13841.446342146402, 13875.722485550232, 11567.595134170264, 11681.474333654836, 11687.649370518899, 11688.298702077902, 11705.941470292593, 7708.0, 7729.381649468029, 7815.140334563193, 7817.296443320129, 7819.0, 3209.534617480499, 3263.666478467907, 3264.8041117533025, 3267.5752152220534, 3301.7688761786926, 918.4432387103928, 973.2068364283086, 992.5087081894509, 995.9452230127267, 1017.7099526634873, 28420.0, 28586.571201431994, 29039.0, 29261.0, 29528.96648617288, 864.321507616518, 961.6826447272383, 1018.709667744704, 1035.0, 1096.7641956979287, 14316.785248221286, 14758.852411475747, 14793.482228121964, 15108.320125594375, 15190.0, 644640.0, 652248.0, 652970.0, 653740.0, 653975.0, 53662.73168634132, 53971.46028539743, 54085.45914540883, 54090.45909540933, 54184.72907635516, 1197.862245841722, 1230.0467137968146, 1301.5249433956537, 1319.089828018664, 1339.1028011232304, 28689.42621147607, 29572.408551829274, 29596.704032959395, 30247.546286806155, 30458.086257412313, 45619.0, 46218.0, 46427.76786116048, 46430.767846160554, 46499.30251046304, 503924.0, 506210.0, 508334.0, 508471.4576427094, 512201.0, 22278.554428911655, 22775.0, 24503.264902052968, 25064.874675626506, 25182.614956177586, 42950.78524607357, 43080.138397231254, 43615.781921090194, 44071.55928440803, 44086.33870492009, 29689.851550742107, 36182.858856758074, 36498.8175059123, 37395.81302093472, 37504.437433437975, 34032.65967340394, 35159.01148402312, 35844.28311433809, 36931.8153409242, 37118.072048199014, 35397.46903796497, 35776.28447431089, 36012.63987360093, 36122.63877361193, 36147.0, 31272.0, 32541.511877321387, 32619.510707339883, 32639.836800815843, 32771.50842737409, 24008.55948643087, 24342.39144021343, 24384.756152438957, 24591.75408245966, 24802.751972480764], "value": 17590.65630950257, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.139650872818, 349.9002784206502, 371.5819702834313, 378.7689509399266, 402.17152665509053, 14599.0, 14815.851841481583, 15514.362558365063, 19090.904545477253, 20510.0, 15716.135612541306, 18707.71938420925, 19572.11925463354, 19815.900920495413, 19850.205991760347, 5573.526250268722, 5921.822345329647, 5925.614835035731, 5926.555508336892, 5927.970360148193, 5564.0, 5773.942260577403, 5802.303723553179, 5836.883262334751, 5852.882942341151, 5797.217375654275, 5798.0, 5798.478136967672, 5800.738966746507, 5803.82588522345, 5492.697901615409, 5646.520045796112, 5708.571857110734, 5715.085586306171, 5724.398938111522, 5015.774290156952, 5016.347874776292, 5017.172166592508, 5020.071286811952, 5025.572826309732, 13040.347982600832, 13285.867141328656, 22964.425889352733, 23604.527909441887, 23992.880035599886, 2137.668661357492, 2139.5613899150635, 2490.489449662833, 2716.6739991200984, 2827.618271533358, 9510.0, 9521.194268310977, 9945.90054099457, 10002.49987500622, 10339.689809305733, 1695.4828777222942, 1827.7715285589302, 1859.470051035462, 1859.6094820087662, 1885.1705249690092, 108859.0, 110470.34294485589, 110940.33589496164, 115430.6913861718, 119693.0, 10122.696319110291, 10317.555542224094, 10940.288881222696, 11137.777244455065, 11311.604093856675, 11882.702932426759, 12485.001199903985, 14327.426902923766, 14378.496752613817, 14382.640433989234, 7842.176571460029, 7945.523268603903, 8106.189381061847, 8262.380321475914, 8300.2114799094, 7933.127355990837, 7956.443048986631, 7968.960155199187, 8138.104808471064, 8158.67365305393, 4369.169857727022, 4462.174497717932, 4483.327500874895, 4540.750258735801, 4546.158960592301, 1885.77370715515, 1910.2397036282716, 1910.8757930734457, 1922.2311075569885, 1958.3158483704003, 890.528020149322, 897.4435849773182, 912.5847739278612, 937.5827756648363, 995.7859060301925, 17733.290668373378, 20113.899430502755, 20633.38099857007, 21551.0, 21788.128474861143, 897.533282693009, 917.6650522559218, 918.7749001494676, 940.3041749105613, 978.4373984958677, 9803.754906127262, 10783.083437907866, 11242.93192146761, 11254.662360129209, 11286.871312868812, 503345.96654033725, 510816.0, 512047.0, 600682.0, 610516.0, 113911.0, 116705.0, 123408.0, 129219.35390322989, 141524.0, 946.9763255918657, 967.4485543240426, 1009.8687170667913, 1012.6303899076784, 1015.669907280137, 18841.0, 18949.715754263972, 19037.0, 19057.71413428772, 19104.426867194008, 53865.53613566397, 54133.72933135309, 54924.705531534855, 55308.72345638246, 56897.0, 81140.5942970305, 81506.59246703729, 82588.58705706433, 82924.0, 85084.57457712672, 7795.883061753965, 7896.09194942589, 8048.396370272136, 8050.718224862102, 8180.9590952044855, 11947.522099116286, 12270.87729122733, 12312.75374492523, 12654.746905061664, 12799.936000319938, 10891.836622450512, 10993.560257589606, 11062.668119956414, 11157.218994670295, 11240.88759112431, 10483.108935740547, 10670.62652807148, 10781.407022613832, 10792.028717415471, 10991.39547324873, 5597.412271711411, 7559.433042521723, 11043.337399756041, 11191.104711623213, 11248.381339026431, 6501.577397469149, 7732.9226707732205, 7976.202379761978, 7984.403119376032, 8112.9188708114525, 10877.347359158475, 10881.597005614472, 10996.80348674271, 11039.503222355173, 11088.778224435304], "value": 9217.813345919845, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1311.9212847229162, 1457.271364317841, 1478.5490425420248, 1639.721247387944, 1831.3864855273482, 6509.511786616005, 7932.722354717579, 8162.714304999319, 8491.78770530737, 8519.91480085199, 8362.205590468911, 8441.831163376728, 8443.071262161156, 8453.239208471236, 8475.83048339033, 2141.850070495062, 2343.496148328109, 2344.5076533927895, 2346.3547524430746, 2347.1902193743217, 2319.4433335999324, 2368.692070030894, 2378.3826997641595, 2380.345405013618, 2386.7493913139133, 1974.82226599606, 2301.9309420717404, 2306.8039216666602, 2338.0063473023984, 2339.2397470821966, 2270.182734215681, 2297.069686776858, 2308.734495533002, 2310.5609934112463, 2314.0859360552654, 2306.1121468234683, 2320.4198950262435, 2330.5804955108074, 2332.4402143485613, 2335.7781010803947, 8235.29999950005, 8384.538850363228, 8461.619227134794, 8468.745937621881, 8471.70349037787, 5031.4465408805, 5216.37403511581, 5230.816921407771, 5236.293100431431, 5241.947580524184, 3312.5031245313394, 3395.6774106459848, 3398.422268214385, 3471.7743346682637, 3478.7564870459078, 1036.440322225997, 1062.1449732964961, 1724.1551639696584, 1763.4180720362249, 1832.8991905445196, 10821.350718956888, 11069.889301106967, 11191.32852028881, 11196.720081997933, 11223.046041086518, 3797.9620203798163, 4365.454318210223, 4501.662375321864, 4520.547945205453, 4539.795709193094, 8835.911640883638, 8845.336599755045, 8848.823023539493, 8856.911430885608, 8860.8670869937, 5130.33305670268, 5137.0, 5143.305653736773, 5143.588512918957, 5144.408393034849, 3803.088157284775, 4677.859664210079, 4812.855614331575, 4820.493848145894, 4829.975850120727, 3588.336157810761, 3631.8728844490315, 3684.7604905681046, 3731.7760934343482, 3746.325661380923, 1136.431784107946, 1139.8632164140356, 1149.8217776244696, 1153.907687385024, 1157.4907040901976, 828.9544075075929, 837.7277384849948, 840.3991146330403, 912.717057712098, 919.2829592917535, 5329.653572517774, 5475.342958844964, 5736.368999410061, 5799.304083509922, 5806.506446952057, 351.3955995687414, 392.4682055814387, 422.89850435895164, 424.2871975081892, 424.4609346130397, 3464.9951514061145, 3767.8869633911027, 3844.8462061517785, 3904.0, 3905.1799122184093, 8710.606302991491, 9828.803423931482, 10066.848997265048, 10163.847542286872, 10373.118284945864, 9315.627374904929, 9557.092076252746, 10325.690229293134, 10338.53476593555, 10347.275690701728, 7443.702251909863, 7691.0, 7742.10965738936, 7752.3798096153305, 7756.573388463576, 5385.73071346439, 5448.318960129984, 5695.231143795535, 5697.373288938131, 5714.514266287412, 9435.57539910692, 9444.33889627747, 9607.375520591138, 9608.991055939305, 9621.470819105016, 9542.618295268388, 9584.28117891147, 9742.0, 9831.803363932539, 9918.256130790076, 5976.820695378971, 6572.178477690289, 6776.93223067763, 6779.966100169468, 7395.408367330493, 7830.608469576612, 8021.799455013671, 8132.43072984909, 8172.8365432689825, 8184.140665230303, 6968.198657154492, 7072.0, 7127.358537731422, 7155.141383034069, 7283.8179045522165, 6785.287544807724, 6898.551594146365, 6993.0, 7007.614581198083, 7032.753853615099, 6655.434288085373, 6718.630475323904, 6795.93204067953, 6822.761203357859, 6824.146981627297, 4707.4821769606, 4758.928616070831, 4774.785134669018, 4781.784819683053, 4900.436449808175, 4152.854650087233, 4664.440267167962, 4797.232442809134, 4956.752162391938, 5054.16606259963], "value": 4320.572000817493, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.2696952013288, 298.7714398485158, 304.392736490701, 310.25229197633695, 315.6835993905414, 4156.729812562181, 4388.97805510972, 4446.288593824985, 5116.925445656417, 5145.536901678848, 4584.243599806028, 4930.6055515558755, 4959.619382696308, 5073.315102461167, 5223.86940326492, 3935.9016024599327, 3962.3065963456424, 3989.6435212027905, 3991.2217117662067, 3999.9800150886185, 3507.929841403183, 3761.4357846323132, 4005.3991901214904, 4016.0160760613544, 4017.1965606878593, 3297.686719761619, 3932.2304962766725, 3959.742616729903, 3968.1071758854173, 3987.9202415951663, 3424.9143771405666, 3938.9015274618077, 3950.2692001979726, 3951.308521008812, 3968.9404658929993, 2991.670916199216, 3245.2535916739284, 3275.5741753571883, 3366.831658417094, 3446.0867870014467, 4399.296112621997, 5077.517635824591, 5095.923561146586, 5124.871878203037, 5134.486551344873, 4523.02467285986, 4541.751832842764, 4563.931541026887, 4570.486232572105, 4570.954290457119, 4107.104491333603, 5069.949300506984, 5432.392451623243, 5460.645058071213, 5516.365617953907, 3142.4186525492855, 3379.026456718097, 3603.4234522476277, 3750.2499500099816, 3770.472133901256, 4702.365180700596, 5144.639875208737, 5806.303243610751, 5812.244408226946, 5833.416658334133, 3385.2890892912765, 3589.3898037333715, 3818.3127037133027, 3850.6534411903062, 3866.0, 3170.1599076244815, 3820.465134881119, 3864.8067596620617, 3890.513685789276, 3895.3572660511263, 3853.7109716771356, 4384.956150438455, 4491.0, 4495.640348772155, 4520.0, 4573.794179261941, 5252.579793616578, 5436.429174936575, 5451.454854514596, 5451.48210919962, 1070.9357438553711, 1187.216437151476, 1189.8869607387285, 1203.711109333771, 1245.439552201513, 689.36233983565, 768.7194174126515, 769.6338998277989, 783.7460063897831, 860.0754189246585, 4195.307774217281, 4433.290673492225, 4784.665073444826, 4825.758712064453, 4883.584895283941, 5092.668976516588, 5200.583953283728, 5298.284731561267, 5300.681959082391, 5310.867228319323, 3853.9421908671393, 4129.628333450004, 4344.218040752632, 4866.4403593586485, 4892.535209155125, 2260.344500094954, 2452.779249867485, 2782.749552540281, 2794.2595212268766, 2813.7467627913584, 4999.425066117443, 5004.2493625956395, 5509.641873278224, 5519.586031047607, 5525.668459892339, 4781.569658730731, 5225.5819534437915, 5277.762500687479, 5281.728856591702, 5327.440618735054, 5828.358880523139, 5909.4044607956475, 5931.851703707442, 5954.196183515343, 5971.552133589998, 4774.689645173053, 4960.454350021422, 5722.570807189394, 5739.2252045973255, 5841.795537156179, 4899.926501102557, 5265.947340526545, 5559.722013899208, 5588.972055139699, 5620.915686264628, 4704.976475117603, 5822.583627794418, 5836.678982655994, 5852.502537284378, 5866.6480011199465, 3454.9827250863586, 3639.526861508093, 3768.1333293342136, 3778.7543809652284, 3816.2176753765966, 4712.434507859079, 5404.5406140476925, 5540.058190107772, 5615.479538971167, 5646.604737668488, 5183.922241166461, 5382.461753824587, 5387.218853266333, 5395.595330350161, 5524.4199359068325, 5434.673919564839, 5449.3188351456065, 5457.863553411196, 5461.0, 5471.0, 4219.894502637459, 5284.392294886137, 5400.756965936621, 5404.351477822686, 5430.429804870431, 4842.539958703917, 5472.823342981278, 5483.204935284281, 5517.420670829666, 5608.85977850557, 4552.406657669789, 4915.721912302755, 5334.546563542142, 5464.726763661881, 5498.780048798003], "value": 3909.581374390733, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.9108315918932, 689.5035574386442, 716.0, 737.8966944627756, 774.907011158661, 14051.929740351297, 14504.202268875208, 14529.927350363247, 14540.709185816277, 14566.417343306255, 10430.478476076203, 12263.834935681118, 12790.104692671493, 12958.287294198815, 13084.869151308483, 531.3464438740342, 675.554134271381, 677.474956908395, 680.3944489404435, 681.4582406986458, 670.8088194864465, 814.6374863185885, 835.4152093534519, 835.7367429259764, 841.5329492131864, 720.9134903811524, 875.264777586827, 892.5046599137509, 894.0344428017758, 899.9685011024616, 883.9701747464196, 887.7913690282778, 889.2397000564489, 889.4663202078766, 891.9420237684529, 514.5600511562598, 527.2039220776617, 529.3462573721451, 656.3829999800221, 658.6476235214137, 10642.946785266102, 11577.710557236052, 11598.420078996016, 13301.002424818178, 13302.93348533251, 13197.27414992175, 13444.663883402894, 13645.249511276876, 13711.177329360271, 13712.862871371259, 11026.393548354838, 11111.777764444665, 11141.442927853655, 11302.208845380826, 11455.59905403307, 3474.9826250868837, 3491.6682915123024, 3543.85824567017, 3589.3898037333715, 3617.674409303149, 80950.0, 82515.58742206251, 84994.57502712446, 85242.57378713066, 87106.56446717726, 10429.530671119817, 10432.791344173225, 10574.577016919391, 10580.259381843198, 10910.836337454944, 18719.90640046791, 18871.7733347332, 20468.79531204669, 20755.481112972295, 20773.688394674093, 7422.96288518554, 7428.85142297151, 7513.323800857949, 7586.544807311579, 7673.386129109658, 4095.0690609104386, 4169.8957526062095, 4191.119864828359, 4239.894002649897, 4262.147570485915, 3299.356625458013, 3424.70889974355, 3437.810920399352, 3441.5525981622227, 3446.052335607678, 617.0497433951664, 621.6580880515788, 631.8704665543553, 631.8767840271195, 640.6700549217157, 445.9130469558405, 499.9850004499798, 568.8679527739768, 590.1973316289842, 641.3137942401679, 7914.327282181079, 8092.2486354639705, 8304.667813287522, 8561.14388561139, 8608.328545377257, 558.1906235957878, 566.1988286574533, 580.9099589563624, 592.9238432245461, 599.2958274028, 3803.7147213959065, 4019.9397009044887, 4036.3138266494766, 4097.7541347519245, 4134.607212314825, 88044.0, 108282.0, 112829.43585282021, 113866.43066784613, 114032.0, 65705.67147164329, 66059.33940660532, 79564.40871182544, 82001.58999205085, 93079.0, 55253.0, 81419.0, 81422.59288703519, 82178.0, 84195.5790221045, 8576.914230857861, 8689.391742578211, 8821.823563528566, 9042.683506077256, 9081.395696502565, 48241.75879120582, 48782.5121748778, 55366.72316638391, 56091.0, 58034.0, 62018.0, 67878.32121678918, 71187.37282076648, 74011.0, 75619.0, 9901.702948911545, 11079.390633514911, 11559.364234967157, 11680.948714615726, 11880.643580692593, 12738.617841464771, 13299.601011969657, 13318.067735258439, 13390.330483475593, 13568.525101621395, 12176.147669663038, 12249.755004899675, 12332.445039973043, 12819.935900320439, 12933.805992910302, 11865.584704535646, 12179.817302740643, 12204.04735242357, 12613.621591352274, 13180.143290686075, 12073.698157546132, 12074.939625301817, 12215.572454964034, 12264.632061038183, 12404.875951240732, 10099.394036357842, 10141.746456338651, 10267.7433064174, 10448.373097614167, 10583.735406614896, 9171.862422063808, 10677.89322106769, 10697.946510267398, 10707.0, 10855.891441085489], "value": 6897.69459875937, "unit": "score"}], "name": "dromaeo_css", "value": 6871.781414565738, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 114.0, 113.0, 113.0, 115.0, 114.0, 114.0, 114.0, 113.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 115.0, 114.0, 145.0, 141.0, 113.0, 111.0, 112.0, 152.0, 141.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 194.0, 264.0, 222.0, 184.0, 180.0, 183.0, 185.0, 181.0], "value": 196.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 62.0, 58.0, 86.0, 61.0, 60.0, 58.0, 59.0, 83.0, 58.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 91.0, 70.0, 69.0, 69.0, 90.0, 67.0, 68.0, 92.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 86.0, 87.0, 85.0, 89.0, 85.0, 88.0, 84.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 102.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 104.0], "value": 97.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 82.0, 81.0, 83.0, 81.0, 82.0, 85.0, 80.0, 80.0], "value": 81.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 54.0, 53.0, 53.0, 54.0, 56.0, 53.0, 53.0, 54.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 71.0, 81.0, 74.0, 75.0, 71.0, 72.0, 73.0, 77.0, 107.0], "value": 77.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 87.0, 85.0, 90.0, 84.0, 87.0, 87.0, 88.0, 86.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 145.0, 154.0, 157.0, 151.0, 168.0, 159.0, 218.0, 155.0], "value": 160.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 177.0, 169.0, 240.0, 160.0, 217.0, 235.0, 226.0, 176.0, 175.0], "value": 195.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 64.0, 67.0, 68.0, 68.0, 67.0, 67.0, 69.0, 74.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1492.1999999999998, "alertThreshold": 2.0}]} 08:53:22 INFO - Return code: 0 08:53:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:53:22 INFO - # TBPL SUCCESS # 08:53:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:53:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:53:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:53:22 INFO - 2016-11-06 08:53:22.433 screenresolution[1677:12479] starting screenresolution argv=screenresolution get 08:53:22 INFO - 2016-11-06 08:53:22.435 screenresolution[1677:12479] Display 0: 1600x1200x32@60 08:53:22 INFO - 2016-11-06 08:53:22.441 screenresolution[1678:12482] starting screenresolution argv=screenresolution list 08:53:22 INFO - Available Modes on Display 0 08:53:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:53:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:53:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:53:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:53:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:53:22 INFO - Intel Iris: 08:53:22 INFO - Chipset Model: Intel Iris 08:53:22 INFO - Type: GPU 08:53:22 INFO - Bus: Built-In 08:53:22 INFO - VRAM (Dynamic, Max): 1536 MB 08:53:22 INFO - Vendor: Intel (0x8086) 08:53:22 INFO - Device ID: 0x0a2e 08:53:22 INFO - Revision ID: 0x0009 08:53:22 INFO - Displays: 08:53:22 INFO - Display: 08:53:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:53:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:53:22 INFO - Display Serial Number: 1600x1200 60 08:53:22 INFO - Main Display: Yes 08:53:22 INFO - Mirror: Off 08:53:22 INFO - Online: Yes 08:53:22 INFO - Rotation: Supported 08:53:22 INFO - Return code: 0 08:53:22 INFO - Running post-action listener: _resource_record_post_action 08:53:22 INFO - [mozharness: 2016-11-06 16:53:22.684431Z] Finished run-tests step (success) 08:53:22 INFO - Running post-run listener: _resource_record_post_run 08:53:22 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 44511232; Write bytes: 713860096; Read time: 475; Write time: 2215 08:53:22 INFO - TinderboxPrint: CPU usage
53.8% 08:53:22 INFO - TinderboxPrint: I/O read bytes / time
44,511,232 / 475 08:53:22 INFO - TinderboxPrint: I/O write bytes / time
713,860,096 / 2,215 08:53:22 INFO - TinderboxPrint: CPU idle
2,240.2 (46.2%) 08:53:22 INFO - TinderboxPrint: CPU system
976.3 (20.1%) 08:53:22 INFO - TinderboxPrint: CPU user
1,637.2 (33.7%) 08:53:22 INFO - TinderboxPrint: Swap in / out
436,387,840 / 0 08:53:22 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404304384; Write bytes: 405583872; Read time: 29906; Write time: 755 08:53:22 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 40337408; Write bytes: 306953216; Read time: 444; Write time: 1455 08:53:23 INFO - Running post-run listener: _upload_blobber_files 08:53:23 INFO - Blob upload gear active. 08:53:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:53:23 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:53:23 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:53:23 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:53:23 INFO - (blobuploader) - INFO - Open directory for files ... 08:53:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:53:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:53:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:24 INFO - (blobuploader) - INFO - Done attempting. 08:53:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:53:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:53:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:25 INFO - (blobuploader) - INFO - Done attempting. 08:53:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:53:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:53:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:27 INFO - (blobuploader) - INFO - Done attempting. 08:53:27 INFO - (blobuploader) - INFO - Iteration through files over. 08:53:28 INFO - Return code: 0 08:53:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:53:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:53:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/614b4ad641c80cd388f720e9e1c4c8dc205d55c2971429a95a83859cdcf8e311e7da62ac356b4163155713177a7f6b10b1942f332cdf9f90bd2188bb3cfb4b13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50faea067b51fb4348834fa8097ca07b94979b7e37a8c3b3e2a2c577342cf287ae44e9f99e8e110a3212cd6d63ecb802d6bcbc3d01e64baff8dce85eaa030b3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40b57dde6dd2acacd76563be10ba2268898cf17e3c0f8d2d942d055e4ae7ca60294b6f422fbafc6df528f1de4ba0b4a4eab80e5d7a84a7c6bbf496506fd64995"} 08:53:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:53:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:53:28 INFO - Contents: 08:53:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/614b4ad641c80cd388f720e9e1c4c8dc205d55c2971429a95a83859cdcf8e311e7da62ac356b4163155713177a7f6b10b1942f332cdf9f90bd2188bb3cfb4b13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50faea067b51fb4348834fa8097ca07b94979b7e37a8c3b3e2a2c577342cf287ae44e9f99e8e110a3212cd6d63ecb802d6bcbc3d01e64baff8dce85eaa030b3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40b57dde6dd2acacd76563be10ba2268898cf17e3c0f8d2d942d055e4ae7ca60294b6f422fbafc6df528f1de4ba0b4a4eab80e5d7a84a7c6bbf496506fd64995"} 08:53:28 INFO - Running post-run listener: copy_logs_to_upload_dir 08:53:28 INFO - Copying logs to upload dir... 08:53:28 INFO - mkdir: /builds/slave/test/build/upload/logs 08:53:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.779222 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-06 08:53:28.066973) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-06 08:53:28.070610) ========= 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.2GrBiVO1FW/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.NN6z6lgvHz/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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/614b4ad641c80cd388f720e9e1c4c8dc205d55c2971429a95a83859cdcf8e311e7da62ac356b4163155713177a7f6b10b1942f332cdf9f90bd2188bb3cfb4b13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50faea067b51fb4348834fa8097ca07b94979b7e37a8c3b3e2a2c577342cf287ae44e9f99e8e110a3212cd6d63ecb802d6bcbc3d01e64baff8dce85eaa030b3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40b57dde6dd2acacd76563be10ba2268898cf17e3c0f8d2d942d055e4ae7ca60294b6f422fbafc6df528f1de4ba0b4a4eab80e5d7a84a7c6bbf496506fd64995"} build_url:https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011174 build_url: 'https://queue.taskcluster.net/v1/task/CjrCC5fVQqqxJ7D5jme8dQ/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/614b4ad641c80cd388f720e9e1c4c8dc205d55c2971429a95a83859cdcf8e311e7da62ac356b4163155713177a7f6b10b1942f332cdf9f90bd2188bb3cfb4b13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50faea067b51fb4348834fa8097ca07b94979b7e37a8c3b3e2a2c577342cf287ae44e9f99e8e110a3212cd6d63ecb802d6bcbc3d01e64baff8dce85eaa030b3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40b57dde6dd2acacd76563be10ba2268898cf17e3c0f8d2d942d055e4ae7ca60294b6f422fbafc6df528f1de4ba0b4a4eab80e5d7a84a7c6bbf496506fd64995"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-06 08:53:28.104219) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 08:53:28.104548) ========= 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.2GrBiVO1FW/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.NN6z6lgvHz/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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.005164 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 08:53:28.153079) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-06 08:53:28.153421) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-06 08:53:28.153740) ========= ========= Total master_lag: 0.17 =========