builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0269 starttime: 1479189954.39 results: success (0) buildid: 20161114194413 builduid: c871fb2adbdf4c0ba790bf2deee973a3 revision: aeb8bcaafe56ee681cf19a13398cd14d163af6ca ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 22:05:54.386764) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 22:05:54.387236) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 22:05:54.387534) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 22:05:54.411938) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:05:54.412303) ========= 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.6WFMISYXhL/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.aSyxBcn6jl/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-14 22:05:54-- 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% 151M=0s 2016-11-14 22:05:54 (151 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092082 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:05:54.526959) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:05:54.527322) ========= 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.6WFMISYXhL/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.aSyxBcn6jl/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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.025120 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:05:54.575759) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 22:05:54.576131) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev aeb8bcaafe56ee681cf19a13398cd14d163af6ca --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev aeb8bcaafe56ee681cf19a13398cd14d163af6ca --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6WFMISYXhL/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.aSyxBcn6jl/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-14 22:05:54,675 truncating revision to first 12 chars 2016-11-14 22:05:54,676 Setting DEBUG logging. 2016-11-14 22:05:54,676 attempt 1/10 2016-11-14 22:05:54,676 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/aeb8bcaafe56?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 22:05:56,211 unpacking tar archive at: mozilla-aurora-aeb8bcaafe56/testing/mozharness/ program finished with exit code 0 elapsedTime=1.949653 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 22:05:56.548334) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 22:05:56.548654) ========= script_repo_revision: aeb8bcaafe56ee681cf19a13398cd14d163af6ca ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 22:05:56.549064) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 22:05:56.549344) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 22:05:56.568311) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-14 22:05:56.569003) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6WFMISYXhL/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.aSyxBcn6jl/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 22:05:56 INFO - MultiFileLogger online at 20161114 22:05:56 in /builds/slave/test 22:05:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 22:05:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:05:56 INFO - {'append_to_log': False, 22:05:56 INFO - 'base_work_dir': '/builds/slave/test', 22:05:56 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:05:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:05:56 INFO - 'branch': 'Mozilla-Aurora', 22:05:56 INFO - 'buildbot_json_path': 'buildprops.json', 22:05:56 INFO - 'config_files': ('talos/mac_config.py',), 22:05:56 INFO - 'default_actions': ('clobber', 22:05:56 INFO - 'read-buildbot-config', 22:05:56 INFO - 'download-and-extract', 22:05:56 INFO - 'populate-webroot', 22:05:56 INFO - 'create-virtualenv', 22:05:56 INFO - 'install', 22:05:56 INFO - 'run-tests'), 22:05:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:05:56 INFO - 'download_minidump_stackwalk': True, 22:05:56 INFO - 'download_symbols': 'ondemand', 22:05:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:05:56 INFO - 'tooltool.py': '/tools/tooltool.py', 22:05:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:05:56 INFO - '/tools/misc-python/virtualenv.py')}, 22:05:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:05:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:05:56 INFO - 'installer_path': 'installer.exe', 22:05:56 INFO - 'log_level': 'info', 22:05:56 INFO - 'log_name': 'talos', 22:05:56 INFO - 'log_to_console': True, 22:05:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:05:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:05:56 INFO - 'opt_config_files': (), 22:05:56 INFO - 'pip_index': False, 22:05:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:05:56 INFO - 'cmd': ('bash', 22:05:56 INFO - '-c', 22:05:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:05:56 INFO - 'enabled': True, 22:05:56 INFO - 'halt_on_failure': False, 22:05:56 INFO - 'name': 'check_screen_resolution'},), 22:05:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:05:56 INFO - 'cmd': ('bash', 22:05:56 INFO - '-c', 22:05:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:05:56 INFO - 'enabled': True, 22:05:56 INFO - 'halt_on_failure': False, 22:05:56 INFO - 'name': 'check_screen_resolution'},), 22:05:56 INFO - 'run_cmd_checks_enabled': True, 22:05:56 INFO - 'sps_profile': False, 22:05:56 INFO - 'sps_profile_interval': 0, 22:05:56 INFO - 'suite': 'dromaeojs', 22:05:56 INFO - 'system_bits': '32', 22:05:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:05:56 INFO - 'title': 't-yosemite-r7-0269', 22:05:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:05:56 INFO - 'use_talos_json': True, 22:05:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:05:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:05:56 INFO - 'work_dir': 'build'} 22:05:56 INFO - [mozharness: 2016-11-15 06:05:56.771918Z] Running clobber step. 22:05:56 INFO - Running pre-action listener: _resource_record_pre_action 22:05:56 INFO - Running main action method: clobber 22:05:56 INFO - rmtree: /builds/slave/test/build 22:05:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:05:59 INFO - Running post-action listener: _resource_record_post_action 22:05:59 INFO - [mozharness: 2016-11-15 06:05:59.108521Z] Finished clobber step (success) 22:05:59 INFO - [mozharness: 2016-11-15 06:05:59.108762Z] Running read-buildbot-config step. 22:05:59 INFO - Running pre-action listener: _resource_record_pre_action 22:05:59 INFO - Running main action method: read_buildbot_config 22:05:59 INFO - Using buildbot properties: 22:05:59 INFO - { 22:05:59 INFO - "project": "", 22:05:59 INFO - "product": "firefox", 22:05:59 INFO - "script_repo_revision": "production", 22:05:59 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 22:05:59 INFO - "repository": "", 22:05:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 22:05:59 INFO - "buildid": "20161114194413", 22:05:59 INFO - "pgo_build": "False", 22:05:59 INFO - "basedir": "/builds/slave/test", 22:05:59 INFO - "buildnumber": 29, 22:05:59 INFO - "slavename": "t-yosemite-r7-0269", 22:05:59 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 22:05:59 INFO - "platform": "yosemite_r7", 22:05:59 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 22:05:59 INFO - "branch": "mozilla-aurora", 22:05:59 INFO - "repo_path": "releases/mozilla-aurora", 22:05:59 INFO - "revision": "aeb8bcaafe56ee681cf19a13398cd14d163af6ca", 22:05:59 INFO - "stage_platform": "macosx64", 22:05:59 INFO - "builduid": "c871fb2adbdf4c0ba790bf2deee973a3", 22:05:59 INFO - "slavebuilddir": "test" 22:05:59 INFO - } 22:05:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 22:05:59 INFO - Running post-action listener: _resource_record_post_action 22:05:59 INFO - [mozharness: 2016-11-15 06:05:59.114059Z] Finished read-buildbot-config step (success) 22:05:59 INFO - [mozharness: 2016-11-15 06:05:59.114284Z] Running download-and-extract step. 22:05:59 INFO - Running pre-action listener: _resource_record_pre_action 22:05:59 INFO - Running main action method: download_and_extract 22:05:59 INFO - mkdir: /builds/slave/test/build/tests 22:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:05:59 INFO - trying https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 22:05:59 INFO - Downloading https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 22:05:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 22:06:00 INFO - Downloaded 1199 bytes. 22:06:00 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 22:06:00 INFO - Using the following test package requirements: 22:06:00 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 22:06:00 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:06:00 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 22:06:00 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:06:00 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 22:06:00 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:06:00 INFO - u'jsshell-mac.zip'], 22:06:00 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:06:00 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 22:06:00 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 22:06:00 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:06:00 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 22:06:00 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:06:00 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 22:06:00 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:06:00 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 22:06:00 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:06:00 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 22:06:00 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 22:06:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 22:06:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:06:00 INFO - Fetch https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 22:06:00 INFO - Http code: 200 22:06:00 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 22:06:00 INFO - x-amz-cf-id: 7tW5U6AuDH4PA83WR8i6ZYtZWmTtrS2sNDsMTsJsQSbYSUdUyhdqRw== 22:06:00 INFO - x-amz-version-id: z4KGPoT9gnZCB6jb92W7pP74v0FEpTpf 22:06:01 INFO - Expected file size: 16327505 22:06:01 INFO - Obtained file size: 16327505 22:06:07 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 22:06:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 22:06:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:06:07 INFO - Fetch https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 22:06:08 INFO - Http code: 200 22:06:08 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 22:06:08 INFO - x-amz-cf-id: mH9-H9QF3dbfSP02x1KnIyVaOnpNU6l34r7ImtcpOLTYF2pXUtJ2SQ== 22:06:08 INFO - x-amz-version-id: z4KGPoT9gnZCB6jb92W7pP74v0FEpTpf 22:06:08 INFO - Expected file size: 16327505 22:06:08 INFO - Obtained file size: 16327505 22:06:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 22:06:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 22:06:14 INFO - Fetch https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 22:06:15 INFO - Http code: 200 22:06:15 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 22:06:15 INFO - x-amz-cf-id: h5OBY0hNsXConJ13JKYiaXabn3qer7w20AsobFAgzP8cRvCcuC5_0A== 22:06:15 INFO - x-amz-version-id: bEKgGLejMeOZA4fg9SF868BW5rpqFXX1 22:06:16 INFO - Expected file size: 14409038 22:06:16 INFO - Obtained file size: 14409038 22:06:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:06:16 INFO - trying https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 22:06:16 INFO - Downloading https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 22:06:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 22:06:19 INFO - Downloaded 114361377 bytes. 22:06:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 22:06:19 INFO - mkdir: /builds/slave/test/properties 22:06:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:06:19 INFO - Writing to file /builds/slave/test/properties/build_url 22:06:19 INFO - Contents: 22:06:19 INFO - build_url:https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 22:06:20 INFO - Running post-action listener: _resource_record_post_action 22:06:20 INFO - Running post-action listener: set_extra_try_arguments 22:06:20 INFO - [mozharness: 2016-11-15 06:06:20.409469Z] Finished download-and-extract step (success) 22:06:20 INFO - [mozharness: 2016-11-15 06:06:20.409591Z] Running populate-webroot step. 22:06:20 INFO - Running pre-action listener: _resource_record_pre_action 22:06:20 INFO - Running main action method: populate_webroot 22:06:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:06:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:06:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:06:20 INFO - '--tppagecycles', 22:06:20 INFO - '7'], 22:06:20 INFO - 'tests': ['tsvgm']}}, 22:06:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:06:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:06:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:06:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:06:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:06:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:06:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:20 INFO - 'talos_options': ['--disable-e10s'], 22:06:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:06:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:06:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:20 INFO - 'talos_options': ['--disable-e10s'], 22:06:20 INFO - 'tests': ['damp', 'tps']}, 22:06:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:20 INFO - 'tests': ['damp', 'tps']}, 22:06:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:06:20 INFO - 'tests': ['dromaeo_dom']}, 22:06:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:06:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:06:20 INFO - 'tests': ['basic_compositor_video']}, 22:06:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:06:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:06:20 INFO - 'tests': ['a11yr', 22:06:20 INFO - 'ts_paint', 22:06:20 INFO - 'tpaint', 22:06:20 INFO - 'sessionrestore', 22:06:20 INFO - 'sessionrestore_no_auto_restore', 22:06:20 INFO - 'tabpaint']}, 22:06:20 INFO - 'other-e10s': {'tests': ['a11yr', 22:06:20 INFO - 'ts_paint', 22:06:20 INFO - 'tpaint', 22:06:20 INFO - 'sessionrestore', 22:06:20 INFO - 'sessionrestore_no_auto_restore', 22:06:20 INFO - 'tabpaint']}, 22:06:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:06:20 INFO - 'ts_paint', 22:06:20 INFO - 'tpaint', 22:06:20 INFO - 'sessionrestore', 22:06:20 INFO - 'sessionrestore_no_auto_restore', 22:06:20 INFO - 'tabpaint']}, 22:06:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:06:20 INFO - 'ts_paint', 22:06:20 INFO - 'tpaint', 22:06:20 INFO - 'sessionrestore', 22:06:20 INFO - 'sessionrestore_no_auto_restore', 22:06:20 INFO - 'tabpaint']}, 22:06:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:06:20 INFO - 'tests': ['a11yr', 22:06:20 INFO - 'ts_paint', 22:06:20 INFO - 'tpaint', 22:06:20 INFO - 'sessionrestore', 22:06:20 INFO - 'sessionrestore_no_auto_restore', 22:06:20 INFO - 'tabpaint']}, 22:06:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:06:20 INFO - 'tests': ['a11yr', 22:06:20 INFO - 'ts_paint', 22:06:20 INFO - 'tpaint', 22:06:20 INFO - 'sessionrestore', 22:06:20 INFO - 'sessionrestore_no_auto_restore', 22:06:20 INFO - 'tabpaint']}, 22:06:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:06:20 INFO - 'tests': ['tsvgx', 22:06:20 INFO - 'tsvgr_opacity', 22:06:20 INFO - 'tart', 22:06:20 INFO - 'tscrollx', 22:06:20 INFO - 'cart']}, 22:06:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:06:20 INFO - 'tsvgr_opacity', 22:06:20 INFO - 'tart', 22:06:20 INFO - 'tscrollx', 22:06:20 INFO - 'cart']}, 22:06:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:20 INFO - 'talos_options': ['--disable-e10s'], 22:06:20 INFO - 'tests': ['tp5o']}, 22:06:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:20 INFO - 'tests': ['tp5o']}, 22:06:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:20 INFO - 'talos_options': ['--disable-e10s', 22:06:20 INFO - '--xperf_path', 22:06:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:06:20 INFO - 'tests': ['tp5n']}, 22:06:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:20 INFO - 'talos_options': ['--xperf_path', 22:06:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:06:20 INFO - 'tests': ['tp5n']}}, 22:06:20 INFO - 'talos.zip': {'path': '', 22:06:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:06:20 INFO - Running post-action listener: _resource_record_post_action 22:06:20 INFO - [mozharness: 2016-11-15 06:06:20.428278Z] Finished populate-webroot step (success) 22:06:20 INFO - [mozharness: 2016-11-15 06:06:20.428414Z] Running create-virtualenv step. 22:06:20 INFO - Running pre-action listener: _resource_record_pre_action 22:06:20 INFO - Running main action method: create_virtualenv 22:06:20 INFO - Creating virtualenv /builds/slave/test/build/venv 22:06:20 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 22:06:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:06:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:06:20 INFO - Using real prefix '/tools/python27' 22:06:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:06:21 INFO - Installing distribute.............................................................................................................................................................................................done. 22:06:24 INFO - Installing pip.................done. 22:06:24 INFO - Return code: 0 22:06:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:06:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:06:24 INFO - Reading from file tmpfile_stdout 22:06:24 INFO - Output received: 22:06:24 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) 22:06:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:06:24 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')]} 22:06:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:24 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')]} 22:06:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:24 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 0x1046f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadcbdaed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450cd30>, '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 0x104372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104504b20>, '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.6WFMISYXhL/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.aSyxBcn6jl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 22:06:24 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 22:06:24 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 22:06:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6WFMISYXhL/Render', 22:06:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:24 INFO - 'HOME': '/Users/cltbld', 22:06:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:24 INFO - 'LOGNAME': 'cltbld', 22:06:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:24 INFO - 'MOZ_NO_REMOTE': '1', 22:06:24 INFO - 'NO_EM_RESTART': '1', 22:06:24 INFO - 'PAGER': '/bin/cat', 22:06:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:24 INFO - 'PWD': '/builds/slave/test', 22:06:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:24 INFO - 'SHELL': '/bin/bash', 22:06:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSyxBcn6jl/Listeners', 22:06:24 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 22:06:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:24 INFO - 'USER': 'cltbld', 22:06:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:24 INFO - 'XPC_FLAGS': '0x0', 22:06:24 INFO - 'XPC_SERVICE_NAME': '0', 22:06:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:24 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 22:06:24 INFO - Cleaning up... 22:06:24 INFO - Return code: 0 22:06:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:06:24 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')]} 22:06:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:24 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')]} 22:06:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:24 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 0x1046f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadcbdaed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450cd30>, '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 0x104372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104504b20>, '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.6WFMISYXhL/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.aSyxBcn6jl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 22:06:24 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 22:06:24 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 22:06:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6WFMISYXhL/Render', 22:06:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:24 INFO - 'HOME': '/Users/cltbld', 22:06:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:24 INFO - 'LOGNAME': 'cltbld', 22:06:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:24 INFO - 'MOZ_NO_REMOTE': '1', 22:06:24 INFO - 'NO_EM_RESTART': '1', 22:06:24 INFO - 'PAGER': '/bin/cat', 22:06:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:24 INFO - 'PWD': '/builds/slave/test', 22:06:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:24 INFO - 'SHELL': '/bin/bash', 22:06:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSyxBcn6jl/Listeners', 22:06:24 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 22:06:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:24 INFO - 'USER': 'cltbld', 22:06:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:24 INFO - 'XPC_FLAGS': '0x0', 22:06:24 INFO - 'XPC_SERVICE_NAME': '0', 22:06:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:25 INFO - Downloading/unpacking psutil>=3.1.1 22:06:25 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 22:06:25 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 22:06:25 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 22:06:25 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 22:06:25 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 22:06:25 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 22:06:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:06:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:06:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:06:28 INFO - Installing collected packages: psutil 22:06:28 INFO - Running setup.py install for psutil 22:06:28 INFO - building 'psutil._psutil_osx' extension 22:06:28 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 22:06:29 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 22:06:29 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 22:06:29 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 22:06:29 INFO - building 'psutil._psutil_posix' extension 22:06:29 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 22:06:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:06:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:06:29 INFO - ^ 22:06:29 INFO - 1 warning generated. 22:06:29 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 22:06:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:06:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:06:29 INFO - Successfully installed psutil 22:06:29 INFO - Cleaning up... 22:06:29 INFO - Return code: 0 22:06:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:06:29 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')]} 22:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:29 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')]} 22:06:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:29 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 0x1046f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadcbdaed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450cd30>, '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 0x104372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104504b20>, '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.6WFMISYXhL/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.aSyxBcn6jl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 22:06:29 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 22:06:29 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 22:06:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6WFMISYXhL/Render', 22:06:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:29 INFO - 'HOME': '/Users/cltbld', 22:06:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:29 INFO - 'LOGNAME': 'cltbld', 22:06:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:29 INFO - 'MOZ_NO_REMOTE': '1', 22:06:29 INFO - 'NO_EM_RESTART': '1', 22:06:29 INFO - 'PAGER': '/bin/cat', 22:06:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:29 INFO - 'PWD': '/builds/slave/test', 22:06:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:29 INFO - 'SHELL': '/bin/bash', 22:06:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSyxBcn6jl/Listeners', 22:06:29 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 22:06:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:29 INFO - 'USER': 'cltbld', 22:06:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:29 INFO - 'XPC_FLAGS': '0x0', 22:06:29 INFO - 'XPC_SERVICE_NAME': '0', 22:06:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:06:29 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 22:06:29 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 22:06:29 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 22:06:29 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 22:06:29 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 22:06:29 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 22:06:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:06:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:06:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:06:32 INFO - Installing collected packages: mozsystemmonitor 22:06:32 INFO - Running setup.py install for mozsystemmonitor 22:06:32 INFO - Successfully installed mozsystemmonitor 22:06:32 INFO - Cleaning up... 22:06:32 INFO - Return code: 0 22:06:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:06: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')]} 22:06:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06: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')]} 22:06:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:32 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 0x1046f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadcbdaed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450cd30>, '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 0x104372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104504b20>, '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.6WFMISYXhL/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.aSyxBcn6jl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 22:06:32 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 22:06:32 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 22:06:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6WFMISYXhL/Render', 22:06:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:32 INFO - 'HOME': '/Users/cltbld', 22:06:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:32 INFO - 'LOGNAME': 'cltbld', 22:06:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:32 INFO - 'MOZ_NO_REMOTE': '1', 22:06:32 INFO - 'NO_EM_RESTART': '1', 22:06:32 INFO - 'PAGER': '/bin/cat', 22:06:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:32 INFO - 'PWD': '/builds/slave/test', 22:06:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:32 INFO - 'SHELL': '/bin/bash', 22:06:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSyxBcn6jl/Listeners', 22:06:32 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 22:06:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:32 INFO - 'USER': 'cltbld', 22:06:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:32 INFO - 'XPC_FLAGS': '0x0', 22:06:32 INFO - 'XPC_SERVICE_NAME': '0', 22:06:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:32 INFO - Downloading/unpacking jsonschema==2.5.1 22:06:32 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 22:06:32 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 22:06:32 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 22:06:32 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 22:06:32 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 22:06:32 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 22:06:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:06:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:06:35 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 22:06:35 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 22:06:35 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 22:06:35 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 22:06:35 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 22:06:35 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 22:06:35 INFO - Downloading functools32-3.2.3-2.tar.gz 22:06:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:06:35 INFO - warning: no files found matching '*.txt' 22:06:35 INFO - no previously-included directories found matching 'build' 22:06:35 INFO - no previously-included directories found matching 'dist' 22:06:35 INFO - no previously-included directories found matching '.git*' 22:06:35 INFO - Installing collected packages: jsonschema, functools32 22:06:35 INFO - Running setup.py install for functools32 22:06:36 INFO - warning: no files found matching '*.txt' 22:06:36 INFO - no previously-included directories found matching 'build' 22:06:36 INFO - no previously-included directories found matching 'dist' 22:06:36 INFO - no previously-included directories found matching '.git*' 22:06:36 INFO - Successfully installed jsonschema functools32 22:06:36 INFO - Cleaning up... 22:06:36 INFO - Return code: 0 22:06:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:06:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:06:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:06:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1046f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadcbdaed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450cd30>, '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 0x104372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104504b20>, '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.6WFMISYXhL/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.aSyxBcn6jl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 22:06:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:06:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:06:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6WFMISYXhL/Render', 22:06:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:36 INFO - 'HOME': '/Users/cltbld', 22:06:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:36 INFO - 'LOGNAME': 'cltbld', 22:06:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:36 INFO - 'MOZ_NO_REMOTE': '1', 22:06:36 INFO - 'NO_EM_RESTART': '1', 22:06:36 INFO - 'PAGER': '/bin/cat', 22:06:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:36 INFO - 'PWD': '/builds/slave/test', 22:06:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:36 INFO - 'SHELL': '/bin/bash', 22:06:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSyxBcn6jl/Listeners', 22:06:36 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 22:06:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:36 INFO - 'USER': 'cltbld', 22:06:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:36 INFO - 'XPC_FLAGS': '0x0', 22:06:36 INFO - 'XPC_SERVICE_NAME': '0', 22:06:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:06:36 INFO - Cleaning up... 22:06:36 INFO - Return code: 0 22:06:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:06:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:06:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:06:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1046f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadcbdaed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450cd30>, '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 0x104372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104504b20>, '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.6WFMISYXhL/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.aSyxBcn6jl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 22:06:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:06:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:06:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6WFMISYXhL/Render', 22:06:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:36 INFO - 'HOME': '/Users/cltbld', 22:06:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:36 INFO - 'LOGNAME': 'cltbld', 22:06:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:36 INFO - 'MOZ_NO_REMOTE': '1', 22:06:36 INFO - 'NO_EM_RESTART': '1', 22:06:36 INFO - 'PAGER': '/bin/cat', 22:06:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:36 INFO - 'PWD': '/builds/slave/test', 22:06:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:36 INFO - 'SHELL': '/bin/bash', 22:06:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSyxBcn6jl/Listeners', 22:06:36 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 22:06:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:36 INFO - 'USER': 'cltbld', 22:06:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:36 INFO - 'XPC_FLAGS': '0x0', 22:06:36 INFO - 'XPC_SERVICE_NAME': '0', 22:06:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:36 INFO - Downloading/unpacking blobuploader==1.2.4 22:06:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:39 INFO - Downloading blobuploader-1.2.4.tar.gz 22:06:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:06:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:06:39 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 22:06:39 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 22:06:39 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 22:06:39 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 22:06:39 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 22:06:39 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 22:06:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:06:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:06:39 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 22:06:39 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 22:06:39 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 22:06:39 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 22:06:39 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 22:06:39 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 22:06:40 INFO - Downloading docopt-0.6.1.tar.gz 22:06:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:06:40 INFO - Installing collected packages: blobuploader, requests, docopt 22:06:40 INFO - Running setup.py install for blobuploader 22:06:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:06:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:06:40 INFO - Running setup.py install for requests 22:06:41 INFO - Running setup.py install for docopt 22:06:41 INFO - Successfully installed blobuploader requests docopt 22:06:41 INFO - Cleaning up... 22:06:41 INFO - Return code: 0 22:06:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:06:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:06:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:41 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 0x1046f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadcbdaed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450cd30>, '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 0x104372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104504b20>, '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.6WFMISYXhL/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.aSyxBcn6jl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 22:06:41 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 22:06:41 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 22:06:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6WFMISYXhL/Render', 22:06:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:41 INFO - 'HOME': '/Users/cltbld', 22:06:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:41 INFO - 'LOGNAME': 'cltbld', 22:06:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:41 INFO - 'MOZ_NO_REMOTE': '1', 22:06:41 INFO - 'NO_EM_RESTART': '1', 22:06:41 INFO - 'PAGER': '/bin/cat', 22:06:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:41 INFO - 'PWD': '/builds/slave/test', 22:06:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:41 INFO - 'SHELL': '/bin/bash', 22:06:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSyxBcn6jl/Listeners', 22:06:41 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 22:06:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:41 INFO - 'USER': 'cltbld', 22:06:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:41 INFO - 'XPC_FLAGS': '0x0', 22:06:41 INFO - 'XPC_SERVICE_NAME': '0', 22:06:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:06:41 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-lkkpZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:06:41 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-qDoRM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:06:41 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-vGHyal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:06:41 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-ghmHW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:06:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-b1zewx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:06:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-1nByG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:06:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-R3M8AS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:06:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-jly3wu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:06:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-Kw3P4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:06:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-zBVo7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:06:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-viq9AP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:06:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-Zv9nyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:06:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-3o000L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:06:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-Rftm3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:43 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-q9cvuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:06:43 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-PkFlYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:06:43 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-DzPIBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:06:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:06:43 INFO - Running setup.py install for manifestparser 22:06:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:06:43 INFO - Running setup.py install for mozcrash 22:06:43 INFO - Running setup.py install for mozdebug 22:06:43 INFO - Running setup.py install for mozdevice 22:06:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:06:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:06:43 INFO - Running setup.py install for mozfile 22:06:44 INFO - Running setup.py install for mozhttpd 22:06:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:06:44 INFO - Running setup.py install for mozinfo 22:06:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:06:44 INFO - Running setup.py install for mozInstall 22:06:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:06:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:06:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:06:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:06:44 INFO - Running setup.py install for mozleak 22:06:44 INFO - Running setup.py install for mozlog 22:06:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:06:44 INFO - Running setup.py install for moznetwork 22:06:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:06:44 INFO - Running setup.py install for mozprocess 22:06:45 INFO - Running setup.py install for mozprofile 22:06:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:06:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:06:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:06:45 INFO - Running setup.py install for mozrunner 22:06:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:06:45 INFO - Running setup.py install for mozscreenshot 22:06:45 INFO - Running setup.py install for moztest 22:06:45 INFO - Running setup.py install for mozversion 22:06:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:06:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:06:45 INFO - Cleaning up... 22:06:45 INFO - Return code: 0 22:06:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:06:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:45 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 0x1046f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadcbdaed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450cd30>, '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 0x104372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104504b20>, '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.6WFMISYXhL/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.aSyxBcn6jl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 22:06:45 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 22:06:45 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 22:06:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6WFMISYXhL/Render', 22:06:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:45 INFO - 'HOME': '/Users/cltbld', 22:06:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:45 INFO - 'LOGNAME': 'cltbld', 22:06:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:45 INFO - 'MOZ_NO_REMOTE': '1', 22:06:45 INFO - 'NO_EM_RESTART': '1', 22:06:45 INFO - 'PAGER': '/bin/cat', 22:06:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:45 INFO - 'PWD': '/builds/slave/test', 22:06:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:45 INFO - 'SHELL': '/bin/bash', 22:06:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSyxBcn6jl/Listeners', 22:06:45 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 22:06:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:45 INFO - 'USER': 'cltbld', 22:06:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:45 INFO - 'XPC_FLAGS': '0x0', 22:06:45 INFO - 'XPC_SERVICE_NAME': '0', 22:06:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:06:46 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-l3layP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:06:46 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)) 22:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:06:46 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-RMdnK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:06:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:06:46 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-gXoIAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:06:46 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)) 22:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:06:46 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-8Jasce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:06:46 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)) 22:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:06:46 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-a7ArG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:06:46 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)) 22:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:06:46 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-E4Doau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:06:46 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)) 22:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:06:46 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-7UCfUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:06:46 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)) 22:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:06:46 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-XnxziV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:06:47 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)) 22:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:06:47 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-TNuHpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:06:47 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)) 22:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:06:47 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-ZSjaGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:06:47 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)) 22:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:06:47 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-j6dYpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:06:47 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)) 22:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:06:47 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-VYOQUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:06:47 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)) 22:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:06:47 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-msZ_oh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:06:47 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)) 22:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:06:47 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-9svEak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:47 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-0g8Sta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:47 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)) 22:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:06:47 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-j9Sp80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:06:47 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)) 22:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:06:47 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-EhGMGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:06:47 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)) 22:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:06:47 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)) 22:06:47 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)) 22:06:47 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)) 22:06:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:50 INFO - Downloading blessings-1.6.tar.gz 22:06:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:06:50 INFO - Installing collected packages: blessings 22:06:50 INFO - Running setup.py install for blessings 22:06:50 INFO - Successfully installed blessings 22:06:50 INFO - Cleaning up... 22:06:50 INFO - Return code: 0 22:06:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:06:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:06:51 INFO - Reading from file tmpfile_stdout 22:06:51 INFO - Current package versions: 22:06:51 INFO - blessings == 1.6 22:06:51 INFO - blobuploader == 1.2.4 22:06:51 INFO - docopt == 0.6.1 22:06:51 INFO - functools32 == 3.2.3-2 22:06:51 INFO - jsonschema == 2.5.1 22:06:51 INFO - manifestparser == 1.1 22:06:51 INFO - mozInstall == 1.12 22:06:51 INFO - mozcrash == 1.0 22:06:51 INFO - mozdebug == 0.1 22:06:51 INFO - mozdevice == 0.48 22:06:51 INFO - mozfile == 1.2 22:06:51 INFO - mozhttpd == 0.7 22:06:51 INFO - mozinfo == 0.9 22:06:51 INFO - mozleak == 0.1 22:06:51 INFO - mozlog == 3.4 22:06:51 INFO - moznetwork == 0.27 22:06:51 INFO - mozprocess == 0.23 22:06:51 INFO - mozprofile == 0.28 22:06:51 INFO - mozrunner == 6.13 22:06:51 INFO - mozscreenshot == 0.1 22:06:51 INFO - mozsystemmonitor == 0.3 22:06:51 INFO - moztest == 0.7 22:06:51 INFO - mozversion == 1.4 22:06:51 INFO - psutil == 3.1.1 22:06:51 INFO - requests == 1.2.3 22:06:51 INFO - wsgiref == 0.1.2 22:06:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06:51 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')]} 22:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:51 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')]} 22:06:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:51 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 0x1046f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadcbdaed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450cd30>, '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 0x104372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104504b20>, '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.6WFMISYXhL/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.aSyxBcn6jl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 22:06:51 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 22:06:51 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 22:06:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6WFMISYXhL/Render', 22:06:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:51 INFO - 'HOME': '/Users/cltbld', 22:06:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:51 INFO - 'LOGNAME': 'cltbld', 22:06:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:51 INFO - 'MOZ_NO_REMOTE': '1', 22:06:51 INFO - 'NO_EM_RESTART': '1', 22:06:51 INFO - 'PAGER': '/bin/cat', 22:06:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:51 INFO - 'PWD': '/builds/slave/test', 22:06:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:51 INFO - 'SHELL': '/bin/bash', 22:06:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSyxBcn6jl/Listeners', 22:06:51 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 22:06:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:51 INFO - 'USER': 'cltbld', 22:06:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:51 INFO - 'XPC_FLAGS': '0x0', 22:06:51 INFO - 'XPC_SERVICE_NAME': '0', 22:06:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:51 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)) 22:06:51 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)) 22:06:51 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)) 22:06:51 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)) 22:06:51 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)) 22:06:51 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)) 22:06:51 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)) 22:06:51 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)) 22:06:51 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)) 22:06:51 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)) 22:06:51 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)) 22:06:51 INFO - Cleaning up... 22:06:51 INFO - Return code: 0 22:06:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:06:51 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')]} 22:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:51 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')]} 22:06:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1046f8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadcbdaed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450cd30>, '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 0x104372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104504b20>, '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.6WFMISYXhL/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.aSyxBcn6jl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 22:06: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', 'jsonschema'] in /builds/slave/test/build 22:06: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 jsonschema 22:06:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6WFMISYXhL/Render', 22:06:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:06:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:06:52 INFO - 'HOME': '/Users/cltbld', 22:06:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:06:52 INFO - 'LOGNAME': 'cltbld', 22:06:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:52 INFO - 'MOZ_NO_REMOTE': '1', 22:06:52 INFO - 'NO_EM_RESTART': '1', 22:06:52 INFO - 'PAGER': '/bin/cat', 22:06:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:06:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:52 INFO - 'PWD': '/builds/slave/test', 22:06:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:06:52 INFO - 'SHELL': '/bin/bash', 22:06:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSyxBcn6jl/Listeners', 22:06:52 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 22:06:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:06:52 INFO - 'USER': 'cltbld', 22:06:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:06:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:06:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:52 INFO - 'XPC_FLAGS': '0x0', 22:06:52 INFO - 'XPC_SERVICE_NAME': '0', 22:06:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:06:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:06:52 INFO - Cleaning up... 22:06:52 INFO - Return code: 0 22:06:52 INFO - Running post-action listener: _resource_record_post_action 22:06:52 INFO - Running post-action listener: _start_resource_monitoring 22:06:52 INFO - Starting resource monitoring. 22:06:52 INFO - [mozharness: 2016-11-15 06:06:52.497140Z] Finished create-virtualenv step (success) 22:06:52 INFO - [mozharness: 2016-11-15 06:06:52.498193Z] Running install step. 22:06:52 INFO - Running pre-action listener: _resource_record_pre_action 22:06:52 INFO - Running main action method: install 22:06:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:06:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:06:52 INFO - Reading from file tmpfile_stdout 22:06:52 INFO - Detecting whether we're running mozinstall >=1.0... 22:06:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:06:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:06:52 INFO - Reading from file tmpfile_stdout 22:06:52 INFO - Output received: 22:06:52 INFO - Usage: mozinstall [options] installer 22:06:52 INFO - Options: 22:06:52 INFO - -h, --help show this help message and exit 22:06:52 INFO - -d DEST, --destination=DEST 22:06:52 INFO - Directory to install application into. [default: 22:06:52 INFO - "/builds/slave/test"] 22:06:52 INFO - --app=APP Application being installed. [default: firefox] 22:06:52 INFO - mkdir: /builds/slave/test/build/application 22:06:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:06:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 22:07:24 INFO - Reading from file tmpfile_stdout 22:07:24 INFO - Output received: 22:07:24 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 22:07:24 INFO - Running post-action listener: _resource_record_post_action 22:07:24 INFO - [mozharness: 2016-11-15 06:07:24.752122Z] Finished install step (success) 22:07:24 INFO - [mozharness: 2016-11-15 06:07:24.752266Z] Running run-tests step. 22:07:24 INFO - Running pre-action listener: _resource_record_pre_action 22:07:24 INFO - Running main action method: run_tests 22:07:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:07:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:07:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:07:24 INFO - 2016-11-14 22:07:24.766 screenresolution[1625:7338] starting screenresolution argv=screenresolution get 22:07:24 INFO - 2016-11-14 22:07:24.769 screenresolution[1625:7338] Display 0: 1600x1200x32@60 22:07:24 INFO - 2016-11-14 22:07:24.775 screenresolution[1626:7340] starting screenresolution argv=screenresolution list 22:07:24 INFO - Available Modes on Display 0 22:07:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:07:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:07:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:07:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:07:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:07:25 INFO - Intel Iris: 22:07:25 INFO - Chipset Model: Intel Iris 22:07:25 INFO - Type: GPU 22:07:25 INFO - Bus: Built-In 22:07:25 INFO - VRAM (Dynamic, Max): 1536 MB 22:07:25 INFO - Vendor: Intel (0x8086) 22:07:25 INFO - Device ID: 0x0a2e 22:07:25 INFO - Revision ID: 0x0009 22:07:25 INFO - Displays: 22:07:25 INFO - Display: 22:07:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:07:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:07:25 INFO - Display Serial Number: 1600x1200 60 22:07:25 INFO - Main Display: Yes 22:07:25 INFO - Mirror: Off 22:07:25 INFO - Online: Yes 22:07:25 INFO - Rotation: Supported 22:07:25 INFO - Return code: 0 22:07:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:07:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:07:25 INFO - Python 2.7.3 22:07:25 INFO - Return code: 0 22:07:25 INFO - grabbing minidump binary from tooltool 22:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:07:25 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 0x10450cd30>, '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 0x104372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104504b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:07:25 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 22:07:25 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 22:07:25 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 22:07:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:07:25 INFO - Return code: 0 22:07:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:07:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:07:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:07:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:07:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:07:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0269', '--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 22:07:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0269 --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 22:07:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6WFMISYXhL/Render', 22:07:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:07:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:07:25 INFO - 'HOME': '/Users/cltbld', 22:07:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:07:25 INFO - 'LOGNAME': 'cltbld', 22:07:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:07:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:07:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:07:25 INFO - 'MOZ_NO_REMOTE': '1', 22:07:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:07:25 INFO - 'NO_EM_RESTART': '1', 22:07:25 INFO - 'PAGER': '/bin/cat', 22:07:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:07:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:07:25 INFO - 'PWD': '/builds/slave/test', 22:07:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:07:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:07:25 INFO - 'SHELL': '/bin/bash', 22:07:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSyxBcn6jl/Listeners', 22:07:25 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 22:07:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:07:25 INFO - 'USER': 'cltbld', 22:07:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:07:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:07:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:07:25 INFO - 'XPC_FLAGS': '0x0', 22:07:25 INFO - 'XPC_SERVICE_NAME': '0', 22:07:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:07:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0269', '--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 22:07:25 INFO - mozversion application_buildid: 20161114194413 22:07:25 INFO - mozversion application_changeset: aeb8bcaafe56ee681cf19a13398cd14d163af6ca 22:07:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:07:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:07:25 INFO - mozversion application_name: Firefox 22:07:25 INFO - mozversion application_remotingname: firefox-dev 22:07:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:07:25 INFO - mozversion application_vendor: Mozilla 22:07:25 INFO - mozversion application_version: 52.0a2 22:07:25 INFO - mozversion platform_buildid: 20161114194413 22:07:25 INFO - mozversion platform_changeset: aeb8bcaafe56ee681cf19a13398cd14d163af6ca 22:07:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:07:25 INFO - mozversion platform_version: 52.0a2 22:07:25 INFO - using testdate: 1479190045 22:07:25 INFO - actual date: 1479190045 22:07:25 INFO - starting webserver on 'localhost:49237' 22:07:25 INFO - SUITE-START | Running 2 tests 22:07:25 INFO - TEST-START | dromaeo_css 22:07:25 INFO - operating with platform_type : mac_ 22:07:25 INFO - Initialising browser for dromaeo_css test... 22:07:25 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/tmp6XOSpQ/profile http://localhost:49237/getInfo.html) 22:07:27 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 22:07:27 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 22:07:27 INFO - PROCESS | 1636 | colorDepth:24 22:07:27 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 22:07:27 INFO - PROCESS | 1636 | __metrics 22:07:27 INFO - PROCESS | 1636 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:07:27 INFO - PROCESS | 1636 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:07:28 INFO - TEST-INFO | 1636: exit 0 22:07:28 INFO - Browser initialized. 22:07:28 INFO - Running cycle 1/1 for dromaeo_css test... 22:07:28 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/tmp6XOSpQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:07:40 INFO - PROCESS | 1638 | RSS: Main: 217464832 22:07:40 INFO - PROCESS | 1638 | 22:08:31 INFO - PROCESS | 1638 | 1479190111762 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] 22:10:46 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49237/tests/dromaeo/cssquery-ext.html) 22:10:48 INFO - PROCESS | 1638 | RSS: Main: 408260608 22:10:48 INFO - PROCESS | 1638 | 22:13:51 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-ext.html (next: http://localhost:49237/tests/dromaeo/cssquery-jquery.html) 22:13:52 INFO - PROCESS | 1638 | RSS: Main: 354590720 22:13:52 INFO - PROCESS | 1638 | 22:16:54 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49237/tests/dromaeo/cssquery-mootools.html) 22:16:55 INFO - PROCESS | 1638 | RSS: Main: 348495872 22:16:55 INFO - PROCESS | 1638 | 22:19:57 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49237/tests/dromaeo/cssquery-prototype.html) 22:19:58 INFO - PROCESS | 1638 | RSS: Main: 328114176 22:19:58 INFO - PROCESS | 1638 | 22:23:01 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49237/tests/dromaeo/cssquery-yui.html) 22:23:02 INFO - PROCESS | 1638 | RSS: Main: 311353344 22:23:02 INFO - PROCESS | 1638 | 22:26:05 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-yui.html (next: http://localhost:49237/tests/dromaeo/cssquery-dojo.html) 22:26:05 INFO - PROCESS | 1638 | RSS: Main: 458469376 22:26:05 INFO - PROCESS | 1638 | 22:26:05 INFO - PROCESS | 1638 | __start_tp_report 22:26:05 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 22:26:05 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 22:26:05 INFO - PROCESS | 1638 | |i|pagename|runs| 22:26:05 INFO - PROCESS | 1638 | |0;dojo.html;3350.4136776064192;3354.580677415323;3376.966230337699;3384.4754063120226;3424.520567120602;5377.300950876381;5729.255196824417;5757.3379061407895;5803.100519419491;5822.214001109844;4377.649788016959;5777.566682498819;5813.09896965971;5834.095715164146;5893.823185304437;5221.660592061513;5254.684718916858;5256.132738098209;5289.841304760844;5332.786688532454;5196.714180720059;5208.947910520903;5209.400918894337;5277.419483856773;5384.488473595002;5272.894542109156;5298.947010529884;5302.072137375963;5309.920351194735;5315.0432922074015;5305.840824775224;5306.151015837486;5308.814191503298;5309.946900530984;5321.308229930123;5122.974385128088;5234.398044224925;5242.711650859202;5245.659032162897;5277.23480095384;5807.912881306784;5808.506276966463;5818.0981947798155;5821.650700957956;5826.067829147359;6146.477549408306;6184.56708030438;6184.938150618526;6190.257169139687;6196.87606247877;6302.149209856657;6311.905321420182;6313.431791138773;6357.745690172387;6357.96821015892;4788.856334309976;5832.795852145155;5861.450269132941;5896.882062358772;5930.644161350333;6309.14826498426;6325.810225693191;6325.810225693236;6362.745490180433;6371.713272902731;4977;6111.895622426848;6113.902014424272;6139.075791860876;6173.660448675366;6316.084167795736;6316.30520642729;6339.2392912850755;6340.144080549159;6340.492760579143;6167.475764559973;6279.560430769893;6300.21247344082;6312.52656050798;6315.494760419156;6231.189945307081;6241.843953901189;6266.091416744546;6273.278572964076;6285.717142728497;4948.158813001798;4949.059678661115;4951.925721114186;4952.777125029383;4979.203327467588;4406.228909940747;4444.8444304449185;4477.7984990675495;4496.642958181417;4506.459224893034;5763.9135412968835;5772.682502462321;5844.590878638454;5846.590738648254;5863.501602363762;4993.725345106054;6270.9372906271265;6304.306526282106;6340.080688300263;6344.809655710336;5921.911171332434;5926.585139040226;5940.495057920039;5949.137375080676;5991.7603295867675;5425.294711687456;5448.727563621803;5455.699936503451;5477.808276710296;5506;6284.937150628618;6347.984322508377;6350.364963503613;6354.650494222862;6370.267419246846;6302.779402720883;6332.810015699536;6368.267649220399;6380.87238255223;6387.840303992437;6275.592086514362;6323.557350985571;6334.968325158345;6341.55609107358;6342.492600592034;6123.387661233841;6266.404691554295;6267.905981410191;6282.717277722422;6285.905711414424;6237.345078766845;6280.842978925563;6316.6841657916;6326.525510586633;6326.9683651581445;6219.067139928866;6221.035739460391;6224.906626400698;6240.344763799736;6249.687515624291;6012.6392416455155;6168.383161683796;6173.41352571505;6198.039303907902;6245.875082498415;5882.1470886722045;5930.28836539618;5930.822075337746;6014;6074.271087469532;6165.383461653978;6189.783357582463;6215.627062376272;6225.782097626561;6230.563860529901;5815.82552523425;5889.411058894077;5895.587308888336;5975.073863551156;6053.727582258897 22:26:05 INFO - PROCESS | 1638 | |1;ext.html;450.2346011779974;477.2673945493666;517.521292804156;522.7281813457001;534.8636097795064;20832.68750968735;37564.812175939114;38346.65786697466;38385.84842454731;39686.40470392947;38941.80529097358;39022;39504.407433888446;39526.40710389346;39552;9165.083491650816;9211.815763684723;9328.953355233249;9337.299702522305;9350.859737103949;9131.954340228289;9177.495237761921;9282.489463079528;9301.720948371525;9307.674231401903;9301.813963720722;9302.348835581512;9307.976122626505;9315.115064068903;9319.254459643245;9134.725958221197;9205.493697846616;9276.304277179172;9286.117818807203;9296.070392960717;9078.319126065571;9153.954230228874;9154.86267705985;9184.494852783095;9189;37351;37635.247295053945;37671.4349284761;37679.623203767886;37689.434658480146;2976.4939960206816;3313.1385839681616;3706.981465092684;3716.832742526593;3858.305505009097;27436.862815685992;27638;29111.41777164466;29698.257543561584;29932.40135197284;3150.6376766671897;3152.3537674776617;3170.3342298117423;3244.0754385000164;3252.9186770330693;477035;479961;482272.9999999965;482440;490627;31877.362452750815;32099.679003210134;32570.34859302848;54394;54553.45446545563;33187;33305.333893322;33316.66683333136;33323.3335333292;33352;14162.866970642324;14309.998300118892;14315.78526322106;14322.212278324583;14356.995010349176;12166.87833121675;12255.345528353559;12260;12261.38693065361;12287.56993505223;7484.925150748423;7541.773746787605;7544.3210111089165;7557.6221188940335;7617.809554761176;3110.1913502489515;3135.4356215880903;3168.8732450702173;3174.539691744684;3211.6788321167696;886.4061079077034;975.0931633580838;994.1500017485091;1012.8126296635146;1035.5598870480167;28384.438855862714;28681.414823147108;28685.28286792847;29053.709462905525;29323.413531729246;1002.3334482569155;1071.0681706915034;1072.3083611070972;1074.2856000759564;1088.1077516436603;14811.629709257355;14889.404423822925;15075.321610527333;15129.621759455882;15266.847331526764;636181;643188;645597;647977;648366;53768.462315377124;54242.728786355816;54339;54345.18482222691;54476.182857257176;1172.5485688010178;1196.3599474281218;1233.6977440527128;1308.4504508106809;1352.6280272925023;28909.710902891544;29592.852035739685;29827.85086074556;30213.09360719182;30339.544906825973;45520.54479455295;46045.53954460412;46109.53890461053;46135.846603835176;46207.84480388017;426430;507579;511260;512066;512089;24204.39489012789;25327.240182794547;25344.619830702184;25373.365665858502;25385.365365866;43870.34194487021;44178;44262.336064959694;44302;44832.10335793331;36376.454353185254;36905.44641830429;37267;38565;38720.03199920025;36624.81687591545;36923.261534768615;37026.44460333151;37178.8141059293;37542.624573755005;36419.27161456809;37001.25997479982;37206.44190337093;37266.8136659315;37269.88190354294;31798.20504487314;33246.335073298884;33318.50022249619;33442.16394590155;33455.83272083624;24133.75866241315;24376;24484;24943.87528062348;25022.37444063913 22:26:05 INFO - PROCESS | 1638 | |2;jquery.html;296.2504862697376;324.5148502988033;366.6754921894123;375.5512162965256;399.070166512027;14384.993050486457;14786.778198327034;15282.465113721008;15517.456889008887;19523.12145953432;19071.713924291147;19190.712139317922;19820.900895495502;19900.40298791035;20022.49943751407;5204.42751297357;5633.183188437684;5991.910121348184;6024.457798798107;6032.487238584726;5006.699598024112;5605.7197140143035;5821.27234095738;5878.970605146989;5950.5834591578605;4985.875353116165;5823.446772556622;5829.009068458372;5833.79581714638;5834.795782147626;5698.088305871082;5804.361520232771;5824.650520968734;5831.970840145815;5902.173695682608;5142.768575414116;5143.434222235551;5158.509941555546;5159.793608255665;5165.612579056548;20863.37409877706;23739.881300593388;24904.50190996188;25084.749152508422;25297;2569.781568566674;2626.8423894566386;3032.95767816399;3062.9234269143226;3095;14755.11469311834;15145.318460669296;15173.848261517463;15270.923645381814;15596.220188990505;1790.274938649849;1818.0909545227387;1830.9267629294811;1839.611093624305;1854.1378259109445;87394;92215.53892230496;92222;114411;114682.27976580359;8705.521196334263;10120.342177758423;10380.377177369382;10589.947050264853;11294.548218071184;11437.37094459796;13733.176009439465;13782.65543361424;13805.378757955916;13813.930930345485;7742.535447873146;7956.761297161094;8018.5990700464745;8136.918630813734;8159.224873637114;6126.417990290915;8258;8277.8344433111;8389.07720150783;8444.493330400195;3828.789416582059;3997.420374045747;4184.121334519785;4376.452943382077;4387.824487020547;1792.5518620344915;1857.3313607101434;1864.850811935042;1910.961780764377;1911.4838993471685;384.2602989245691;824.6783754335814;869.0787764969026;889.9143045484499;894.6515397082658;11013.724656883642;11596.594119205787;11633.65099047013;11840;11993.580224692094;424.96600271978167;426.6608046602958;438.0297640725744;439.4111890067325;460.61538615255967;5796.739146738407;5798.710064496759;6051.4553690168095;6234.688265586702;6257.812265632038;579737;580155;595670;602969;603556;121978;124347;125769.74230257764;128013.71986280018;130427.69572304345;423.04814168121726;438.2330920888445;439.8020890599243;440.0363204581955;440.46483522520293;10776.838347424638;10923.344599324066;10929.508172132115;11002.669919902415;11021.338719676845;47924.5207547934;48608.270875936185;49615.50384496109;50822.745886270335;51891;57830.421695782505;68703;69824.65087674733;75119.24880751122;75185;7101.2898710128575;7826.018016589479;7882.5270483771155;7915.168907264654;8358.665653373797;10926.344419334866;12334.50661973511;12542.561010364594;12734.490620375082;12790.872091278969;10521.526531306263;10696.1977851663;10710.303830251009;10958.780824383308;11018.393988330397;10669.35983840972;10737.409442480739;10803.351798892092;11100.11199104086;11255.324680519196;5003.499650035113;9990.400575965465;10761.408122553335;11140.944295278472;11546.480408381474;7885.881711774213;7887.487313324616;7999.840003199787;8192.959035204785;8364.958175209085;8084.070331911906;10207;10755.08581770527;10931.78136437251;10995.230333876552 22:26:05 INFO - PROCESS | 1638 | |3;mootools.html;1481.8073650425442;1502.917339546325;1635.2641311409868;1780.5014595913149;1804.4676820338007;8073.515589064646;8190.344772418207;8613.741587752369;8623.094575069606;8630.48217106974;7292.927070729291;7634.122075961266;7821.608919554028;8109.391795615337;8362.707305244317;2298.5173113646156;2337.836351455399;2338.3218866528687;2341.66045923341;2342.039763696876;2338.8479748816358;2357.316378250305;2364.6571247169236;2396.4847557775074;2398.70016247969;2324.5118525109665;2335.614623587106;2350.4946436516148;2354.458474550854;2362.031567057507;2301.309607117858;2302.9884850575722;2314.9189778357677;2316.339843144704;2327.918522851709;2350.3771500552366;2370.8221883358647;2370.9407264818346;2379.1316169598144;2380.0955636858057;7429.665665045032;8508.234258916727;8563.657453701844;8572.828543429096;8582.013068497077;5105.795768169269;5275.762590683428;5292.153255479144;5294.444083371229;5300.655457395295;3239.7084262416497;3391.372596069735;3615.096225943514;3622.855085796565;3631.455281707739;1068.8770791359016;1069.743261617214;1091.8471414002047;1109.9667009989714;1112.1825458288151;11050.502727377287;11114.22200445969;11228.382438965853;11251.662450126509;11272.380019098946;3704.6480584344445;4625.398698169217;4730.550597693214;4741.5495527924795;4807.759612019385;9617.951910240545;9621.422714637145;9623.085806848478;9632;9636.903630963741;4449.354843547732;5262.342207224097;5264.447233040552;5269.288646032813;5269.446708095671;4280.785960701953;4541.772911354486;4774.116788394089;4830.951690483121;4852.199387101089;3811.866584669523;3827.5981021992848;3877.747946383476;3929.724919255682;3938.6061393860386;1245.5391505143155;1246.2896149194871;1247.6319485751544;1263.8041103628952;1269.238456925837;868.6199812513667;882;882.4881568690145;904.841652710773;915.9969833032951;5134.743262836918;5142.511461411234;5330.120530112488;5421.972890135524;5439.864003399946;429.89897374117055;434.41137259014215;448.89795551919957;453.51926957425235;467.90174063447057;3781.0736369589627;3800.4489349044234;3847.8076096195077;3851.7322328384184;3885.8445662173194;9887.357321774061;10427.322224055411;10437;10441.321314114559;10454.215933804842;10357.274990750726;10396.792064158675;10396.792064158675;10396.792064158675;10401.375917444977;7982.4412291140225;8039.2764651180505;8046.074701409413;8065.435419520694;8111.837763244702;5417.674939503642;5525.060739674345;5593.384727679871;5686.289213848269;5688.5733569981585;9291.953540232256;9573.904260957579;9598.32811703174;9634.807303854024;9755.073268039809;9400.341976061884;9661.951690241503;9696.127348538665;9745.074217949283;9840.55717492729;7238.131424229126;7268.27317268269;7300.45246606496;7400;7401.962990185015;7929.762107136795;7937.563434011185;8021.358291336795;8040.477368970998;8052.758417247492;6967.65161741921;7105.18290396611;7224.783256502314;7267.418606511572;7325.194228634739;7095.361417472246;7224.711011559479;7248.710051598088;7256.2018177999325;7264.418846492374;5777.335606405317;6554.180727409074;6691.397774200151;6837.418819400406;6839.760608378683;4695.201815691409;4785.569298763128;4810.518948105161;4832.468428472936;4884.3650325457465;4840.394950631171;4852.126617208936;4859.22638237048;4983.551480366785;4997.675151115166 22:26:05 INFO - PROCESS | 1638 | |4;prototype.html;255.51579756361696;296.9020223326302;299.36982651518565;303.8678174993878;309.5526963537689;5075.645194319991;5250.606204534661;5286.973565132179;5321;5333.99975009372;4475.753833539154;4786.760661966905;5174.43081261061;5207.218917162428;5296.761645725942;3864.4976153100056;4030.334994725881;4066.4713587233764;4092.918141637173;4114.753114813106;3235.3367559650333;4059.863238293268;4068.907630201039;4112.362583799516;4130.793460326987;3981;3991.762553608376;3996.42051902474;4007;4020.030185209241;3988.0458575297894;4004.899877503057;4008.5590585035625;4010.639042486161;4053.885181575061;3072.523758817387;3423.4180189367867;3529.8941031769086;3568.3041806847673;3569.3932031554436;4217.207686733157;4826.300186472942;5173.198154286091;5293.7353132343615;5296.894062118775;4050.0887300357476;4135.6898232632375;4176.7076304658385;4466.731996080245;4504.054148628792;4745.501722319142;4806.735629540374;5494;5519.645943434862;5565;3710.9133146103527;3721.6836568891677;3805.676517496016;3808.733388662795;3837.3284675182013;5225.608079394061;5378.623496355257;5771.538276937835;5772.9711351442975;5860.267466566679;3363.3105213431186;3618.782873027627;3742.5321834770652;3868.2650296443585;3876.0697432616535;3523;3534.151803567125;3768.0014796079063;3927.8821635350987;3930.6855451563806;3811.294910441577;4071.3485842265095;4504.175808797442;4534.841280555231;4540.50163446062;5525.480492864414;5581.302337207849;5584.6492104737645;5602.74191614376;5650.869826034809;1203.9398030098635;1204.9759004819855;1213.6662417835166;1234.8209509621054;1246.4764798784531;834.4284165346756;857.100044952797;861.7716305179133;874.0341922175904;875.7241468937265;4937.086638971802;4978.6017118630425;4980.950190498121;5021.899562008739;5075.746212689351;3964.5638979712207;4606.424196975378;5088.363954505687;5395.62230643859;5480.21892884814;4470.418845550058;5301.840944771663;5354.3574771027725;5378.381486129145;5385.865353366197;2651.7745991590546;2817.9718202817708;2821.2085325818252;2833.6964996102024;2838.4890719670243;5216.504432078947;5291.592325491892;5487.42382049887;5678.614981707693;5700.8859822803315;4724;5134.2812006319145;5328.893422131536;5507.807226747044;5574.331080270394;5149.510796474329;5269.525742683177;5541.584381171348;5544.390117087117;5562.666240025612;5026;5305.1776974569;5343.893122137613;5431.538319242909;5494.313210848644;4596.540345965377;4798.089557011025;5117.462666420121;5340.359156901197;5366.946330536801;5133.460986596354;5230.660007099678;5264.9210261846865;5480.314960629921;5548.889022219452;3416.8382749865164;3533.5229743984232;3724.6906917711194;3859.1895701902913;3864.9806750966063;5008.474110218521;5735.025045742151;5746.2529871116485;5767.5385969121535;5777.104548794943;5369.436209198134;5575;5594.832155035356;5596.776128954796;5603.719814009365;4607.935722744338;5504.889902202013;5534.972325138348;5554.250176226158;5594.356648985256;5179.326687530597;5311.229871668664;5348.384935732441;5356.812511562077;5489.106258340866;4869.683470574401;5587.262241501716;5587.8824235152315;5595.580331475075;5603.411641777555;4928.408590969107;5244.370675518962;5466.808661696821;5478.753456094406;5542.722863856871 22:26:05 INFO - PROCESS | 1638 | |5;yui.html;591.5953686533998;722.2813300765739;749.2095838889969;777.6850375597885;785.8664027115393;11981.700457488556;14079.296035198238;14425.350859211334;15810.209489525536;16690.41583544576;13701.246431446292;15064.774028389556;15136.470223542177;15251.466198683049;15273.389064437437;788.136989995955;819.778659761864;820.4420993724265;821.6302663801283;822.4653974916292;643.83904023994;658.1411258307907;789.8301865099003;812.5733989655419;816.9019717633862;849.0278630967539;862.3704695572236;865.5455885660016;866.4498043742205;866.8439680857443;855.8502262104138;857.716953405381;858.0689951402735;858.9441686290371;859.2567429173782;499.9650723003384;502.6807976934636;509.2666560153367;512.8897287083295;639.4532674563275;12202.877971220352;13793.310334483236;14313.212773297464;14373;14690.55928322152;13072.673183170496;13387.732245355135;13498.392572334269;13561.593152205449;13645.249511276876;11319.377434241114;11467.885321146765;11543.711407214887;11554.942225288904;11610.593629223022;3501.439769636846;3585.1933315004176;3592.425211966099;3747.850085996557;3769.6795772359383;78025.60987195028;85548.57225713832;87131;87962;88056;10360.523585678297;10407.739806504898;10752.462376881125;10834.945825270823;10862.674119776419;20279.594408111756;20392.490187745425;20446.284380046924;20581.691274630593;20849.79150208509;7637.15991240963;7733.651985660659;7772.417068719868;7894.368450523944;7931.881021784678;4042.272390969595;4155.937660935088;4200.600942910419;4268.125034367947;4368.5194628590825;2992;3416.231347946692;3525.6474352564537;3528.400171970771;3529.7705649133236;624.7782037376666;627.7520379450092;643.6749441531996;644.091830518966;648.1120864415686;580.2862479150668;614.9422992452974;636.2905360522983;658.1707049118146;675.8344205669642;8781.868271975925;9113.726588202364;9186;9254.814903701888;9385.245901639262;543.6058857328484;606.5117580347862;615.5906322295623;656.3666062249952;660.2736989311815;4274.487061552633;4312.870613881589;4414.271645178574;4533.750643714628;4542.523035081335;66456.33543664598;87367.56316218378;91481;91752.5412372934;105588;60477;65384.67307663431;67791.66104169447;92192;92893;82249.58875205586;84462;84786;85773.57113214643;87434.56282718545;8673.219410253107;8676.479411235094;8747.868781968404;8924.86612700823;9174.082591740773;49618.50381496283;51035;51907.74046129745;52124.739376302874;52720.73639631777;57801.71099144477;76367;76431;80044.59977700074;80617;12012.159148859846;12138.33239171854;12180.817287740869;12400.00799936021;12432.440540175532;14057.367418466398;14101.717965640424;14289.571312860631;14585.708285834437;14821.036632618845;12232.816507752214;12534.811977820522;12912.935435322763;13179.802302965654;13771.931140344233;12652.810207847073;12824.422900969665;13360.732785344433;13897.235652039235;14170.858291417366;13057.08600397963;13110.344482776014;13305.733885322046;13501.797473037715;13894.930525347309;10174.745631359276;10711.250212485364;11002.449877505933;11422.143339249424;11887;11410.94294528522;11450.713732156762;11564.054912774838;11600;11718.355490448108 22:26:05 INFO - PROCESS | 1638 | __end_tp_report 22:26:05 INFO - PROCESS | 1638 | __start_cc_report 22:26:05 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,2105 22:26:05 INFO - PROCESS | 1638 | __end_cc_report 22:26:05 INFO - PROCESS | 1638 | __startTimestamp1479191165824__endTimestamp 22:26:05 INFO - PROCESS | 1638 | 22:26:05 INFO - PROCESS | 1638 | ------- Summary: start ------- 22:26:05 INFO - PROCESS | 1638 | Number of tests: 6 22:26:05 INFO - PROCESS | 1638 | 22:26:05 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5782.51 Median:5975.07 stddev:663.15 (11.1%) stddev-sans-first:637.15 22:26:05 INFO - PROCESS | 1638 | Values: 3350.4 3354.6 3377.0 3384.5 3424.5 5377.3 5729.3 5757.3 5803.1 5822.2 4377.6 5777.6 5813.1 5834.1 5893.8 5221.7 5254.7 5256.1 5289.8 5332.8 5196.7 5208.9 5209.4 5277.4 5384.5 5272.9 5298.9 5302.1 5309.9 5315.0 5305.8 5306.2 5308.8 5309.9 5321.3 5123.0 5234.4 5242.7 5245.7 5277.2 5807.9 5808.5 5818.1 5821.7 5826.1 6146.5 6184.6 6184.9 6190.3 6196.9 6302.1 6311.9 6313.4 6357.7 6358.0 4788.9 5832.8 5861.5 5896.9 5930.6 6309.1 6325.8 6325.8 6362.7 6371.7 4977.0 6111.9 6113.9 6139.1 6173.7 6316.1 6316.3 6339.2 6340.1 6340.5 6167.5 6279.6 6300.2 6312.5 6315.5 6231.2 6241.8 6266.1 6273.3 6285.7 4948.2 4949.1 4951.9 4952.8 4979.2 4406.2 4444.8 4477.8 4496.6 4506.5 5763.9 5772.7 5844.6 5846.6 5863.5 4993.7 6270.9 6304.3 6340.1 6344.8 5921.9 5926.6 5940.5 5949.1 5991.8 5425.3 5448.7 5455.7 5477.8 5506.0 6284.9 6348.0 6350.4 6354.7 6370.3 6302.8 6332.8 6368.3 6380.9 6387.8 6275.6 6323.6 6335.0 6341.6 6342.5 6123.4 6266.4 6267.9 6282.7 6285.9 6237.3 6280.8 6316.7 6326.5 6327.0 6219.1 6221.0 6224.9 6240.3 6249.7 6012.6 6168.4 6173.4 6198.0 6245.9 5882.1 5930.3 5930.8 6014.0 6074.3 6165.4 6189.8 6215.6 6225.8 6230.6 5815.8 5889.4 5895.6 5975.1 6053.7 22:26:05 INFO - PROCESS | 1638 | 22:26:05 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:65061.62 Median:37268.44 stddev:146167.51 (392.2%) stddev-sans-first:146497.05 22:26:05 INFO - PROCESS | 1638 | Values: 450.2 477.3 517.5 522.7 534.9 20832.7 37564.8 38346.7 38385.8 39686.4 38941.8 39022.0 39504.4 39526.4 39552.0 9165.1 9211.8 9329.0 9337.3 9350.9 9132.0 9177.5 9282.5 9301.7 9307.7 9301.8 9302.3 9308.0 9315.1 9319.3 9134.7 9205.5 9276.3 9286.1 9296.1 9078.3 9154.0 9154.9 9184.5 9189.0 37351.0 37635.2 37671.4 37679.6 37689.4 2976.5 3313.1 3707.0 3716.8 3858.3 27436.9 27638.0 29111.4 29698.3 29932.4 3150.6 3152.4 3170.3 3244.1 3252.9 477035.0 479961.0 482273.0 482440.0 490627.0 31877.4 32099.7 32570.3 54394.0 54553.5 33187.0 33305.3 33316.7 33323.3 33352.0 14162.9 14310.0 14315.8 14322.2 14357.0 12166.9 12255.3 12260.0 12261.4 12287.6 7484.9 7541.8 7544.3 7557.6 7617.8 3110.2 3135.4 3168.9 3174.5 3211.7 886.4 975.1 994.2 1012.8 1035.6 28384.4 28681.4 28685.3 29053.7 29323.4 1002.3 1071.1 1072.3 1074.3 1088.1 14811.6 14889.4 15075.3 15129.6 15266.8 636181.0 643188.0 645597.0 647977.0 648366.0 53768.5 54242.7 54339.0 54345.2 54476.2 1172.5 1196.4 1233.7 1308.5 1352.6 28909.7 29592.9 29827.9 30213.1 30339.5 45520.5 46045.5 46109.5 46135.8 46207.8 426430.0 507579.0 511260.0 512066.0 512089.0 24204.4 25327.2 25344.6 25373.4 25385.4 43870.3 44178.0 44262.3 44302.0 44832.1 36376.5 36905.4 37267.0 38565.0 38720.0 36624.8 36923.3 37026.4 37178.8 37542.6 36419.3 37001.3 37206.4 37266.8 37269.9 31798.2 33246.3 33318.5 33442.2 33455.8 24133.8 24376.0 24484.0 24943.9 25022.4 22:26:05 INFO - PROCESS | 1638 | 22:26:05 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:33083.30 Median:4090.77 stddev:98613.70 (2410.6%) stddev-sans-first:98859.60 22:26:05 INFO - PROCESS | 1638 | Values: 296.3 324.5 366.7 375.6 399.1 14385.0 14786.8 15282.5 15517.5 19523.1 19071.7 19190.7 19820.9 19900.4 20022.5 5204.4 5633.2 5991.9 6024.5 6032.5 5006.7 5605.7 5821.3 5879.0 5950.6 4985.9 5823.4 5829.0 5833.8 5834.8 5698.1 5804.4 5824.7 5832.0 5902.2 5142.8 5143.4 5158.5 5159.8 5165.6 20863.4 23739.9 24904.5 25084.7 25297.0 2569.8 2626.8 3033.0 3062.9 3095.0 14755.1 15145.3 15173.8 15270.9 15596.2 1790.3 1818.1 1830.9 1839.6 1854.1 87394.0 92215.5 92222.0 114411.0 114682.3 8705.5 10120.3 10380.4 10589.9 11294.5 11437.4 13733.2 13782.7 13805.4 13813.9 7742.5 7956.8 8018.6 8136.9 8159.2 6126.4 8258.0 8277.8 8389.1 8444.5 3828.8 3997.4 4184.1 4376.5 4387.8 1792.6 1857.3 1864.9 1911.0 1911.5 384.3 824.7 869.1 889.9 894.7 11013.7 11596.6 11633.7 11840.0 11993.6 425.0 426.7 438.0 439.4 460.6 5796.7 5798.7 6051.5 6234.7 6257.8 579737.0 580155.0 595670.0 602969.0 603556.0 121978.0 124347.0 125769.7 128013.7 130427.7 423.0 438.2 439.8 440.0 440.5 10776.8 10923.3 10929.5 11002.7 11021.3 47924.5 48608.3 49615.5 50822.7 51891.0 57830.4 68703.0 69824.7 75119.2 75185.0 7101.3 7826.0 7882.5 7915.2 8358.7 10926.3 12334.5 12542.6 12734.5 12790.9 10521.5 10696.2 10710.3 10958.8 11018.4 10669.4 10737.4 10803.4 11100.1 11255.3 5003.5 9990.4 10761.4 11140.9 11546.5 7885.9 7887.5 7999.8 8193.0 8365.0 8084.1 10207.0 10755.1 10931.8 10995.2 22:26:05 INFO - PROCESS | 1638 | 22:26:05 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5501.61 Median:4855.71 stddev:3071.06 (63.2%) stddev-sans-first:3064.82 22:26:05 INFO - PROCESS | 1638 | Values: 1481.8 1502.9 1635.3 1780.5 1804.5 8073.5 8190.3 8613.7 8623.1 8630.5 7292.9 7634.1 7821.6 8109.4 8362.7 2298.5 2337.8 2338.3 2341.7 2342.0 2338.8 2357.3 2364.7 2396.5 2398.7 2324.5 2335.6 2350.5 2354.5 2362.0 2301.3 2303.0 2314.9 2316.3 2327.9 2350.4 2370.8 2370.9 2379.1 2380.1 7429.7 8508.2 8563.7 8572.8 8582.0 5105.8 5275.8 5292.2 5294.4 5300.7 3239.7 3391.4 3615.1 3622.9 3631.5 1068.9 1069.7 1091.8 1110.0 1112.2 11050.5 11114.2 11228.4 11251.7 11272.4 3704.6 4625.4 4730.6 4741.5 4807.8 9618.0 9621.4 9623.1 9632.0 9636.9 4449.4 5262.3 5264.4 5269.3 5269.4 4280.8 4541.8 4774.1 4831.0 4852.2 3811.9 3827.6 3877.7 3929.7 3938.6 1245.5 1246.3 1247.6 1263.8 1269.2 868.6 882.0 882.5 904.8 916.0 5134.7 5142.5 5330.1 5422.0 5439.9 429.9 434.4 448.9 453.5 467.9 3781.1 3800.4 3847.8 3851.7 3885.8 9887.4 10427.3 10437.0 10441.3 10454.2 10357.3 10396.8 10396.8 10396.8 10401.4 7982.4 8039.3 8046.1 8065.4 8111.8 5417.7 5525.1 5593.4 5686.3 5688.6 9292.0 9573.9 9598.3 9634.8 9755.1 9400.3 9662.0 9696.1 9745.1 9840.6 7238.1 7268.3 7300.5 7400.0 7402.0 7929.8 7937.6 8021.4 8040.5 8052.8 6967.7 7105.2 7224.8 7267.4 7325.2 7095.4 7224.7 7248.7 7256.2 7264.4 5777.3 6554.2 6691.4 6837.4 6839.8 4695.2 4785.6 4810.5 4832.5 4884.4 4840.4 4852.1 4859.2 4983.6 4997.7 22:26:05 INFO - PROCESS | 1638 | 22:26:05 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4421.90 Median:5050.82 stddev:1350.42 (26.7%) stddev-sans-first:1317.51 22:26:05 INFO - PROCESS | 1638 | Values: 255.5 296.9 299.4 303.9 309.6 5075.6 5250.6 5287.0 5321.0 5334.0 4475.8 4786.8 5174.4 5207.2 5296.8 3864.5 4030.3 4066.5 4092.9 4114.8 3235.3 4059.9 4068.9 4112.4 4130.8 3981.0 3991.8 3996.4 4007.0 4020.0 3988.0 4004.9 4008.6 4010.6 4053.9 3072.5 3423.4 3529.9 3568.3 3569.4 4217.2 4826.3 5173.2 5293.7 5296.9 4050.1 4135.7 4176.7 4466.7 4504.1 4745.5 4806.7 5494.0 5519.6 5565.0 3710.9 3721.7 3805.7 3808.7 3837.3 5225.6 5378.6 5771.5 5773.0 5860.3 3363.3 3618.8 3742.5 3868.3 3876.1 3523.0 3534.2 3768.0 3927.9 3930.7 3811.3 4071.3 4504.2 4534.8 4540.5 5525.5 5581.3 5584.6 5602.7 5650.9 1203.9 1205.0 1213.7 1234.8 1246.5 834.4 857.1 861.8 874.0 875.7 4937.1 4978.6 4981.0 5021.9 5075.7 3964.6 4606.4 5088.4 5395.6 5480.2 4470.4 5301.8 5354.4 5378.4 5385.9 2651.8 2818.0 2821.2 2833.7 2838.5 5216.5 5291.6 5487.4 5678.6 5700.9 4724.0 5134.3 5328.9 5507.8 5574.3 5149.5 5269.5 5541.6 5544.4 5562.7 5026.0 5305.2 5343.9 5431.5 5494.3 4596.5 4798.1 5117.5 5340.4 5366.9 5133.5 5230.7 5264.9 5480.3 5548.9 3416.8 3533.5 3724.7 3859.2 3865.0 5008.5 5735.0 5746.3 5767.5 5777.1 5369.4 5575.0 5594.8 5596.8 5603.7 4607.9 5504.9 5535.0 5554.3 5594.4 5179.3 5311.2 5348.4 5356.8 5489.1 4869.7 5587.3 5587.9 5595.6 5603.4 4928.4 5244.4 5466.8 5478.8 5542.7 22:26:05 INFO - PROCESS | 1638 | 22:26:05 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:19346.54 Median:27080.51 stddev:27006.21 (99.7%) stddev-sans-first:27045.25 22:26:05 INFO - PROCESS | 1638 | Values: 591.6 722.3 749.2 777.7 785.9 11981.7 14079.3 14425.4 15810.2 16690.4 13701.2 15064.8 15136.5 15251.5 15273.4 788.1 819.8 820.4 821.6 822.5 643.8 658.1 789.8 812.6 816.9 849.0 862.4 865.5 866.4 866.8 855.9 857.7 858.1 858.9 859.3 500.0 502.7 509.3 512.9 639.5 12202.9 13793.3 14313.2 14373.0 14690.6 13072.7 13387.7 13498.4 13561.6 13645.2 11319.4 11467.9 11543.7 11554.9 11610.6 3501.4 3585.2 3592.4 3747.9 3769.7 78025.6 85548.6 87131.0 87962.0 88056.0 10360.5 10407.7 10752.5 10834.9 10862.7 20279.6 20392.5 20446.3 20581.7 20849.8 7637.2 7733.7 7772.4 7894.4 7931.9 4042.3 4155.9 4200.6 4268.1 4368.5 2992.0 3416.2 3525.6 3528.4 3529.8 624.8 627.8 643.7 644.1 648.1 580.3 614.9 636.3 658.2 675.8 8781.9 9113.7 9186.0 9254.8 9385.2 543.6 606.5 615.6 656.4 660.3 4274.5 4312.9 4414.3 4533.8 4542.5 66456.3 87367.6 91481.0 91752.5 105588.0 60477.0 65384.7 67791.7 92192.0 92893.0 82249.6 84462.0 84786.0 85773.6 87434.6 8673.2 8676.5 8747.9 8924.9 9174.1 49618.5 51035.0 51907.7 52124.7 52720.7 57801.7 76367.0 76431.0 80044.6 80617.0 12012.2 12138.3 12180.8 12400.0 12432.4 14057.4 14101.7 14289.6 14585.7 14821.0 12232.8 12534.8 12912.9 13179.8 13771.9 12652.8 12824.4 13360.7 13897.2 14170.9 13057.1 13110.3 13305.7 13501.8 13894.9 10174.7 10711.3 11002.4 11422.1 11887.0 11410.9 11450.7 11564.1 11600.0 11718.4 22:26:05 INFO - PROCESS | 1638 | -------- Summary: end -------- 22:26:05 INFO - PROCESS | 1638 | 22:26:06 INFO - TEST-INFO | 1638: exit 0 22:26:06 INFO - TEST-OK | dromaeo_css | took 1121153ms 22:26:06 INFO - TEST-START | kraken 22:26:06 INFO - operating with platform_type : mac_ 22:26:06 INFO - Initialising browser for kraken test... 22:26:06 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/tmp97jjeu/profile http://localhost:49237/getInfo.html) 22:26:08 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 22:26:08 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 22:26:08 INFO - PROCESS | 1648 | colorDepth:24 22:26:08 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 22:26:08 INFO - PROCESS | 1648 | __metrics 22:26:08 INFO - PROCESS | 1648 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:26:08 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:26:09 INFO - TEST-INFO | 1648: exit 0 22:26:09 INFO - Browser initialized. 22:26:09 INFO - Running cycle 1/1 for kraken test... 22:26:09 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/tmp97jjeu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:26:21 INFO - PROCESS | 1649 | RSS: Main: 217620480 22:26:21 INFO - PROCESS | 1649 | 22:26:24 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection) 22:26:25 INFO - PROCESS | 1649 | RSS: Main: 267550720 22:26:25 INFO - PROCESS | 1649 | 22:26:27 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-dft) 22:26:28 INFO - PROCESS | 1649 | RSS: Main: 278007808 22:26:28 INFO - PROCESS | 1649 | 22:26:31 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-fft) 22:26:32 INFO - PROCESS | 1649 | RSS: Main: 375906304 22:26:32 INFO - PROCESS | 1649 | 22:26:34 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator) 22:26:34 INFO - PROCESS | 1649 | RSS: Main: 278196224 22:26:34 INFO - PROCESS | 1649 | 22:26:36 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:26:37 INFO - PROCESS | 1649 | RSS: Main: 279359488 22:26:37 INFO - PROCESS | 1649 | 22:26:40 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom) 22:26:40 INFO - PROCESS | 1649 | RSS: Main: 319356928 22:26:40 INFO - PROCESS | 1649 | 22:26:43 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate) 22:26:44 INFO - PROCESS | 1649 | RSS: Main: 321273856 22:26:44 INFO - PROCESS | 1649 | 22:26:47 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial) 22:26:47 INFO - PROCESS | 1649 | RSS: Main: 316481536 22:26:47 INFO - PROCESS | 1649 | 22:26:49 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:26:49 INFO - PROCESS | 1649 | RSS: Main: 295182336 22:26:49 INFO - PROCESS | 1649 | 22:26:51 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:26:52 INFO - PROCESS | 1649 | RSS: Main: 299679744 22:26:52 INFO - PROCESS | 1649 | 22:26:54 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:26:55 INFO - PROCESS | 1649 | RSS: Main: 301301760 22:26:55 INFO - PROCESS | 1649 | 22:26:58 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:26:58 INFO - PROCESS | 1649 | RSS: Main: 312426496 22:26:58 INFO - PROCESS | 1649 | 22:27:01 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:27:02 INFO - PROCESS | 1649 | RSS: Main: 312193024 22:27:02 INFO - PROCESS | 1649 | 22:27:04 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49237/tests/kraken/driver.html?testName=ai-astar) 22:27:04 INFO - PROCESS | 1649 | RSS: Main: 355557376 22:27:04 INFO - PROCESS | 1649 | 22:27:04 INFO - PROCESS | 1649 | __start_tp_report 22:27:04 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 22:27:04 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 22:27:04 INFO - PROCESS | 1649 | |i|pagename|runs| 22:27:04 INFO - PROCESS | 1649 | |0;ai-astar;115;115;115;112;114;114;111;114;117;112 22:27:04 INFO - PROCESS | 1649 | |1;audio-beat-detection;140;112;113;168;142;116;115;136;176;112 22:27:04 INFO - PROCESS | 1649 | |2;audio-dft;185;183;181;187;184;182;185;183;182;182 22:27:04 INFO - PROCESS | 1649 | |3;audio-fft;81;57;59;89;61;58;59;58;81;57 22:27:04 INFO - PROCESS | 1649 | |4;audio-oscillator;86;69;94;69;68;70;88;67;69;91 22:27:04 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;89;86;89;86;88;86;89;86;89;86 22:27:04 INFO - PROCESS | 1649 | |6;imaging-darkroom;93;93;94;94;94;94;94;95;95;94 22:27:04 INFO - PROCESS | 1649 | |7;imaging-desaturate;81;81;80;80;82;83;84;85;82;80 22:27:04 INFO - PROCESS | 1649 | |8;json-parse-financial;55;53;54;53;55;56;58;56;56;57 22:27:04 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;70;65;70;81;76;73;71;70;71;70 22:27:04 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;83;82;83;86;84;83;82;82;88;84 22:27:04 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;145;146;147;144;145;154;157;156;194;158 22:27:04 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;168;167;169;168;167;222;225;163;166;170 22:27:04 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;67;66;67;66;67;65;66;65;66;66 22:27:04 INFO - PROCESS | 1649 | __end_tp_report 22:27:04 INFO - PROCESS | 1649 | __start_cc_report 22:27:04 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1968 22:27:04 INFO - PROCESS | 1649 | __end_cc_report 22:27:04 INFO - PROCESS | 1649 | __startTimestamp1479191224427__endTimestamp 22:27:04 INFO - PROCESS | 1649 | 22:27:04 INFO - PROCESS | 1649 | ------- Summary: start ------- 22:27:04 INFO - PROCESS | 1649 | Number of tests: 14 22:27:04 INFO - PROCESS | 1649 | 22:27:04 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.50 stddev:1.79 (1.6%) stddev-sans-first:1.86 22:27:04 INFO - PROCESS | 1649 | Values: 115.0 115.0 115.0 112.0 114.0 114.0 111.0 114.0 117.0 112.0 22:27:04 INFO - PROCESS | 1649 | 22:27:04 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:133.00 Median:138.00 stddev:23.82 (17.3%) stddev-sans-first:25.13 22:27:04 INFO - PROCESS | 1649 | Values: 140.0 112.0 113.0 168.0 142.0 116.0 115.0 136.0 176.0 112.0 22:27:04 INFO - PROCESS | 1649 | 22:27:04 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:183.40 Median:183.50 stddev:1.84 (1.0%) stddev-sans-first:1.86 22:27:04 INFO - PROCESS | 1649 | Values: 185.0 183.0 181.0 187.0 184.0 182.0 185.0 183.0 182.0 182.0 22:27:04 INFO - PROCESS | 1649 | 22:27:04 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:66.00 Median:60.00 stddev:12.44 (20.7%) stddev-sans-first:11.95 22:27:04 INFO - PROCESS | 1649 | Values: 81.0 57.0 59.0 89.0 61.0 58.0 59.0 58.0 81.0 57.0 22:27:04 INFO - PROCESS | 1649 | 22:27:04 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:78.00 stddev:11.10 (14.2%) stddev-sans-first:11.30 22:27:04 INFO - PROCESS | 1649 | Values: 86.0 69.0 94.0 69.0 68.0 70.0 88.0 67.0 69.0 91.0 22:27:04 INFO - PROCESS | 1649 | 22:27:04 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:88.50 stddev:1.51 (1.7%) stddev-sans-first:1.48 22:27:04 INFO - PROCESS | 1649 | Values: 89.0 86.0 89.0 86.0 88.0 86.0 89.0 86.0 89.0 86.0 22:27:04 INFO - PROCESS | 1649 | 22:27:04 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 22:27:04 INFO - PROCESS | 1649 | Values: 93.0 93.0 94.0 94.0 94.0 94.0 94.0 95.0 95.0 94.0 22:27:04 INFO - PROCESS | 1649 | 22:27:04 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:81.80 Median:82.00 stddev:1.75 (2.1%) stddev-sans-first:1.83 22:27:04 INFO - PROCESS | 1649 | Values: 81.0 81.0 80.0 80.0 82.0 83.0 84.0 85.0 82.0 80.0 22:27:04 INFO - PROCESS | 1649 | 22:27:04 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:56.00 stddev:1.64 (2.9%) stddev-sans-first:1.73 22:27:04 INFO - PROCESS | 1649 | Values: 55.0 53.0 54.0 53.0 55.0 56.0 58.0 56.0 56.0 57.0 22:27:04 INFO - PROCESS | 1649 | 22:27:04 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:71.00 stddev:4.27 (6.0%) stddev-sans-first:4.48 22:27:04 INFO - PROCESS | 1649 | Values: 70.0 65.0 70.0 81.0 76.0 73.0 71.0 70.0 71.0 70.0 22:27:04 INFO - PROCESS | 1649 | 22:27:04 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:83.50 stddev:1.95 (2.3%) stddev-sans-first:2.05 22:27:04 INFO - PROCESS | 1649 | Values: 83.0 82.0 83.0 86.0 84.0 83.0 82.0 82.0 88.0 84.0 22:27:04 INFO - PROCESS | 1649 | 22:27:04 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:154.60 Median:155.00 stddev:14.91 (9.6%) stddev-sans-first:15.40 22:27:04 INFO - PROCESS | 1649 | Values: 145.0 146.0 147.0 144.0 145.0 154.0 157.0 156.0 194.0 158.0 22:27:04 INFO - PROCESS | 1649 | 22:27:04 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.50 Median:168.50 stddev:23.80 (14.1%) stddev-sans-first:24.94 22:27:04 INFO - PROCESS | 1649 | Values: 168.0 167.0 169.0 168.0 167.0 222.0 225.0 163.0 166.0 170.0 22:27:04 INFO - PROCESS | 1649 | 22:27:04 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 22:27:04 INFO - PROCESS | 1649 | Values: 67.0 66.0 67.0 66.0 67.0 65.0 66.0 65.0 66.0 66.0 22:27:04 INFO - PROCESS | 1649 | -------- Summary: end -------- 22:27:04 INFO - PROCESS | 1649 | 22:27:05 INFO - TEST-INFO | 1649: exit 0 22:27:05 INFO - TEST-OK | kraken | took 58775ms 22:27:05 INFO - SUITE-END | took 1179s 22:27:05 INFO - Completed test suite (00:19:40) 22:27:05 INFO - javascript benchmark 22:27:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3350.4136776064192, 3354.580677415323, 3376.966230337699, 3384.4754063120226, 3424.520567120602, 5377.300950876381, 5729.255196824417, 5757.3379061407895, 5803.100519419491, 5822.214001109844, 4377.649788016959, 5777.566682498819, 5813.09896965971, 5834.095715164146, 5893.823185304437, 5221.660592061513, 5254.684718916858, 5256.132738098209, 5289.841304760844, 5332.786688532454, 5196.714180720059, 5208.947910520903, 5209.400918894337, 5277.419483856773, 5384.488473595002, 5272.894542109156, 5298.947010529884, 5302.072137375963, 5309.920351194735, 5315.0432922074015, 5305.840824775224, 5306.151015837486, 5308.814191503298, 5309.946900530984, 5321.308229930123, 5122.974385128088, 5234.398044224925, 5242.711650859202, 5245.659032162897, 5277.23480095384, 5807.912881306784, 5808.506276966463, 5818.0981947798155, 5821.650700957956, 5826.067829147359, 6146.477549408306, 6184.56708030438, 6184.938150618526, 6190.257169139687, 6196.87606247877, 6302.149209856657, 6311.905321420182, 6313.431791138773, 6357.745690172387, 6357.96821015892, 4788.856334309976, 5832.795852145155, 5861.450269132941, 5896.882062358772, 5930.644161350333, 6309.14826498426, 6325.810225693191, 6325.810225693236, 6362.745490180433, 6371.713272902731, 4977.0, 6111.895622426848, 6113.902014424272, 6139.075791860876, 6173.660448675366, 6316.084167795736, 6316.30520642729, 6339.2392912850755, 6340.144080549159, 6340.492760579143, 6167.475764559973, 6279.560430769893, 6300.21247344082, 6312.52656050798, 6315.494760419156, 6231.189945307081, 6241.843953901189, 6266.091416744546, 6273.278572964076, 6285.717142728497, 4948.158813001798, 4949.059678661115, 4951.925721114186, 4952.777125029383, 4979.203327467588, 4406.228909940747, 4444.8444304449185, 4477.7984990675495, 4496.642958181417, 4506.459224893034, 5763.9135412968835, 5772.682502462321, 5844.590878638454, 5846.590738648254, 5863.501602363762, 4993.725345106054, 6270.9372906271265, 6304.306526282106, 6340.080688300263, 6344.809655710336, 5921.911171332434, 5926.585139040226, 5940.495057920039, 5949.137375080676, 5991.7603295867675, 5425.294711687456, 5448.727563621803, 5455.699936503451, 5477.808276710296, 5506.0, 6284.937150628618, 6347.984322508377, 6350.364963503613, 6354.650494222862, 6370.267419246846, 6302.779402720883, 6332.810015699536, 6368.267649220399, 6380.87238255223, 6387.840303992437, 6275.592086514362, 6323.557350985571, 6334.968325158345, 6341.55609107358, 6342.492600592034, 6123.387661233841, 6266.404691554295, 6267.905981410191, 6282.717277722422, 6285.905711414424, 6237.345078766845, 6280.842978925563, 6316.6841657916, 6326.525510586633, 6326.9683651581445, 6219.067139928866, 6221.035739460391, 6224.906626400698, 6240.344763799736, 6249.687515624291, 6012.6392416455155, 6168.383161683796, 6173.41352571505, 6198.039303907902, 6245.875082498415, 5882.1470886722045, 5930.28836539618, 5930.822075337746, 6014.0, 6074.271087469532, 6165.383461653978, 6189.783357582463, 6215.627062376272, 6225.782097626561, 6230.563860529901, 5815.82552523425, 5889.411058894077, 5895.587308888336, 5975.073863551156, 6053.727582258897], "value": 5740.829990286431, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.2346011779974, 477.2673945493666, 517.521292804156, 522.7281813457001, 534.8636097795064, 20832.68750968735, 37564.812175939114, 38346.65786697466, 38385.84842454731, 39686.40470392947, 38941.80529097358, 39022.0, 39504.407433888446, 39526.40710389346, 39552.0, 9165.083491650816, 9211.815763684723, 9328.953355233249, 9337.299702522305, 9350.859737103949, 9131.954340228289, 9177.495237761921, 9282.489463079528, 9301.720948371525, 9307.674231401903, 9301.813963720722, 9302.348835581512, 9307.976122626505, 9315.115064068903, 9319.254459643245, 9134.725958221197, 9205.493697846616, 9276.304277179172, 9286.117818807203, 9296.070392960717, 9078.319126065571, 9153.954230228874, 9154.86267705985, 9184.494852783095, 9189.0, 37351.0, 37635.247295053945, 37671.4349284761, 37679.623203767886, 37689.434658480146, 2976.4939960206816, 3313.1385839681616, 3706.981465092684, 3716.832742526593, 3858.305505009097, 27436.862815685992, 27638.0, 29111.41777164466, 29698.257543561584, 29932.40135197284, 3150.6376766671897, 3152.3537674776617, 3170.3342298117423, 3244.0754385000164, 3252.9186770330693, 477035.0, 479961.0, 482272.9999999965, 482440.0, 490627.0, 31877.362452750815, 32099.679003210134, 32570.34859302848, 54394.0, 54553.45446545563, 33187.0, 33305.333893322, 33316.66683333136, 33323.3335333292, 33352.0, 14162.866970642324, 14309.998300118892, 14315.78526322106, 14322.212278324583, 14356.995010349176, 12166.87833121675, 12255.345528353559, 12260.0, 12261.38693065361, 12287.56993505223, 7484.925150748423, 7541.773746787605, 7544.3210111089165, 7557.6221188940335, 7617.809554761176, 3110.1913502489515, 3135.4356215880903, 3168.8732450702173, 3174.539691744684, 3211.6788321167696, 886.4061079077034, 975.0931633580838, 994.1500017485091, 1012.8126296635146, 1035.5598870480167, 28384.438855862714, 28681.414823147108, 28685.28286792847, 29053.709462905525, 29323.413531729246, 1002.3334482569155, 1071.0681706915034, 1072.3083611070972, 1074.2856000759564, 1088.1077516436603, 14811.629709257355, 14889.404423822925, 15075.321610527333, 15129.621759455882, 15266.847331526764, 636181.0, 643188.0, 645597.0, 647977.0, 648366.0, 53768.462315377124, 54242.728786355816, 54339.0, 54345.18482222691, 54476.182857257176, 1172.5485688010178, 1196.3599474281218, 1233.6977440527128, 1308.4504508106809, 1352.6280272925023, 28909.710902891544, 29592.852035739685, 29827.85086074556, 30213.09360719182, 30339.544906825973, 45520.54479455295, 46045.53954460412, 46109.53890461053, 46135.846603835176, 46207.84480388017, 426430.0, 507579.0, 511260.0, 512066.0, 512089.0, 24204.39489012789, 25327.240182794547, 25344.619830702184, 25373.365665858502, 25385.365365866, 43870.34194487021, 44178.0, 44262.336064959694, 44302.0, 44832.10335793331, 36376.454353185254, 36905.44641830429, 37267.0, 38565.0, 38720.03199920025, 36624.81687591545, 36923.261534768615, 37026.44460333151, 37178.8141059293, 37542.624573755005, 36419.27161456809, 37001.25997479982, 37206.44190337093, 37266.8136659315, 37269.88190354294, 31798.20504487314, 33246.335073298884, 33318.50022249619, 33442.16394590155, 33455.83272083624, 24133.75866241315, 24376.0, 24484.0, 24943.87528062348, 25022.37444063913], "value": 17769.55829105718, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.2504862697376, 324.5148502988033, 366.6754921894123, 375.5512162965256, 399.070166512027, 14384.993050486457, 14786.778198327034, 15282.465113721008, 15517.456889008887, 19523.12145953432, 19071.713924291147, 19190.712139317922, 19820.900895495502, 19900.40298791035, 20022.49943751407, 5204.42751297357, 5633.183188437684, 5991.910121348184, 6024.457798798107, 6032.487238584726, 5006.699598024112, 5605.7197140143035, 5821.27234095738, 5878.970605146989, 5950.5834591578605, 4985.875353116165, 5823.446772556622, 5829.009068458372, 5833.79581714638, 5834.795782147626, 5698.088305871082, 5804.361520232771, 5824.650520968734, 5831.970840145815, 5902.173695682608, 5142.768575414116, 5143.434222235551, 5158.509941555546, 5159.793608255665, 5165.612579056548, 20863.37409877706, 23739.881300593388, 24904.50190996188, 25084.749152508422, 25297.0, 2569.781568566674, 2626.8423894566386, 3032.95767816399, 3062.9234269143226, 3095.0, 14755.11469311834, 15145.318460669296, 15173.848261517463, 15270.923645381814, 15596.220188990505, 1790.274938649849, 1818.0909545227387, 1830.9267629294811, 1839.611093624305, 1854.1378259109445, 87394.0, 92215.53892230496, 92222.0, 114411.0, 114682.27976580359, 8705.521196334263, 10120.342177758423, 10380.377177369382, 10589.947050264853, 11294.548218071184, 11437.37094459796, 13733.176009439465, 13782.65543361424, 13805.378757955916, 13813.930930345485, 7742.535447873146, 7956.761297161094, 8018.5990700464745, 8136.918630813734, 8159.224873637114, 6126.417990290915, 8258.0, 8277.8344433111, 8389.07720150783, 8444.493330400195, 3828.789416582059, 3997.420374045747, 4184.121334519785, 4376.452943382077, 4387.824487020547, 1792.5518620344915, 1857.3313607101434, 1864.850811935042, 1910.961780764377, 1911.4838993471685, 384.2602989245691, 824.6783754335814, 869.0787764969026, 889.9143045484499, 894.6515397082658, 11013.724656883642, 11596.594119205787, 11633.65099047013, 11840.0, 11993.580224692094, 424.96600271978167, 426.6608046602958, 438.0297640725744, 439.4111890067325, 460.61538615255967, 5796.739146738407, 5798.710064496759, 6051.4553690168095, 6234.688265586702, 6257.812265632038, 579737.0, 580155.0, 595670.0, 602969.0, 603556.0, 121978.0, 124347.0, 125769.74230257764, 128013.71986280018, 130427.69572304345, 423.04814168121726, 438.2330920888445, 439.8020890599243, 440.0363204581955, 440.46483522520293, 10776.838347424638, 10923.344599324066, 10929.508172132115, 11002.669919902415, 11021.338719676845, 47924.5207547934, 48608.270875936185, 49615.50384496109, 50822.745886270335, 51891.0, 57830.421695782505, 68703.0, 69824.65087674733, 75119.24880751122, 75185.0, 7101.2898710128575, 7826.018016589479, 7882.5270483771155, 7915.168907264654, 8358.665653373797, 10926.344419334866, 12334.50661973511, 12542.561010364594, 12734.490620375082, 12790.872091278969, 10521.526531306263, 10696.1977851663, 10710.303830251009, 10958.780824383308, 11018.393988330397, 10669.35983840972, 10737.409442480739, 10803.351798892092, 11100.11199104086, 11255.324680519196, 5003.499650035113, 9990.400575965465, 10761.408122553335, 11140.944295278472, 11546.480408381474, 7885.881711774213, 7887.487313324616, 7999.840003199787, 8192.959035204785, 8364.958175209085, 8084.070331911906, 10207.0, 10755.08581770527, 10931.78136437251, 10995.230333876552], "value": 8420.897477161023, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1481.8073650425442, 1502.917339546325, 1635.2641311409868, 1780.5014595913149, 1804.4676820338007, 8073.515589064646, 8190.344772418207, 8613.741587752369, 8623.094575069606, 8630.48217106974, 7292.927070729291, 7634.122075961266, 7821.608919554028, 8109.391795615337, 8362.707305244317, 2298.5173113646156, 2337.836351455399, 2338.3218866528687, 2341.66045923341, 2342.039763696876, 2338.8479748816358, 2357.316378250305, 2364.6571247169236, 2396.4847557775074, 2398.70016247969, 2324.5118525109665, 2335.614623587106, 2350.4946436516148, 2354.458474550854, 2362.031567057507, 2301.309607117858, 2302.9884850575722, 2314.9189778357677, 2316.339843144704, 2327.918522851709, 2350.3771500552366, 2370.8221883358647, 2370.9407264818346, 2379.1316169598144, 2380.0955636858057, 7429.665665045032, 8508.234258916727, 8563.657453701844, 8572.828543429096, 8582.013068497077, 5105.795768169269, 5275.762590683428, 5292.153255479144, 5294.444083371229, 5300.655457395295, 3239.7084262416497, 3391.372596069735, 3615.096225943514, 3622.855085796565, 3631.455281707739, 1068.8770791359016, 1069.743261617214, 1091.8471414002047, 1109.9667009989714, 1112.1825458288151, 11050.502727377287, 11114.22200445969, 11228.382438965853, 11251.662450126509, 11272.380019098946, 3704.6480584344445, 4625.398698169217, 4730.550597693214, 4741.5495527924795, 4807.759612019385, 9617.951910240545, 9621.422714637145, 9623.085806848478, 9632.0, 9636.903630963741, 4449.354843547732, 5262.342207224097, 5264.447233040552, 5269.288646032813, 5269.446708095671, 4280.785960701953, 4541.772911354486, 4774.116788394089, 4830.951690483121, 4852.199387101089, 3811.866584669523, 3827.5981021992848, 3877.747946383476, 3929.724919255682, 3938.6061393860386, 1245.5391505143155, 1246.2896149194871, 1247.6319485751544, 1263.8041103628952, 1269.238456925837, 868.6199812513667, 882.0, 882.4881568690145, 904.841652710773, 915.9969833032951, 5134.743262836918, 5142.511461411234, 5330.120530112488, 5421.972890135524, 5439.864003399946, 429.89897374117055, 434.41137259014215, 448.89795551919957, 453.51926957425235, 467.90174063447057, 3781.0736369589627, 3800.4489349044234, 3847.8076096195077, 3851.7322328384184, 3885.8445662173194, 9887.357321774061, 10427.322224055411, 10437.0, 10441.321314114559, 10454.215933804842, 10357.274990750726, 10396.792064158675, 10396.792064158675, 10396.792064158675, 10401.375917444977, 7982.4412291140225, 8039.2764651180505, 8046.074701409413, 8065.435419520694, 8111.837763244702, 5417.674939503642, 5525.060739674345, 5593.384727679871, 5686.289213848269, 5688.5733569981585, 9291.953540232256, 9573.904260957579, 9598.32811703174, 9634.807303854024, 9755.073268039809, 9400.341976061884, 9661.951690241503, 9696.127348538665, 9745.074217949283, 9840.55717492729, 7238.131424229126, 7268.27317268269, 7300.45246606496, 7400.0, 7401.962990185015, 7929.762107136795, 7937.563434011185, 8021.358291336795, 8040.477368970998, 8052.758417247492, 6967.65161741921, 7105.18290396611, 7224.783256502314, 7267.418606511572, 7325.194228634739, 7095.361417472246, 7224.711011559479, 7248.710051598088, 7256.2018177999325, 7264.418846492374, 5777.335606405317, 6554.180727409074, 6691.397774200151, 6837.418819400406, 6839.760608378683, 4695.201815691409, 4785.569298763128, 4810.518948105161, 4832.468428472936, 4884.3650325457465, 4840.394950631171, 4852.126617208936, 4859.22638237048, 4983.551480366785, 4997.675151115166], "value": 4378.681413770279, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [255.51579756361696, 296.9020223326302, 299.36982651518565, 303.8678174993878, 309.5526963537689, 5075.645194319991, 5250.606204534661, 5286.973565132179, 5321.0, 5333.99975009372, 4475.753833539154, 4786.760661966905, 5174.43081261061, 5207.218917162428, 5296.761645725942, 3864.4976153100056, 4030.334994725881, 4066.4713587233764, 4092.918141637173, 4114.753114813106, 3235.3367559650333, 4059.863238293268, 4068.907630201039, 4112.362583799516, 4130.793460326987, 3981.0, 3991.762553608376, 3996.42051902474, 4007.0, 4020.030185209241, 3988.0458575297894, 4004.899877503057, 4008.5590585035625, 4010.639042486161, 4053.885181575061, 3072.523758817387, 3423.4180189367867, 3529.8941031769086, 3568.3041806847673, 3569.3932031554436, 4217.207686733157, 4826.300186472942, 5173.198154286091, 5293.7353132343615, 5296.894062118775, 4050.0887300357476, 4135.6898232632375, 4176.7076304658385, 4466.731996080245, 4504.054148628792, 4745.501722319142, 4806.735629540374, 5494.0, 5519.645943434862, 5565.0, 3710.9133146103527, 3721.6836568891677, 3805.676517496016, 3808.733388662795, 3837.3284675182013, 5225.608079394061, 5378.623496355257, 5771.538276937835, 5772.9711351442975, 5860.267466566679, 3363.3105213431186, 3618.782873027627, 3742.5321834770652, 3868.2650296443585, 3876.0697432616535, 3523.0, 3534.151803567125, 3768.0014796079063, 3927.8821635350987, 3930.6855451563806, 3811.294910441577, 4071.3485842265095, 4504.175808797442, 4534.841280555231, 4540.50163446062, 5525.480492864414, 5581.302337207849, 5584.6492104737645, 5602.74191614376, 5650.869826034809, 1203.9398030098635, 1204.9759004819855, 1213.6662417835166, 1234.8209509621054, 1246.4764798784531, 834.4284165346756, 857.100044952797, 861.7716305179133, 874.0341922175904, 875.7241468937265, 4937.086638971802, 4978.6017118630425, 4980.950190498121, 5021.899562008739, 5075.746212689351, 3964.5638979712207, 4606.424196975378, 5088.363954505687, 5395.62230643859, 5480.21892884814, 4470.418845550058, 5301.840944771663, 5354.3574771027725, 5378.381486129145, 5385.865353366197, 2651.7745991590546, 2817.9718202817708, 2821.2085325818252, 2833.6964996102024, 2838.4890719670243, 5216.504432078947, 5291.592325491892, 5487.42382049887, 5678.614981707693, 5700.8859822803315, 4724.0, 5134.2812006319145, 5328.893422131536, 5507.807226747044, 5574.331080270394, 5149.510796474329, 5269.525742683177, 5541.584381171348, 5544.390117087117, 5562.666240025612, 5026.0, 5305.1776974569, 5343.893122137613, 5431.538319242909, 5494.313210848644, 4596.540345965377, 4798.089557011025, 5117.462666420121, 5340.359156901197, 5366.946330536801, 5133.460986596354, 5230.660007099678, 5264.9210261846865, 5480.314960629921, 5548.889022219452, 3416.8382749865164, 3533.5229743984232, 3724.6906917711194, 3859.1895701902913, 3864.9806750966063, 5008.474110218521, 5735.025045742151, 5746.2529871116485, 5767.5385969121535, 5777.104548794943, 5369.436209198134, 5575.0, 5594.832155035356, 5596.776128954796, 5603.719814009365, 4607.935722744338, 5504.889902202013, 5534.972325138348, 5554.250176226158, 5594.356648985256, 5179.326687530597, 5311.229871668664, 5348.384935732441, 5356.812511562077, 5489.106258340866, 4869.683470574401, 5587.262241501716, 5587.8824235152315, 5595.580331475075, 5603.411641777555, 4928.408590969107, 5244.370675518962, 5466.808661696821, 5478.753456094406, 5542.722863856871], "value": 3984.6339192404416, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.5953686533998, 722.2813300765739, 749.2095838889969, 777.6850375597885, 785.8664027115393, 11981.700457488556, 14079.296035198238, 14425.350859211334, 15810.209489525536, 16690.41583544576, 13701.246431446292, 15064.774028389556, 15136.470223542177, 15251.466198683049, 15273.389064437437, 788.136989995955, 819.778659761864, 820.4420993724265, 821.6302663801283, 822.4653974916292, 643.83904023994, 658.1411258307907, 789.8301865099003, 812.5733989655419, 816.9019717633862, 849.0278630967539, 862.3704695572236, 865.5455885660016, 866.4498043742205, 866.8439680857443, 855.8502262104138, 857.716953405381, 858.0689951402735, 858.9441686290371, 859.2567429173782, 499.9650723003384, 502.6807976934636, 509.2666560153367, 512.8897287083295, 639.4532674563275, 12202.877971220352, 13793.310334483236, 14313.212773297464, 14373.0, 14690.55928322152, 13072.673183170496, 13387.732245355135, 13498.392572334269, 13561.593152205449, 13645.249511276876, 11319.377434241114, 11467.885321146765, 11543.711407214887, 11554.942225288904, 11610.593629223022, 3501.439769636846, 3585.1933315004176, 3592.425211966099, 3747.850085996557, 3769.6795772359383, 78025.60987195028, 85548.57225713832, 87131.0, 87962.0, 88056.0, 10360.523585678297, 10407.739806504898, 10752.462376881125, 10834.945825270823, 10862.674119776419, 20279.594408111756, 20392.490187745425, 20446.284380046924, 20581.691274630593, 20849.79150208509, 7637.15991240963, 7733.651985660659, 7772.417068719868, 7894.368450523944, 7931.881021784678, 4042.272390969595, 4155.937660935088, 4200.600942910419, 4268.125034367947, 4368.5194628590825, 2992.0, 3416.231347946692, 3525.6474352564537, 3528.400171970771, 3529.7705649133236, 624.7782037376666, 627.7520379450092, 643.6749441531996, 644.091830518966, 648.1120864415686, 580.2862479150668, 614.9422992452974, 636.2905360522983, 658.1707049118146, 675.8344205669642, 8781.868271975925, 9113.726588202364, 9186.0, 9254.814903701888, 9385.245901639262, 543.6058857328484, 606.5117580347862, 615.5906322295623, 656.3666062249952, 660.2736989311815, 4274.487061552633, 4312.870613881589, 4414.271645178574, 4533.750643714628, 4542.523035081335, 66456.33543664598, 87367.56316218378, 91481.0, 91752.5412372934, 105588.0, 60477.0, 65384.67307663431, 67791.66104169447, 92192.0, 92893.0, 82249.58875205586, 84462.0, 84786.0, 85773.57113214643, 87434.56282718545, 8673.219410253107, 8676.479411235094, 8747.868781968404, 8924.86612700823, 9174.082591740773, 49618.50381496283, 51035.0, 51907.74046129745, 52124.739376302874, 52720.73639631777, 57801.71099144477, 76367.0, 76431.0, 80044.59977700074, 80617.0, 12012.159148859846, 12138.33239171854, 12180.817287740869, 12400.00799936021, 12432.440540175532, 14057.367418466398, 14101.717965640424, 14289.571312860631, 14585.708285834437, 14821.036632618845, 12232.816507752214, 12534.811977820522, 12912.935435322763, 13179.802302965654, 13771.931140344233, 12652.810207847073, 12824.422900969665, 13360.732785344433, 13897.235652039235, 14170.858291417366, 13057.08600397963, 13110.344482776014, 13305.733885322046, 13501.797473037715, 13894.930525347309, 10174.745631359276, 10711.250212485364, 11002.449877505933, 11422.143339249424, 11887.0, 11410.94294528522, 11450.713732156762, 11564.054912774838, 11600.0, 11718.355490448108], "value": 7166.413745979926, "unit": "score"}], "name": "dromaeo_css", "value": 6894.683060785486, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 115.0, 112.0, 114.0, 114.0, 111.0, 114.0, 117.0, 112.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 112.0, 113.0, 168.0, 142.0, 116.0, 115.0, 136.0, 176.0, 112.0], "value": 133.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 181.0, 187.0, 184.0, 182.0, 185.0, 183.0, 182.0, 182.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 57.0, 59.0, 89.0, 61.0, 58.0, 59.0, 58.0, 81.0, 57.0], "value": 66.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 94.0, 69.0, 68.0, 70.0, 88.0, 67.0, 69.0, 91.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 86.0, 88.0, 86.0, 89.0, 86.0, 89.0, 86.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 80.0, 80.0, 82.0, 83.0, 84.0, 85.0, 82.0, 80.0], "value": 81.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 54.0, 53.0, 55.0, 56.0, 58.0, 56.0, 56.0, 57.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 65.0, 70.0, 81.0, 76.0, 73.0, 71.0, 70.0, 71.0, 70.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 86.0, 84.0, 83.0, 82.0, 82.0, 88.0, 84.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 147.0, 144.0, 145.0, 154.0, 157.0, 156.0, 194.0, 158.0], "value": 154.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 169.0, 168.0, 167.0, 222.0, 225.0, 163.0, 166.0, 170.0], "value": 178.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 66.0, 67.0, 65.0, 66.0, 65.0, 66.0, 66.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1446.4999999999998, "alertThreshold": 2.0}]} 22:27:05 INFO - Return code: 0 22:27:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:27:05 ERROR - # TBPL SUCCESS # 22:27:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:27:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:27:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:27:05 INFO - 2016-11-14 22:27:05.740 screenresolution[1653:9812] starting screenresolution argv=screenresolution get 22:27:05 INFO - 2016-11-14 22:27:05.743 screenresolution[1653:9812] Display 0: 1600x1200x32@60 22:27:05 INFO - 2016-11-14 22:27:05.749 screenresolution[1654:9815] starting screenresolution argv=screenresolution list 22:27:05 INFO - Available Modes on Display 0 22:27:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:27:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:27:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:27:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:27:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:27:05 INFO - Intel Iris: 22:27:05 INFO - Chipset Model: Intel Iris 22:27:05 INFO - Type: GPU 22:27:05 INFO - Bus: Built-In 22:27:05 INFO - VRAM (Dynamic, Max): 1536 MB 22:27:05 INFO - Vendor: Intel (0x8086) 22:27:05 INFO - Device ID: 0x0a2e 22:27:05 INFO - Revision ID: 0x0009 22:27:05 INFO - Displays: 22:27:05 INFO - Display: 22:27:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:27:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:27:05 INFO - Display Serial Number: 1600x1200 60 22:27:05 INFO - Main Display: Yes 22:27:05 INFO - Mirror: Off 22:27:05 INFO - Online: Yes 22:27:05 INFO - Rotation: Supported 22:27:05 INFO - Return code: 0 22:27:05 INFO - Running post-action listener: _resource_record_post_action 22:27:05 INFO - [mozharness: 2016-11-15 06:27:05.983850Z] Finished run-tests step (success) 22:27:05 INFO - Running post-run listener: _resource_record_post_run 22:27:06 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 37512192; Write bytes: 655454720; Read time: 241; Write time: 1901 22:27:06 INFO - TinderboxPrint: CPU usage
53.3% 22:27:06 INFO - TinderboxPrint: I/O read bytes / time
37,512,192 / 241 22:27:06 INFO - TinderboxPrint: I/O write bytes / time
655,454,720 / 1,901 22:27:06 INFO - TinderboxPrint: CPU idle
2,264.3 (46.7%) 22:27:06 INFO - TinderboxPrint: CPU system
969.2 (20.0%) 22:27:06 INFO - TinderboxPrint: CPU user
1,620.1 (33.4%) 22:27:06 INFO - TinderboxPrint: Swap in / out
390,922,240 / 0 22:27:06 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 352653824; Write bytes: 348772352; Read time: 27627; Write time: 764 22:27:06 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 34456576; Write bytes: 296438272; Read time: 218; Write time: 1101 22:27:06 INFO - Running post-run listener: _upload_blobber_files 22:27:06 INFO - Blob upload gear active. 22:27:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:27:06 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 22:27:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:27:06 INFO - (blobuploader) - INFO - Open directory for files ... 22:27:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:27:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:27:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:27:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:27:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:27:07 INFO - (blobuploader) - INFO - Done attempting. 22:27:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:27:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:27:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:27:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:27:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:27:09 INFO - (blobuploader) - INFO - Done attempting. 22:27:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:27:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:27:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:27:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:27:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:27:11 INFO - (blobuploader) - INFO - Done attempting. 22:27:11 INFO - (blobuploader) - INFO - Iteration through files over. 22:27:11 INFO - Return code: 0 22:27:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:27:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:27:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8bc80e92e48a48f5bfa1738a862df980dce1ed7a095a0691ed63fe7556f675ef0482c124ce9d3ebc54655e55ded8fb4b5c6ed8d2794a61c4c53b5d9073608e46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3290ded797933bf88a139e9c8f035624be458d86e945d0e7754f3653ded24f990b4b4091cbd18993b5b291fb2bc3b1b1075adc8241789fd8195750021c5bcf2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1e5a4b2ebac253ea712a44447aa8ecc8e6d1d74f8f87f95dbd3c03efac0e6d56b9d9b40606714333ac202c79a0c6ab5dddc42f54c288c2110e838a02322f228"} 22:27:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:27:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:27:11 INFO - Contents: 22:27:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8bc80e92e48a48f5bfa1738a862df980dce1ed7a095a0691ed63fe7556f675ef0482c124ce9d3ebc54655e55ded8fb4b5c6ed8d2794a61c4c53b5d9073608e46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3290ded797933bf88a139e9c8f035624be458d86e945d0e7754f3653ded24f990b4b4091cbd18993b5b291fb2bc3b1b1075adc8241789fd8195750021c5bcf2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1e5a4b2ebac253ea712a44447aa8ecc8e6d1d74f8f87f95dbd3c03efac0e6d56b9d9b40606714333ac202c79a0c6ab5dddc42f54c288c2110e838a02322f228"} 22:27:11 INFO - Running post-run listener: copy_logs_to_upload_dir 22:27:11 INFO - Copying logs to upload dir... 22:27:11 INFO - mkdir: /builds/slave/test/build/upload/logs 22:27:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.669826 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-14 22:27:11.278961) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 22:27:11.282492) ========= 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.6WFMISYXhL/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.aSyxBcn6jl/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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/Mozilla-Aurora/sha512/8bc80e92e48a48f5bfa1738a862df980dce1ed7a095a0691ed63fe7556f675ef0482c124ce9d3ebc54655e55ded8fb4b5c6ed8d2794a61c4c53b5d9073608e46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3290ded797933bf88a139e9c8f035624be458d86e945d0e7754f3653ded24f990b4b4091cbd18993b5b291fb2bc3b1b1075adc8241789fd8195750021c5bcf2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1e5a4b2ebac253ea712a44447aa8ecc8e6d1d74f8f87f95dbd3c03efac0e6d56b9d9b40606714333ac202c79a0c6ab5dddc42f54c288c2110e838a02322f228"} build_url:https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010968 build_url: 'https://queue.taskcluster.net/v1/task/YPuPq3CATme1o2djrk6LaA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8bc80e92e48a48f5bfa1738a862df980dce1ed7a095a0691ed63fe7556f675ef0482c124ce9d3ebc54655e55ded8fb4b5c6ed8d2794a61c4c53b5d9073608e46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3290ded797933bf88a139e9c8f035624be458d86e945d0e7754f3653ded24f990b4b4091cbd18993b5b291fb2bc3b1b1075adc8241789fd8195750021c5bcf2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1e5a4b2ebac253ea712a44447aa8ecc8e6d1d74f8f87f95dbd3c03efac0e6d56b9d9b40606714333ac202c79a0c6ab5dddc42f54c288c2110e838a02322f228"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 22:27:11.316267) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:27:11.316591) ========= 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.6WFMISYXhL/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.aSyxBcn6jl/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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.005149 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:27:11.365471) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 22:27:11.365788) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 22:27:11.366232) ========= ========= Total master_lag: 0.18 =========