builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0263 starttime: 1478142219.59 results: success (0) buildid: 20161102171723 builduid: 3a4d89bea69d45f29d6b01ad291d8cc9 revision: ac55a6776435142feebf3c20bbabfee100686416 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:39.587707) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:39.588144) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:39.588417) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:39.613531) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:39.613919) ========= 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.27KSup06V9/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.OlbVQYAbgC/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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-02 20:03:39-- 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% 18.3M=0.001s 2016-11-02 20:03:39 (18.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125277 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:39.760924) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:39.761374) ========= 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.27KSup06V9/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.OlbVQYAbgC/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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.024966 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:39.809404) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-02 20:03:39.809831) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ac55a6776435142feebf3c20bbabfee100686416 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev ac55a6776435142feebf3c20bbabfee100686416 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.27KSup06V9/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.OlbVQYAbgC/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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-02 20:03:39,892 truncating revision to first 12 chars 2016-11-02 20:03:39,892 Setting DEBUG logging. 2016-11-02 20:03:39,892 attempt 1/10 2016-11-02 20:03:39,892 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ac55a6776435?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-02 20:03:41,309 unpacking tar archive at: mozilla-central-ac55a6776435/testing/mozharness/ program finished with exit code 0 elapsedTime=1.706476 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-02 20:03:41.537354) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:41.537692) ========= script_repo_revision: ac55a6776435142feebf3c20bbabfee100686416 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:41.538127) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:41.538416) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-02 20:03:41.556065) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-02 20:03:41.556479) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.27KSup06V9/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.OlbVQYAbgC/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 20:03:41 INFO - MultiFileLogger online at 20161102 20:03:41 in /builds/slave/test 20:03:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 20:03:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:03:41 INFO - {'append_to_log': False, 20:03:41 INFO - 'base_work_dir': '/builds/slave/test', 20:03:41 INFO - 'blob_upload_branch': 'Firefox', 20:03:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:03:41 INFO - 'branch': 'Firefox', 20:03:41 INFO - 'buildbot_json_path': 'buildprops.json', 20:03:41 INFO - 'config_files': ('talos/mac_config.py',), 20:03:41 INFO - 'default_actions': ('clobber', 20:03:41 INFO - 'read-buildbot-config', 20:03:41 INFO - 'download-and-extract', 20:03:41 INFO - 'populate-webroot', 20:03:41 INFO - 'create-virtualenv', 20:03:41 INFO - 'install', 20:03:41 INFO - 'run-tests'), 20:03:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:03:41 INFO - 'download_minidump_stackwalk': True, 20:03:41 INFO - 'download_symbols': 'ondemand', 20:03:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:03:41 INFO - 'tooltool.py': '/tools/tooltool.py', 20:03:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:03:41 INFO - '/tools/misc-python/virtualenv.py')}, 20:03:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:03:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:03:41 INFO - 'installer_path': 'installer.exe', 20:03:41 INFO - 'log_level': 'info', 20:03:41 INFO - 'log_name': 'talos', 20:03:41 INFO - 'log_to_console': True, 20:03:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:03:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:03:41 INFO - 'opt_config_files': (), 20:03:41 INFO - 'pip_index': False, 20:03:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:03:41 INFO - 'cmd': ('bash', 20:03:41 INFO - '-c', 20:03:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:03:41 INFO - 'enabled': True, 20:03:41 INFO - 'halt_on_failure': False, 20:03:41 INFO - 'name': 'check_screen_resolution'},), 20:03:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:03:41 INFO - 'cmd': ('bash', 20:03:41 INFO - '-c', 20:03:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:03:41 INFO - 'enabled': True, 20:03:41 INFO - 'halt_on_failure': False, 20:03:41 INFO - 'name': 'check_screen_resolution'},), 20:03:41 INFO - 'run_cmd_checks_enabled': True, 20:03:41 INFO - 'sps_profile': False, 20:03:41 INFO - 'sps_profile_interval': 0, 20:03:41 INFO - 'suite': 'dromaeojs-e10s', 20:03:41 INFO - 'system_bits': '32', 20:03:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:03:41 INFO - 'title': 't-yosemite-r7-0263', 20:03:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:03:41 INFO - 'use_talos_json': True, 20:03:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:03:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:03:41 INFO - 'work_dir': 'build'} 20:03:41 INFO - [mozharness: 2016-11-03 03:03:41.761722Z] Running clobber step. 20:03:41 INFO - Running pre-action listener: _resource_record_pre_action 20:03:41 INFO - Running main action method: clobber 20:03:41 INFO - rmtree: /builds/slave/test/build 20:03:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:03:44 INFO - Running post-action listener: _resource_record_post_action 20:03:44 INFO - [mozharness: 2016-11-03 03:03:44.008698Z] Finished clobber step (success) 20:03:44 INFO - [mozharness: 2016-11-03 03:03:44.008845Z] Running read-buildbot-config step. 20:03:44 INFO - Running pre-action listener: _resource_record_pre_action 20:03:44 INFO - Running main action method: read_buildbot_config 20:03:44 INFO - Using buildbot properties: 20:03:44 INFO - { 20:03:44 INFO - "project": "", 20:03:44 INFO - "product": "firefox", 20:03:44 INFO - "script_repo_revision": "production", 20:03:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 20:03:44 INFO - "repository": "", 20:03:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 20:03:44 INFO - "buildid": "20161102171723", 20:03:44 INFO - "pgo_build": "False", 20:03:44 INFO - "basedir": "/builds/slave/test", 20:03:44 INFO - "buildnumber": 85, 20:03:44 INFO - "slavename": "t-yosemite-r7-0263", 20:03:44 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 20:03:44 INFO - "platform": "yosemite_r7", 20:03:44 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 20:03:44 INFO - "branch": "mozilla-central", 20:03:44 INFO - "repo_path": "mozilla-central", 20:03:44 INFO - "revision": "ac55a6776435142feebf3c20bbabfee100686416", 20:03:44 INFO - "stage_platform": "macosx64", 20:03:44 INFO - "builduid": "3a4d89bea69d45f29d6b01ad291d8cc9", 20:03:44 INFO - "slavebuilddir": "test" 20:03:44 INFO - } 20:03:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 20:03:44 INFO - Running post-action listener: _resource_record_post_action 20:03:44 INFO - [mozharness: 2016-11-03 03:03:44.012097Z] Finished read-buildbot-config step (success) 20:03:44 INFO - [mozharness: 2016-11-03 03:03:44.012219Z] Running download-and-extract step. 20:03:44 INFO - Running pre-action listener: _resource_record_pre_action 20:03:44 INFO - Running main action method: download_and_extract 20:03:44 INFO - mkdir: /builds/slave/test/build/tests 20:03:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:03:44 INFO - trying https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 20:03:44 INFO - Downloading https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 20:03:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 20:03:44 INFO - Downloaded 1199 bytes. 20:03:44 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 20:03:44 INFO - Using the following test package requirements: 20:03:44 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 20:03:44 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:03:44 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 20:03:44 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:03:44 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 20:03:44 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:03:44 INFO - u'jsshell-mac.zip'], 20:03:44 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:03:44 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 20:03:44 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 20:03:44 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:03:44 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 20:03:44 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:03:44 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 20:03:44 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:03:44 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 20:03:44 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:03:44 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 20:03:44 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:03:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 20:03:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:03:44 INFO - Fetch https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 20:03:45 INFO - Http code: 200 20:03:45 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 20:03:45 INFO - x-amz-cf-id: c4nFR6dDGaiAQRLOX4jzRAwEL4IlD6LMerkGy5UxNQmoofUCiUq1_Q== 20:03:45 INFO - x-amz-version-id: uaf3.uAwB1T559tumwSlmQ3oSJ_RXYDW 20:03:45 INFO - Expected file size: 16389270 20:03:45 INFO - Obtained file size: 16389270 20:03:52 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:03:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 20:03:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:03:52 INFO - Fetch https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 20:03:53 INFO - Http code: 200 20:03:53 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 20:03:53 INFO - x-amz-cf-id: 0dSYC_7LPCHQRwPa8EJf6gbL4Q6R1zd3W9-JgEvYu6Mu88jks59cSA== 20:03:53 INFO - x-amz-version-id: uaf3.uAwB1T559tumwSlmQ3oSJ_RXYDW 20:03:53 INFO - Expected file size: 16389270 20:03:53 INFO - Obtained file size: 16389270 20:03:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 20:03:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:03:59 INFO - Fetch https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 20:04:00 INFO - Http code: 200 20:04:00 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 20:04:00 INFO - x-amz-cf-id: bE5jczXgkcYxS5Tbq96Kdjc3hhBAnKjqJF0MMzfB0Yq3oau09xrAUg== 20:04:00 INFO - x-amz-version-id: SX5O70OVlToACv.KCZkEyPpy6O3C1rl4 20:04:00 INFO - Expected file size: 14409038 20:04:00 INFO - Obtained file size: 14409038 20:04:01 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')]} 20:04:01 INFO - trying https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:04:01 INFO - Downloading https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 20:04:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 20:04:04 INFO - Downloaded 111881450 bytes. 20:04:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:04:04 INFO - mkdir: /builds/slave/test/properties 20:04:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:04:04 INFO - Writing to file /builds/slave/test/properties/build_url 20:04:04 INFO - Contents: 20:04:04 INFO - build_url:https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:04:04 INFO - Running post-action listener: _resource_record_post_action 20:04:04 INFO - Running post-action listener: set_extra_try_arguments 20:04:04 INFO - [mozharness: 2016-11-03 03:04:04.961356Z] Finished download-and-extract step (success) 20:04:04 INFO - [mozharness: 2016-11-03 03:04:04.961501Z] Running populate-webroot step. 20:04:04 INFO - Running pre-action listener: _resource_record_pre_action 20:04:04 INFO - Running main action method: populate_webroot 20:04:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:04:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:04:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:04:04 INFO - '--tppagecycles', 20:04:04 INFO - '7'], 20:04:04 INFO - 'tests': ['tsvgm']}}, 20:04:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:04:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:04:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:04:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:04:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:04:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:04:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:04:04 INFO - 'talos_options': ['--disable-e10s'], 20:04:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:04:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:04:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:04:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:04:04 INFO - 'talos_options': ['--disable-e10s'], 20:04:04 INFO - 'tests': ['damp', 'tps']}, 20:04:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:04:04 INFO - 'tests': ['damp', 'tps']}, 20:04:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:04:04 INFO - 'tests': ['dromaeo_dom']}, 20:04:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:04:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:04:04 INFO - 'tests': ['basic_compositor_video']}, 20:04:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:04:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:04:04 INFO - 'tests': ['a11yr', 20:04:04 INFO - 'ts_paint', 20:04:04 INFO - 'tpaint', 20:04:04 INFO - 'sessionrestore', 20:04:04 INFO - 'sessionrestore_no_auto_restore', 20:04:04 INFO - 'tabpaint']}, 20:04:04 INFO - 'other-e10s': {'tests': ['a11yr', 20:04:04 INFO - 'ts_paint', 20:04:04 INFO - 'tpaint', 20:04:04 INFO - 'sessionrestore', 20:04:04 INFO - 'sessionrestore_no_auto_restore', 20:04:04 INFO - 'tabpaint']}, 20:04:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:04:04 INFO - 'ts_paint', 20:04:04 INFO - 'tpaint', 20:04:04 INFO - 'sessionrestore', 20:04:04 INFO - 'sessionrestore_no_auto_restore', 20:04:04 INFO - 'tabpaint']}, 20:04:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:04:04 INFO - 'ts_paint', 20:04:04 INFO - 'tpaint', 20:04:04 INFO - 'sessionrestore', 20:04:04 INFO - 'sessionrestore_no_auto_restore', 20:04:04 INFO - 'tabpaint']}, 20:04:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:04:04 INFO - 'tests': ['a11yr', 20:04:04 INFO - 'ts_paint', 20:04:04 INFO - 'tpaint', 20:04:04 INFO - 'sessionrestore', 20:04:04 INFO - 'sessionrestore_no_auto_restore', 20:04:04 INFO - 'tabpaint']}, 20:04:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:04:04 INFO - 'tests': ['a11yr', 20:04:04 INFO - 'ts_paint', 20:04:04 INFO - 'tpaint', 20:04:04 INFO - 'sessionrestore', 20:04:04 INFO - 'sessionrestore_no_auto_restore', 20:04:04 INFO - 'tabpaint']}, 20:04:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:04:04 INFO - 'tests': ['tsvgx', 20:04:04 INFO - 'tsvgr_opacity', 20:04:04 INFO - 'tart', 20:04:04 INFO - 'tscrollx', 20:04:04 INFO - 'cart']}, 20:04:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:04:04 INFO - 'tsvgr_opacity', 20:04:04 INFO - 'tart', 20:04:04 INFO - 'tscrollx', 20:04:04 INFO - 'cart']}, 20:04:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:04:04 INFO - 'talos_options': ['--disable-e10s'], 20:04:04 INFO - 'tests': ['tp5o']}, 20:04:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:04:04 INFO - 'tests': ['tp5o']}, 20:04:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:04:04 INFO - 'talos_options': ['--disable-e10s', 20:04:04 INFO - '--xperf_path', 20:04:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:04:04 INFO - 'tests': ['tp5n']}, 20:04:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:04:04 INFO - 'talos_options': ['--xperf_path', 20:04:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:04:04 INFO - 'tests': ['tp5n']}}, 20:04:04 INFO - 'talos.zip': {'path': '', 20:04:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:04:04 INFO - Running post-action listener: _resource_record_post_action 20:04:04 INFO - [mozharness: 2016-11-03 03:04:04.980107Z] Finished populate-webroot step (success) 20:04:04 INFO - [mozharness: 2016-11-03 03:04:04.980213Z] Running create-virtualenv step. 20:04:04 INFO - Running pre-action listener: _resource_record_pre_action 20:04:04 INFO - Running main action method: create_virtualenv 20:04:04 INFO - Creating virtualenv /builds/slave/test/build/venv 20:04:04 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 20:04:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:04:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:04:05 INFO - Using real prefix '/tools/python27' 20:04:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:04:05 INFO - Installing distribute.............................................................................................................................................................................................done. 20:04:08 INFO - Installing pip.................done. 20:04:08 INFO - Return code: 0 20:04:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:04:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:04:08 INFO - Reading from file tmpfile_stdout 20:04:08 INFO - Output received: 20:04:08 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) 20:04:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:04:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e569490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32256b140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37dd30>, '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 0x10e1a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e375b20>, '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.27KSup06V9/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.OlbVQYAbgC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 20:04:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:04:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:04:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.27KSup06V9/Render', 20:04:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:08 INFO - 'HOME': '/Users/cltbld', 20:04:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:08 INFO - 'LOGNAME': 'cltbld', 20:04:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:08 INFO - 'MOZ_NO_REMOTE': '1', 20:04:08 INFO - 'NO_EM_RESTART': '1', 20:04:08 INFO - 'PAGER': '/bin/cat', 20:04:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:08 INFO - 'PWD': '/builds/slave/test', 20:04:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:08 INFO - 'SHELL': '/bin/bash', 20:04:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OlbVQYAbgC/Listeners', 20:04:08 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 20:04:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:08 INFO - 'USER': 'cltbld', 20:04:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:08 INFO - 'XPC_FLAGS': '0x0', 20:04:08 INFO - 'XPC_SERVICE_NAME': '0', 20:04:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:09 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 20:04:09 INFO - Cleaning up... 20:04:09 INFO - Return code: 0 20:04:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:04:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:09 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 0x10e569490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32256b140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37dd30>, '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 0x10e1a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e375b20>, '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.27KSup06V9/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.OlbVQYAbgC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 20:04:09 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 20:04:09 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 20:04:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.27KSup06V9/Render', 20:04:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:09 INFO - 'HOME': '/Users/cltbld', 20:04:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:09 INFO - 'LOGNAME': 'cltbld', 20:04:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:09 INFO - 'MOZ_NO_REMOTE': '1', 20:04:09 INFO - 'NO_EM_RESTART': '1', 20:04:09 INFO - 'PAGER': '/bin/cat', 20:04:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:09 INFO - 'PWD': '/builds/slave/test', 20:04:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:09 INFO - 'SHELL': '/bin/bash', 20:04:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OlbVQYAbgC/Listeners', 20:04:09 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 20:04:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:09 INFO - 'USER': 'cltbld', 20:04:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:09 INFO - 'XPC_FLAGS': '0x0', 20:04:09 INFO - 'XPC_SERVICE_NAME': '0', 20:04:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:09 INFO - Downloading/unpacking psutil>=3.1.1 20:04:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:04:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:04:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:04:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:04:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:04:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:04:12 INFO - Installing collected packages: psutil 20:04:12 INFO - Running setup.py install for psutil 20:04:12 INFO - building 'psutil._psutil_osx' extension 20:04:12 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 20:04:13 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 20:04:13 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 20:04:13 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 20:04:13 INFO - building 'psutil._psutil_posix' extension 20:04:13 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 20:04:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:04:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:04:13 INFO - ^ 20:04:13 INFO - 1 warning generated. 20:04:13 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 20:04:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:04:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:04:14 INFO - Successfully installed psutil 20:04:14 INFO - Cleaning up... 20:04:14 INFO - Return code: 0 20:04:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:04:14 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')]} 20:04:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:14 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')]} 20:04:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:14 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 0x10e569490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32256b140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37dd30>, '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 0x10e1a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e375b20>, '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.27KSup06V9/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.OlbVQYAbgC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 20:04:14 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 20:04:14 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 20:04:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.27KSup06V9/Render', 20:04:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:14 INFO - 'HOME': '/Users/cltbld', 20:04:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:14 INFO - 'LOGNAME': 'cltbld', 20:04:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:14 INFO - 'MOZ_NO_REMOTE': '1', 20:04:14 INFO - 'NO_EM_RESTART': '1', 20:04:14 INFO - 'PAGER': '/bin/cat', 20:04:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:14 INFO - 'PWD': '/builds/slave/test', 20:04:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:14 INFO - 'SHELL': '/bin/bash', 20:04:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OlbVQYAbgC/Listeners', 20:04:14 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 20:04:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:14 INFO - 'USER': 'cltbld', 20:04:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:14 INFO - 'XPC_FLAGS': '0x0', 20:04:14 INFO - 'XPC_SERVICE_NAME': '0', 20:04:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:04:14 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 20:04:14 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 20:04:14 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 20:04:14 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 20:04:14 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 20:04:14 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 20:04:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:04:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:04:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:04:16 INFO - Installing collected packages: mozsystemmonitor 20:04:16 INFO - Running setup.py install for mozsystemmonitor 20:04:17 INFO - Successfully installed mozsystemmonitor 20:04:17 INFO - Cleaning up... 20:04:17 INFO - Return code: 0 20:04:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:04:17 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')]} 20:04:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:17 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')]} 20:04:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:17 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 0x10e569490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32256b140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37dd30>, '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 0x10e1a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e375b20>, '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.27KSup06V9/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.OlbVQYAbgC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 20:04:17 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 20:04:17 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 20:04:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.27KSup06V9/Render', 20:04:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:17 INFO - 'HOME': '/Users/cltbld', 20:04:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:17 INFO - 'LOGNAME': 'cltbld', 20:04:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:17 INFO - 'MOZ_NO_REMOTE': '1', 20:04:17 INFO - 'NO_EM_RESTART': '1', 20:04:17 INFO - 'PAGER': '/bin/cat', 20:04:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:17 INFO - 'PWD': '/builds/slave/test', 20:04:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:17 INFO - 'SHELL': '/bin/bash', 20:04:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OlbVQYAbgC/Listeners', 20:04:17 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 20:04:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:17 INFO - 'USER': 'cltbld', 20:04:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:17 INFO - 'XPC_FLAGS': '0x0', 20:04:17 INFO - 'XPC_SERVICE_NAME': '0', 20:04:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:17 INFO - Downloading/unpacking jsonschema==2.5.1 20:04:17 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 20:04:17 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 20:04:17 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 20:04:17 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 20:04:17 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 20:04:17 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 20:04:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:04:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:04:19 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 20:04:19 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 20:04:19 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 20:04:19 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 20:04:19 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 20:04:19 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 20:04:20 INFO - Downloading functools32-3.2.3-2.tar.gz 20:04:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:04:20 INFO - warning: no files found matching '*.txt' 20:04:20 INFO - no previously-included directories found matching 'build' 20:04:20 INFO - no previously-included directories found matching 'dist' 20:04:20 INFO - no previously-included directories found matching '.git*' 20:04:20 INFO - Installing collected packages: jsonschema, functools32 20:04:20 INFO - Running setup.py install for functools32 20:04:20 INFO - warning: no files found matching '*.txt' 20:04:20 INFO - no previously-included directories found matching 'build' 20:04:20 INFO - no previously-included directories found matching 'dist' 20:04:20 INFO - no previously-included directories found matching '.git*' 20:04:20 INFO - Successfully installed jsonschema functools32 20:04:20 INFO - Cleaning up... 20:04:20 INFO - Return code: 0 20:04:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:04:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:20 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 0x10e569490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32256b140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37dd30>, '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 0x10e1a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e375b20>, '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.27KSup06V9/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.OlbVQYAbgC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 20:04:20 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 20:04:20 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 20:04:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.27KSup06V9/Render', 20:04:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:20 INFO - 'HOME': '/Users/cltbld', 20:04:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:20 INFO - 'LOGNAME': 'cltbld', 20:04:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:20 INFO - 'MOZ_NO_REMOTE': '1', 20:04:20 INFO - 'NO_EM_RESTART': '1', 20:04:20 INFO - 'PAGER': '/bin/cat', 20:04:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:20 INFO - 'PWD': '/builds/slave/test', 20:04:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:20 INFO - 'SHELL': '/bin/bash', 20:04:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OlbVQYAbgC/Listeners', 20:04:20 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 20:04:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:20 INFO - 'USER': 'cltbld', 20:04:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:20 INFO - 'XPC_FLAGS': '0x0', 20:04:20 INFO - 'XPC_SERVICE_NAME': '0', 20:04:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:04:20 INFO - Cleaning up... 20:04:20 INFO - Return code: 0 20:04:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:04:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:20 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 0x10e569490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32256b140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37dd30>, '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 0x10e1a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e375b20>, '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.27KSup06V9/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.OlbVQYAbgC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 20:04:20 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 20:04:20 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 20:04:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.27KSup06V9/Render', 20:04:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:20 INFO - 'HOME': '/Users/cltbld', 20:04:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:20 INFO - 'LOGNAME': 'cltbld', 20:04:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:20 INFO - 'MOZ_NO_REMOTE': '1', 20:04:20 INFO - 'NO_EM_RESTART': '1', 20:04:20 INFO - 'PAGER': '/bin/cat', 20:04:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:20 INFO - 'PWD': '/builds/slave/test', 20:04:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:20 INFO - 'SHELL': '/bin/bash', 20:04:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OlbVQYAbgC/Listeners', 20:04:20 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 20:04:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:20 INFO - 'USER': 'cltbld', 20:04:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:20 INFO - 'XPC_FLAGS': '0x0', 20:04:20 INFO - 'XPC_SERVICE_NAME': '0', 20:04:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:21 INFO - Downloading/unpacking blobuploader==1.2.4 20:04:21 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 20:04:21 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 20:04:21 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 20:04:21 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 20:04:21 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 20:04:21 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 20:04:23 INFO - Downloading blobuploader-1.2.4.tar.gz 20:04:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:04:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:04:23 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 20:04:23 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 20:04:23 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 20:04:23 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 20:04:23 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 20:04:23 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 20:04:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:04:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:04:24 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 20:04:24 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 20:04:24 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 20:04:24 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 20:04:24 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 20:04:24 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 20:04:24 INFO - Downloading docopt-0.6.1.tar.gz 20:04:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:04:25 INFO - Installing collected packages: blobuploader, requests, docopt 20:04:25 INFO - Running setup.py install for blobuploader 20:04:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:04:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:04:25 INFO - Running setup.py install for requests 20:04:25 INFO - Running setup.py install for docopt 20:04:25 INFO - Successfully installed blobuploader requests docopt 20:04:25 INFO - Cleaning up... 20:04:25 INFO - Return code: 0 20:04:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:04: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')]} 20:04:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04: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')]} 20:04:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:25 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 0x10e569490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32256b140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37dd30>, '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 0x10e1a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e375b20>, '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.27KSup06V9/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.OlbVQYAbgC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 20:04:25 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 20:04:25 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 20:04:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.27KSup06V9/Render', 20:04:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:25 INFO - 'HOME': '/Users/cltbld', 20:04:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:25 INFO - 'LOGNAME': 'cltbld', 20:04:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:25 INFO - 'MOZ_NO_REMOTE': '1', 20:04:25 INFO - 'NO_EM_RESTART': '1', 20:04:25 INFO - 'PAGER': '/bin/cat', 20:04:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:25 INFO - 'PWD': '/builds/slave/test', 20:04:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:25 INFO - 'SHELL': '/bin/bash', 20:04:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OlbVQYAbgC/Listeners', 20:04:25 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 20:04:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:25 INFO - 'USER': 'cltbld', 20:04:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:25 INFO - 'XPC_FLAGS': '0x0', 20:04:25 INFO - 'XPC_SERVICE_NAME': '0', 20:04:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:04:26 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-YvMG4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:04:26 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-Cxgun3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:04:26 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-LkdZwP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:04:26 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-_UkVGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:04:26 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-X9xGuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:04:26 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-rh4Mbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:04:26 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-8mOrN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:04:26 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-aEc8jP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:04:27 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-IRmYgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:04:27 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-d9gPrc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:04:27 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-XGmj3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:04:27 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-eRcbgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:04:27 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-lHO4es-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:04:27 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-U_0a7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:04:27 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-cEBMKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:04:27 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-30aiiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:04:27 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-v2Xu2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:04:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:04:27 INFO - Running setup.py install for manifestparser 20:04:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:04:28 INFO - Running setup.py install for mozcrash 20:04:28 INFO - Running setup.py install for mozdebug 20:04:28 INFO - Running setup.py install for mozdevice 20:04:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:04:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:04:28 INFO - Running setup.py install for mozfile 20:04:28 INFO - Running setup.py install for mozhttpd 20:04:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:04:28 INFO - Running setup.py install for mozinfo 20:04:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:04:29 INFO - Running setup.py install for mozInstall 20:04:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:04:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:04:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:04:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:04:29 INFO - Running setup.py install for mozleak 20:04:29 INFO - Running setup.py install for mozlog 20:04:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:04:29 INFO - Running setup.py install for moznetwork 20:04:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:04:29 INFO - Running setup.py install for mozprocess 20:04:29 INFO - Running setup.py install for mozprofile 20:04:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:04:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:04:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:04:29 INFO - Running setup.py install for mozrunner 20:04:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:04:30 INFO - Running setup.py install for mozscreenshot 20:04:30 INFO - Running setup.py install for moztest 20:04:30 INFO - Running setup.py install for mozversion 20:04:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:04:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:04:30 INFO - Cleaning up... 20:04:30 INFO - Return code: 0 20:04:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:04:30 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')]} 20:04:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:30 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')]} 20:04:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:30 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 0x10e569490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32256b140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37dd30>, '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 0x10e1a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e375b20>, '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.27KSup06V9/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.OlbVQYAbgC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 20:04:30 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 20:04:30 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 20:04:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.27KSup06V9/Render', 20:04:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:30 INFO - 'HOME': '/Users/cltbld', 20:04:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:30 INFO - 'LOGNAME': 'cltbld', 20:04:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:30 INFO - 'MOZ_NO_REMOTE': '1', 20:04:30 INFO - 'NO_EM_RESTART': '1', 20:04:30 INFO - 'PAGER': '/bin/cat', 20:04:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:30 INFO - 'PWD': '/builds/slave/test', 20:04:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:30 INFO - 'SHELL': '/bin/bash', 20:04:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OlbVQYAbgC/Listeners', 20:04:30 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 20:04:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:30 INFO - 'USER': 'cltbld', 20:04:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:30 INFO - 'XPC_FLAGS': '0x0', 20:04:30 INFO - 'XPC_SERVICE_NAME': '0', 20:04:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:04:30 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-J7cKeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:04:30 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)) 20:04:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:04:30 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-11taj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:04:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:04:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:04:31 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-ZhQhS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:04:31 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)) 20:04:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:04:31 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-n0aTa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:04:31 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)) 20:04:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:04:31 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-YhjK1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:04:31 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)) 20:04:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:04:31 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-70JHze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:04:31 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)) 20:04:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:04:31 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-H4jB1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:04:31 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)) 20:04:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:04:31 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-iw7HJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:04:31 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)) 20:04:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:04:31 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-DAbWcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:04:31 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)) 20:04:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:04:31 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-erQUKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:04:31 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)) 20:04:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:04:31 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-ns3xvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:04:32 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)) 20:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:04:32 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-_Hacpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:04:32 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)) 20:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:04:32 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-WoAuHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:04:32 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)) 20:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:04:32 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-3us_63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:04:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:04:32 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-9z63pW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:04:32 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)) 20:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:04:32 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-VLxG6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:04:32 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)) 20:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:04:32 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-rZ46gl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:04:32 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)) 20:04:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:04:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:04:32 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)) 20:04:32 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)) 20:04:32 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)) 20:04:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:04: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 20:04: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 20:04: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 20:04: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 20:04: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 20:04: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 20:04:35 INFO - Downloading blessings-1.6.tar.gz 20:04:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:04:35 INFO - Installing collected packages: blessings 20:04:35 INFO - Running setup.py install for blessings 20:04:35 INFO - Successfully installed blessings 20:04:35 INFO - Cleaning up... 20:04:35 INFO - Return code: 0 20:04:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:04:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:04:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:04:35 INFO - Reading from file tmpfile_stdout 20:04:35 INFO - Current package versions: 20:04:35 INFO - blessings == 1.6 20:04:35 INFO - blobuploader == 1.2.4 20:04:35 INFO - docopt == 0.6.1 20:04:35 INFO - functools32 == 3.2.3-2 20:04:35 INFO - jsonschema == 2.5.1 20:04:35 INFO - manifestparser == 1.1 20:04:35 INFO - mozInstall == 1.12 20:04:35 INFO - mozcrash == 0.17 20:04:35 INFO - mozdebug == 0.1 20:04:35 INFO - mozdevice == 0.48 20:04:35 INFO - mozfile == 1.2 20:04:35 INFO - mozhttpd == 0.7 20:04:35 INFO - mozinfo == 0.9 20:04:35 INFO - mozleak == 0.1 20:04:35 INFO - mozlog == 3.4 20:04:35 INFO - moznetwork == 0.27 20:04:35 INFO - mozprocess == 0.23 20:04:35 INFO - mozprofile == 0.28 20:04:35 INFO - mozrunner == 6.12 20:04:35 INFO - mozscreenshot == 0.1 20:04:35 INFO - mozsystemmonitor == 0.3 20:04:35 INFO - moztest == 0.7 20:04:35 INFO - mozversion == 1.4 20:04:35 INFO - psutil == 3.1.1 20:04:35 INFO - requests == 1.2.3 20:04:35 INFO - wsgiref == 0.1.2 20:04:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:04:35 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')]} 20:04:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:35 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')]} 20:04:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:35 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 0x10e569490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32256b140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37dd30>, '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 0x10e1a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e375b20>, '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.27KSup06V9/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.OlbVQYAbgC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 20:04:35 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 20:04:35 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 20:04:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.27KSup06V9/Render', 20:04:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:35 INFO - 'HOME': '/Users/cltbld', 20:04:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:35 INFO - 'LOGNAME': 'cltbld', 20:04:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:35 INFO - 'MOZ_NO_REMOTE': '1', 20:04:35 INFO - 'NO_EM_RESTART': '1', 20:04:35 INFO - 'PAGER': '/bin/cat', 20:04:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:35 INFO - 'PWD': '/builds/slave/test', 20:04:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:35 INFO - 'SHELL': '/bin/bash', 20:04:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OlbVQYAbgC/Listeners', 20:04:35 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 20:04:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:35 INFO - 'USER': 'cltbld', 20:04:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:35 INFO - 'XPC_FLAGS': '0x0', 20:04:35 INFO - 'XPC_SERVICE_NAME': '0', 20:04:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:36 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)) 20:04:36 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)) 20:04:36 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)) 20:04:36 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)) 20:04:36 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)) 20:04:36 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)) 20:04:36 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)) 20:04:36 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)) 20:04:36 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)) 20:04:36 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)) 20:04:36 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)) 20:04:36 INFO - Cleaning up... 20:04:36 INFO - Return code: 0 20:04:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:04: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')]} 20:04:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:04:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04: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')]} 20:04:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:04:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:04:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e569490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32256b140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37dd30>, '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 0x10e1a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e375b20>, '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.27KSup06V9/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.OlbVQYAbgC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 20:04:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:04:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:04:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.27KSup06V9/Render', 20:04:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:04:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:04:36 INFO - 'HOME': '/Users/cltbld', 20:04:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:04:36 INFO - 'LOGNAME': 'cltbld', 20:04:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:04:36 INFO - 'MOZ_NO_REMOTE': '1', 20:04:36 INFO - 'NO_EM_RESTART': '1', 20:04:36 INFO - 'PAGER': '/bin/cat', 20:04:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:04:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:04:36 INFO - 'PWD': '/builds/slave/test', 20:04:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:04:36 INFO - 'SHELL': '/bin/bash', 20:04:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OlbVQYAbgC/Listeners', 20:04:36 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 20:04:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:04:36 INFO - 'USER': 'cltbld', 20:04:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:04:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:04:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:04:36 INFO - 'XPC_FLAGS': '0x0', 20:04:36 INFO - 'XPC_SERVICE_NAME': '0', 20:04:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:04:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:04:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:04:36 INFO - Cleaning up... 20:04:36 INFO - Return code: 0 20:04:36 INFO - Running post-action listener: _resource_record_post_action 20:04:36 INFO - Running post-action listener: _start_resource_monitoring 20:04:36 INFO - Starting resource monitoring. 20:04:36 INFO - [mozharness: 2016-11-03 03:04:36.383848Z] Finished create-virtualenv step (success) 20:04:36 INFO - [mozharness: 2016-11-03 03:04:36.384880Z] Running install step. 20:04:36 INFO - Running pre-action listener: _resource_record_pre_action 20:04:36 INFO - Running main action method: install 20:04:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:04:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:04:36 INFO - Reading from file tmpfile_stdout 20:04:36 INFO - Detecting whether we're running mozinstall >=1.0... 20:04:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:04:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:04:36 INFO - Reading from file tmpfile_stdout 20:04:36 INFO - Output received: 20:04:36 INFO - Usage: mozinstall [options] installer 20:04:36 INFO - Options: 20:04:36 INFO - -h, --help show this help message and exit 20:04:36 INFO - -d DEST, --destination=DEST 20:04:36 INFO - Directory to install application into. [default: 20:04:36 INFO - "/builds/slave/test"] 20:04:36 INFO - --app=APP Application being installed. [default: firefox] 20:04:36 INFO - mkdir: /builds/slave/test/build/application 20:04:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:04:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:05:10 INFO - Reading from file tmpfile_stdout 20:05:10 INFO - Output received: 20:05:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:05:10 INFO - Running post-action listener: _resource_record_post_action 20:05:10 INFO - [mozharness: 2016-11-03 03:05:10.823496Z] Finished install step (success) 20:05:10 INFO - [mozharness: 2016-11-03 03:05:10.823605Z] Running run-tests step. 20:05:10 INFO - Running pre-action listener: _resource_record_pre_action 20:05:10 INFO - Running main action method: run_tests 20:05:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:05:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:05:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:05:10 INFO - 2016-11-02 20:05:10.836 screenresolution[1642:7553] starting screenresolution argv=screenresolution get 20:05:10 INFO - 2016-11-02 20:05:10.838 screenresolution[1642:7553] Display 0: 1600x1200x32@60 20:05:10 INFO - 2016-11-02 20:05:10.843 screenresolution[1643:7555] starting screenresolution argv=screenresolution list 20:05:10 INFO - Available Modes on Display 0 20:05:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:05:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:05:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:05:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:05:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:05:11 INFO - Intel Iris: 20:05:11 INFO - Chipset Model: Intel Iris 20:05:11 INFO - Type: GPU 20:05:11 INFO - Bus: Built-In 20:05:11 INFO - VRAM (Dynamic, Max): 1536 MB 20:05:11 INFO - Vendor: Intel (0x8086) 20:05:11 INFO - Device ID: 0x0a2e 20:05:11 INFO - Revision ID: 0x0009 20:05:11 INFO - Displays: 20:05:11 INFO - Display: 20:05:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:05:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:05:11 INFO - Display Serial Number: 1600x1200 60 20:05:11 INFO - Main Display: Yes 20:05:11 INFO - Mirror: Off 20:05:11 INFO - Online: Yes 20:05:11 INFO - Rotation: Supported 20:05:11 INFO - Return code: 0 20:05:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:05:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:05:11 INFO - Python 2.7.3 20:05:11 INFO - Return code: 0 20:05:11 INFO - grabbing minidump binary from tooltool 20:05:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:11 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 0x10e37dd30>, '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 0x10e1a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e375b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:05:11 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 20:05:11 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 20:05:11 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 20:05:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:05:11 INFO - Return code: 0 20:05:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:05:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:05:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:05:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:05:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:05:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0263', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 20:05:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0263 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 20:05:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.27KSup06V9/Render', 20:05:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:05:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:05:11 INFO - 'HOME': '/Users/cltbld', 20:05:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:05:11 INFO - 'LOGNAME': 'cltbld', 20:05:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:05:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:11 INFO - 'MOZ_NO_REMOTE': '1', 20:05:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:05:11 INFO - 'NO_EM_RESTART': '1', 20:05:11 INFO - 'PAGER': '/bin/cat', 20:05:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:05:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:11 INFO - 'PWD': '/builds/slave/test', 20:05:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:05:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:05:11 INFO - 'SHELL': '/bin/bash', 20:05:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OlbVQYAbgC/Listeners', 20:05:11 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 20:05:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:05:11 INFO - 'USER': 'cltbld', 20:05:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:05:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:11 INFO - 'XPC_FLAGS': '0x0', 20:05:11 INFO - 'XPC_SERVICE_NAME': '0', 20:05:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:05:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0263', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 20:05:11 INFO - mozversion application_buildid: 20161102171723 20:05:11 INFO - mozversion application_changeset: ac55a6776435142feebf3c20bbabfee100686416 20:05:11 INFO - mozversion application_display_name: Nightly 20:05:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:05:11 INFO - mozversion application_name: Firefox 20:05:11 INFO - mozversion application_remotingname: firefox 20:05:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:05:11 INFO - mozversion application_vendor: Mozilla 20:05:11 INFO - mozversion application_version: 52.0a1 20:05:11 INFO - mozversion platform_buildid: 20161102171723 20:05:11 INFO - mozversion platform_changeset: ac55a6776435142feebf3c20bbabfee100686416 20:05:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:05:11 INFO - mozversion platform_version: 52.0a1 20:05:11 INFO - using testdate: 1478142311 20:05:11 INFO - actual date: 1478142311 20:05:11 INFO - starting webserver on 'localhost:49273' 20:05:11 INFO - SUITE-START | Running 2 tests 20:05:11 INFO - TEST-START | dromaeo_css 20:05:11 INFO - operating with platform_type : mac_ 20:05:11 INFO - Initialising browser for dromaeo_css test... 20:05:11 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmp3UqalO/profile http://localhost:49273/getInfo.html) 20:05:14 INFO - PROCESS | 1653 | 2016-11-02 20:05:14.244 plugin-container[1655:7728] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 20:05:14 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:05:14 INFO - PROCESS | 1653 | 2016-11-02 20:05:14.246 plugin-container[1655:7728] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9403, name = 'com.apple.CFPasteboardClient' 20:05:14 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:05:14 INFO - PROCESS | 1653 | 2016-11-02 20:05:14.246 plugin-container[1655:7728] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:05:14 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 20:05:14 INFO - PROCESS | 1653 | colorDepth:24 20:05:14 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 20:05:14 INFO - PROCESS | 1653 | __metrics 20:05:14 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 20:05:15 INFO - TEST-INFO | 1653: exit 0 20:05:15 INFO - Browser initialized. 20:05:15 INFO - Running cycle 1/1 for dromaeo_css test... 20:05:15 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmp3UqalO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:05:17 INFO - PROCESS | 1656 | 2016-11-02 20:05:17.703 plugin-container[1657:7847] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 20:05:17 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:05:17 INFO - PROCESS | 1656 | 2016-11-02 20:05:17.706 plugin-container[1657:7847] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451f, name = 'com.apple.CFPasteboardClient' 20:05:17 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:05:17 INFO - PROCESS | 1656 | 2016-11-02 20:05:17.706 plugin-container[1657:7847] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:05:27 INFO - PROCESS | 1656 | RSS: Main: 238092288 20:05:27 INFO - PROCESS | 1656 | 20:06:16 INFO - PROCESS | 1656 | 1478142376852 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] 20:08:30 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 20:08:32 INFO - PROCESS | 1656 | RSS: Main: 250585088 20:08:32 INFO - PROCESS | 1656 | 20:11:35 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 20:11:35 INFO - PROCESS | 1656 | RSS: Main: 261181440 20:11:35 INFO - PROCESS | 1656 | 20:14:37 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 20:14:38 INFO - PROCESS | 1656 | RSS: Main: 265560064 20:14:38 INFO - PROCESS | 1656 | 20:17:40 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 20:17:41 INFO - PROCESS | 1656 | RSS: Main: 269017088 20:17:41 INFO - PROCESS | 1656 | 20:20:43 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 20:20:44 INFO - PROCESS | 1656 | RSS: Main: 274411520 20:20:44 INFO - PROCESS | 1656 | 20:23:47 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 20:23:47 INFO - PROCESS | 1656 | RSS: Main: 276533248 20:23:47 INFO - PROCESS | 1656 | 20:23:47 INFO - PROCESS | 1656 | __start_tp_report 20:23:47 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 20:23:47 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 20:23:47 INFO - PROCESS | 1656 | |i|pagename|runs| 20:23:47 INFO - PROCESS | 1656 | |0;dojo.html;3276.131825066356;3313.8508767105486;3326.3513614845083;3332.650071742467;3348.3638108759333;5562.860928476785;5745.942540574593;5800.941990580093;5834.474897259246;5869.677167755777;5501.972490137544;5646.040173170561;5701.287339082614;5784.132380142991;5811.622244554101;4272.252355837732;5144.871378215537;5194.8441546753465;5231.136862417697;5258.7370631468275;5182.37811462625;5330.493603107699;5333.253344531769;5369.40936496985;5399.298091248133;5137.332146820927;5270.39390470097;5278.551323137519;5288.762005709714;5328.706921119337;5139.7687104080405;5164.741762911839;5181.663191892515;5203.687778733288;5209.1404918188455;5090.516400941905;5114.514121158484;5164.741762911877;5182.626603949956;5244.187150991603;5834.058529550718;5856.912146317766;5868.4718375346;5869.354371019184;5897.911531327033;6101.186783078283;6159.353267906895;6177.135201071854;6203.317635060185;6236.095766113888;5297.549708274802;6196.132541444202;6211.937880621181;6227.09707092469;6238.656873871935;5753.654780713171;5821.27234095738;5976.252968378953;5981.910271345933;5988.131720900445;6177.62934223948;6199.5660303779205;6213.471854892385;6268.686565671698;6296.087067375296;6156.630602163885;6161.137440758297;6162.599431036968;6188.597741146811;6224.190855188887;6183.752649894044;6190.350013248544;6203.96898015507;6211.254649442096;6212.534059945522;6050.909236361458;6096.889554637438;6109.586862300737;6136.048912418493;6144.863200307957;6180.196574445384;6184.536159788034;6189.226346706661;6207.844803879939;6214.285357183891;4937.580305674058;4957.702537847741;4977.800887964513;4991.650584459126;4991.875203119951;4384.167008268419;4423.270160423558;4433.844815431509;4460.888477788017;4514.187446259705;5930.911036334458;5977.910331345033;5989.610675306091;6018.548608854353;6059.48494377974;5133.974330128325;6226.097215903668;6230.096635987847;6235.812925612239;6239.126522286883;5789.2763404574425;5852.590318677651;5927.170196172539;5966.701664916824;5974.551908606872;5451.182322651634;5478.205660179252;5484.095124304525;5519.69641669712;5529.502344788988;4790.496997815179;5495.6153069286365;5524.530414914777;5885.852258809577;6264.373562643699;5103.234514822806;6146.340488068107;6156.29202641702;6163.8150855474405;6227.19046523949;6150.923588372107;6157.784477543264;6168.506519478521;6243.96878015607;6272.529560282906;5291.629585928948;6070.2412198475195;6159.784407545714;6202.472789812917;6212.937870621417;5105.438401775876;6094.984975752976;6217.96891015542;6227.564070515021;6230.470410015199;4727.463574338285;5029.170186919264;5563.332400112013;6070.757169713162;6111.419415155553;5003.424606170337;6002.069679199731;6132.9062451666905;6145.893739126821;6148.585825260126;5127.871868188992;5903.616264942765;5942.108683697307;5975.940240597538;6044.818655440344;6077.726502307319;6094.542909281733;6097;6102.786402475892;6121.959266924722;6004.819855404345;6005.339412664691;6032.547558933185;6036.336003039574;6076.179715738319 20:23:47 INFO - PROCESS | 1656 | |1;ext.html;458.8738097023318;464.16913724433266;467.107824056053;492.09454603529497;508.96946183229034;38965.02587435315;40343.99140021501;40463.79768101155;40487.79756101215;42824.57175428245;34851.651483485155;41828.79085604575;42356.3646545302;42362;42470;9536.380135291218;9661.516924153799;9684.515774211297;9695.515224238796;9736.853947190797;9552.235821134293;9684.70945871625;9685.903140968605;9707.805843883118;9717.854232186522;9668.468234247113;9696.563654635558;9703.902960970407;9707.563159657833;9710.417374957526;9583.520823958808;9644.951775241148;9672.129508344247;9676.903230967671;9678.75803104921;9404.482753448558;9508.286878484141;9519;9527.52362381878;9529.237660987104;39838.60161398378;40079.19841603152;40127.39808902869;40340.39489407661;40394;3301.3067255876294;3351.2124650707065;3383.2721500148778;3453.1539772755586;3782.7730336179643;27297;28699.426011479864;31066.844665776527;31178;31348.21629459259;3180.061881744891;3198.2643991881764;3329.267561136546;3337.983310083434;3363.764536482447;475210;475557;479308;479914.6004269991;480466.9999999965;59369.40630593725;59562;59667.70166149142;59808;59854.21166306685;62452.68773656103;62747.37252627507;62844.74310513764;62849.68575157095;62917.056244156374;15780.526584202493;15783.053016819027;15806.525804225892;15917.442889498812;15932.442364517186;12286;12329.568465103679;12351.567695130627;12400.875991240153;12403.379831008593;16036.358545658042;16644.750328745078;16765.91617041907;16817.915910420616;16855.831441685426;5284.841454756286;5294.77619399853;6210.441060304594;6268.62388256697;6320.652364119926;772.4901564967207;807.3299161695778;881.3742243007537;913.3104506097853;967.7435479597913;23735.525289494115;23911.7608823913;26238;29842.552361714592;30613.540796888065;924.8901318417926;967.690339091484;972.9735129438369;1027.0294571629888;1032.0814475117152;14881.47914822976;15056.473023444347;15230.695386092217;15308.234588270541;15439.30523126462;642360;646408;649107;649513;650845;55830;56117.71941140268;56123;56147;56147.438525615034;1298.2924306253115;1344.37486568746;1361.952331668387;1363.8090667306585;1365.5630198336437;27661.170164895084;28487.857560712062;28862.855685721435;28956;29049.85475072611;49672.00655986788;49835.50164498309;49989.75005124951;49990.25014624856;50154.24768628547;493939;576266;576994;577737;579803;24935.87532062328;25812;25894.35264118412;25936.481270374865;25974.87012564925;37890;45284.54715452803;46850.29724554066;46972.06055878795;47575.76212119056;30953.380932380776;36559.81720091489;37003.44494832634;37765.62234377731;37979.81010094932;37330.25339493249;37383.12179206595;38320.65877694267;38649;38756.41865372078;36025.63974360328;36079.73720919755;36682.44976325304;36803;36966.890993270245;29386.55920161242;30719;30890.69109308968;30972.0708378749;31017.534736978512;23082.53834923326;23150.652740208574;23482;23580.88209558941;23592.764072359743 20:23:47 INFO - PROCESS | 1656 | |2;jquery.html;312.99530507042397;365.53029357275904;366.948627192193;386.83366152554424;400.415393525453;18867.905660471697;19486.610267794636;20070.49823754403;20494.487637809045;20719.06764195611;17410.82589174108;17667.734983975217;17768;18129.365472208476;20364.490887727814;5728.283964504437;5808.186853840466;6000.969995150018;6020;6029.306629737571;4939.753012349386;5878.1476685880525;5952.434518720715;5957.434043765857;5958.851028724295;4899.510048995107;5084.898302033976;5805.042168042289;5899.439553242457;5906.527477801787;5109.616778741609;5752.194692743019;5929.051351783715;5956.16613674086;5967.791127310524;4967.304577359173;5084.262781896604;5174.275601415805;5175.922361164586;5182.041322355339;21096.367108986757;24513.754862451504;24668.383290417703;24829.25512234636;24859.751402485923;2140.7003019577273;2313.3175713164655;2425.2360506440414;2442.7297805141407;2892.363679990399;9438;9854.556544955494;9901.405915645084;10182.643607473703;10413.323133996266;1444.248990524921;1821.5992481654148;1896.8198021187966;1948.1136083082163;1971.447994561525;122094;123769;124122;124736;125059.3747031259;11041.736444028955;11463.713407164722;11550.422478876022;11704.648860534198;11991.340476273888;14373.137611743328;14382.28088595566;14390.496332628307;14401.279936003159;14477.638059048608;8118.066422361504;8422;8431.15688431152;8487.490750554987;8540.914590854136;8254.752357429286;8263.669453221924;8270.297024752843;8408;8456.78858028542;4343.67422443318;4528.43394575678;4562.726236425825;4577.633789296849;4589.196890544142;1904.7523821903064;1906.1803424527586;1910.9331173408864;1917.2522716140716;1931.9227230910606;784.6233807772302;846.1792061700141;874.8294082653948;911.8221946720458;916.1342531307984;19293.8070619292;20446.591068178852;21842.563148736936;22058.117675292808;22834.771652283263;866.2247288676725;891.7012800711705;926.9258459323238;938.3772356264258;989.7129832348683;11014.889851101547;11297.49161287744;11429.485673144729;11437.085033197323;11454.885451145381;563259;571970;572559;572640;579733;54924;60740.925907408906;63760;132967.33516332356;139606;835.4527784301292;995.1690338567232;1000.8148492528906;1004.4425343934187;1008.5259927833906;14497.710045798814;18154.636907262044;18424.631507370046;18636.254549817855;19123.148733940994;38632.80683596564;54841.451585483635;54883.08115543278;55266.72366638141;55516.16725749198;70593.29406706072;77217;79389;79756.60121699587;83630.21882702471;7037.542559733601;7210.9278907210255;7560.903668967859;7966.28303452692;8322.70870519529;8765.342599305206;12090.879091208975;13221.735565288447;13231.486017656614;13524.59426217215;11170.49732762044;11303.208775385645;11387.373694446553;11438;11670.70823229426;8715.607797649247;9548.904510954802;10647.893521065;10982.725431864268;11282.605108821152;10443.321184123008;11168.050715689258;11217.326960382403;11908.642740717793;11996.58011969577;7105.253948335557;7357.742479013209;7433.9256607433235;7900.3284720799375;8465.28045116172;10737.975549540364;11175.832362514406;11389.886101138882;11514.424278786195;11633.302001879914 20:23:47 INFO - PROCESS | 1656 | |3;mootools.html;1482.221833537393;1514.1066770605341;1686.3591835102666;1794.300222913065;1796.3173993882324;6966.268541803113;7369.189389167201;8096.878546821795;8600.397972141953;8611.35414843886;8055.275025247726;8352.791180220498;8398.706045288402;8482.57587120643;8490.745277641681;2268.3421807675795;2316.061994892063;2318.3624503261653;2320.709911261092;2337.544178885118;2309.9422514437106;2345.695059642244;2364.420716924356;2366.4438856868624;2369.443180852507;2306.5617532668825;2318.7681231876845;2331.4171457135717;2333.8482998605123;2336.6144586143264;2259.1641092795608;2273.5680220758004;2289;2289.461976435545;2292.289390289016;2322.1175953137868;2337;2337.2637619149914;2338.75443078476;2349.5183487385043;8413.074561798197;8531.018932822742;8546.572671366406;8551.18763717452;8561.272291855199;5080.847574572768;5239.5546378557865;5253.973730131325;5262.158054711266;5266.368035835686;3289.687479689426;3438.6045604755273;3572.1069732566857;3629.5281613390353;3636.2727454509204;1024.129489933558;1038.6573783321599;1041.5209003858233;1052.6578861869923;1073.983890241647;10882.510287037103;10918.727031824188;10966.56133754657;10992.065674417685;11000;4462.397576327155;4519.864404067883;4694.2723877799;4710.7880145392855;4730.172219861511;9503.144716975505;9541.284403669753;9553.570089345996;9554.761130971643;9579.473128977834;5211.687298762086;5214.60890433219;5217.113090774577;5219.164933610604;5221.034108689904;4174.186033723456;4189.581041895786;4701.106789709944;4718.079974405026;4777.737224452689;3743.8689645862264;3788.8105594720705;3868.729188956746;3885.378339465672;3887.6976212968407;1071.5874388360537;1079.4171147580375;1150.9539618415338;1248.3071895098308;1259.5087915712695;730.2077246187946;834.995825020871;894.3918135667808;895.5969813583783;924.6717415317465;5751.769929202785;5761.222234998306;5854.941450585525;5857.26784151981;6091.14723938649;847.4533925618065;863.9136086391436;875.8554838451712;896.3322324868016;915.4781774388534;3549.8047607381327;3868.2456920900718;3886.8833934981994;3925.7840818755244;3989.5212574490665;9738.902610973799;10203.846942295871;10204.081632653097;10359.32664376813;10378.169746420268;10300.845487317696;10317.638882639072;10318;10341.844872326921;10463.738406539898;7045.542039767502;7799.259070388304;7801.336886364609;8009.919900801034;8036.598170091472;5521.3926468089285;5582.972085139549;5905.291365036223;5916.852078698067;5933.317668468181;8834.381593288665;9496.43021418717;9499.240060794982;9503.66737164196;9624.566894489904;9371.109744574254;9565.713028609152;9687.612495500101;9770.071843174886;9801.656942006995;6555.770548030987;6754.155730533684;7050.858982820418;7247.818804529929;7436.73971410998;7852.646630901738;8094.473859199134;8094.919050809416;8099.190080991853;8112.797180070545;6138.693065346804;7157.320054594805;7232.276772322725;7348.191698913222;7392.778216653509;6845.657717114024;6982.825429364307;7011.053507776385;7077.929220707933;7143.571385716874;6638.0374845648075;6700.83247918806;6769.593824370553;6795.150606174228;6808.455323574201;3817.0648191193336;3922.8626998054933;4219.915601688011;4884.291777692286;5068.949310506995;4749.35883655702;4929.852104436873;4963.938476251213;5048.520390563038;5078.669886457368 20:23:47 INFO - PROCESS | 1656 | |4;prototype.html;300.7278413036202;304.94968330225515;306.94321550513155;310.12389998254935;311.4052160373687;5490.615656904019;5530.778768849246;5554.694491802949;5575.944240557593;5594.24477695511;5070.416902056265;5200.297959775429;5255.237990491386;5477;5480.91778623321;3070.065858509136;4056.634902858742;4067.1458993611377;4118.855840045599;4167.291410521687;3431;4137.379393091031;4159.836884819907;4171.728837625545;4194.706370554062;3611.368010598148;4145.461090058303;4153.7300075494995;4159.667226621863;4185.246655601991;3877.515310586177;4138.793060347;4164.479440069991;4199.538050814408;4201.399467582992;3181.475056615656;3583.1009564930628;3640.290143422034;3651.7808931464197;3661.7253705972157;4458.687891847572;4621.537846215385;5364.517193452569;5371.785128594852;5373.570114390839;4668.509806470306;4756.31033500144;4799.544043315879;4846.684965477232;4847.345608342864;5761.53907687384;5761.991651460978;5778.826635200909;5824.388439213864;5836.474717275466;3668.064643515913;3737.3459644562095;4023.1350259694013;4027.818748156311;4039.616236457561;5390.218418329359;5693;6085.452309292141;6088.695565221721;6128.44843964041;3908.3941988991182;3928.7839168845417;3930.0960779020984;3945.743526670757;3968.7023473239624;3484.6758231871727;3932.9970857431745;3997.700172487075;4027.959720402817;4035.1727895781296;4169.145325208325;4613.404613404587;4718.575328220477;4742.383490146259;4748.406449193851;4758.048390321949;4801.399825021872;5385.892282154335;5832.445917637818;5889.263842019747;1204.602481181208;1219.8536175658978;1360.211077575004;1407.8380986186514;1424.0956992309957;777.6694904665494;824.4105464592845;877.4077497689162;895.4582477600958;899.1817446123996;4718.598919091847;5382.542483888913;5534.861628459321;5540.806071787549;5545.362283337387;5584.301962254718;5752.395998420189;6051.304100028465;6054.878902421927;6156;5270.604704647167;5481.534069604048;5510.752016159283;5555.50000499957;5567.995521612216;2537.974620253811;2674.3180488975577;2871.6266885304776;3174.2857856981996;3185.729213016873;5357.678539287655;5762.654240745569;6128.26460824695;6133.141360209485;6163.537734669918;4642.048380082075;5572.3870374258795;5851.795187168429;6128.448439640454;6141.140240366352;5631.887362252732;5658;6118.479929205978;6134.78528251509;6135.44780969706;5609.49514543693;5978.641281523122;5986.580939334379;6015.5488338373925;6048.909266361096;5494.835154945358;5819.836032793373;5839.8832023358445;5876.94123058781;5905.940940590539;5307.858810355792;5453.400125986059;6089.817305480843;6120.44915957566;6148.661823599745;3465.185681364827;3852.5762166162262;4060.8629583715424;4074.633283004544;4077.4903137107863;5081.85658226896;5761.279840019997;5916.67458289781;5966.105084237399;5985.760569577168;5240.868978275574;5579.8884022320135;5776.364599894092;5826.329972053099;5840.503557197516;5060.468650791614;5421.5391691706645;5717.685527296038;5742.310922689304;5815.534757219328;5235.790568377374;5658.801941932177;5680.573956953328;5704.172894930295;5751.971240143773;4844.079624871263;5486;5496;5767.653940763567;5843.480695019241;5110.795568177231;5275.973620131875;5680.573956953162;5688.402717714746;5764.798232061858 20:23:47 INFO - PROCESS | 1656 | |5;yui.html;589.3281658908844;765.3685709289837;769.8113962079295;772.4940164192452;804.1395706593943;14280.285985700712;14759.483418080355;14787.778183327258;15081;15103.018303810271;13778.44886204553;13927;13937.651558711037;13947.302634868267;13972.231527265996;657.5824351536761;685.1948959972037;803.4014659078965;826.450410477032;834.4993004197494;823.1192623892438;844.5523872347666;849.371465115813;850.2942557677113;851.1233429567525;893.803363260085;893.8748575199477;894.9149830766099;896.5472436419599;897.3673560140086;888.0098689960712;889.2752406788511;891.4250308551013;892.5091199840094;892.6250974590688;653.2128784814288;661.6658587413374;662.1557514169473;663.7212370804274;663.7477758451806;13920.37358318878;14264.35810388535;14287.214203218819;14291.499797507142;14833.629159270997;13766.174029558259;13885.930570347084;14170.645733856632;14188;14238.644033899132;11348.773024539545;11427.828582571268;11474.196806223568;11664.241824281395;11793.528258869635;3006.2334104803354;3714.53568303962;3767.566729826078;3772.3586990211456;3889.7666140031406;87543;89713.10286897112;89878;91356.54321728348;91825;10367.170626349875;10392.688219353431;10479.42363170033;10481.056704896597;10681.03870651645;17577.20902559388;20572.485687857923;20922.581548368948;20952;21034.78965210328;7828.84342313162;7842.5686587237105;7879.960600197077;7902.841943161219;7972.402069844785;4270.185171302246;4386.342048692658;4428.512863585003;4435.4233949587;4537.0925814837165;3272.918177045593;3335.916602084919;3346.2470944037395;3374.0383990562573;3378.0710304666413;485.94549826875766;562.8967224240535;604.7762327938691;616.2450997527992;633.3539789414771;548.7393488093123;561.1386521689187;578.9710514474259;631.8988961766096;652.7617419641797;8303.792405189919;8689.56552172389;8857.247133993555;9157.358984871129;9179.219766319804;571.3372487913999;608.695652173913;648.753473680008;651.6383407209045;654.1692051095083;4105.97947010263;4482;4523.11799199153;4547.545245475492;4584;73948.63025684944;93853.59219611711;109240;120745.755254685;125237.37381313035;63568;64699.67650161719;70349;70525.64737176281;83067.3386532266;76279.85580216302;76566;76857;84143;84778.15221847827;9276.907230927603;9597.856032159663;9606.183474404754;9639.084286992724;9646;51807.48192518027;52577.47422525879;55060;55660;56371.43628563662;55766;79070.6046469764;79404.60297698475;79774;80113.59943200246;10074.89925100769;11982.520699171935;12357.382130893599;12386.3187524687;12673.429695663533;13571.52499662507;13839.16964982064;13927.930360348133;13938;13961.790573141208;12512.249265044127;12597.724591606431;12812.893128485548;12864.549740759028;13197.076204665966;12383.75232495327;12831.935840320739;12847.486100555872;13365.198088114745;13430.865691342962;12354.505819767468;12610.49558017669;13013.544525941546;13070.738585228051;13116.08187426871;9344.649314168028;10041.146502547364;10173.643922462974;10181.541830617789;10250.692479225636;8232.17678232172;8629.525376104375;10092.444915529717;10636.627718029831;11190.608328708457 20:23:47 INFO - PROCESS | 1656 | __end_tp_report 20:23:47 INFO - PROCESS | 1656 | __start_cc_report 20:23:47 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,370 20:23:47 INFO - PROCESS | 1656 | __end_cc_report 20:23:47 INFO - PROCESS | 1656 | __startTimestamp1478143427527__endTimestamp 20:23:47 INFO - PROCESS | 1656 | 20:23:47 INFO - PROCESS | 1656 | ------- Summary: start ------- 20:23:47 INFO - PROCESS | 1656 | Number of tests: 6 20:23:47 INFO - PROCESS | 1656 | 20:23:47 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5680.39 Median:5966.70 stddev:646.51 (10.8%) stddev-sans-first:620.37 20:23:47 INFO - PROCESS | 1656 | Values: 3276.1 3313.9 3326.4 3332.7 3348.4 5562.9 5745.9 5800.9 5834.5 5869.7 5502.0 5646.0 5701.3 5784.1 5811.6 4272.3 5144.9 5194.8 5231.1 5258.7 5182.4 5330.5 5333.3 5369.4 5399.3 5137.3 5270.4 5278.6 5288.8 5328.7 5139.8 5164.7 5181.7 5203.7 5209.1 5090.5 5114.5 5164.7 5182.6 5244.2 5834.1 5856.9 5868.5 5869.4 5897.9 6101.2 6159.4 6177.1 6203.3 6236.1 5297.5 6196.1 6211.9 6227.1 6238.7 5753.7 5821.3 5976.3 5981.9 5988.1 6177.6 6199.6 6213.5 6268.7 6296.1 6156.6 6161.1 6162.6 6188.6 6224.2 6183.8 6190.4 6204.0 6211.3 6212.5 6050.9 6096.9 6109.6 6136.0 6144.9 6180.2 6184.5 6189.2 6207.8 6214.3 4937.6 4957.7 4977.8 4991.7 4991.9 4384.2 4423.3 4433.8 4460.9 4514.2 5930.9 5977.9 5989.6 6018.5 6059.5 5134.0 6226.1 6230.1 6235.8 6239.1 5789.3 5852.6 5927.2 5966.7 5974.6 5451.2 5478.2 5484.1 5519.7 5529.5 4790.5 5495.6 5524.5 5885.9 6264.4 5103.2 6146.3 6156.3 6163.8 6227.2 6150.9 6157.8 6168.5 6244.0 6272.5 5291.6 6070.2 6159.8 6202.5 6212.9 5105.4 6095.0 6218.0 6227.6 6230.5 4727.5 5029.2 5563.3 6070.8 6111.4 5003.4 6002.1 6132.9 6145.9 6148.6 5127.9 5903.6 5942.1 5975.9 6044.8 6077.7 6094.5 6097.0 6102.8 6122.0 6004.8 6005.3 6032.5 6036.3 6076.2 20:23:47 INFO - PROCESS | 1656 | 20:23:47 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:68937.93 Median:40428.90 stddev:151615.88 (375.0%) stddev-sans-first:151954.02 20:23:47 INFO - PROCESS | 1656 | Values: 458.9 464.2 467.1 492.1 509.0 38965.0 40344.0 40463.8 40487.8 42824.6 34851.7 41828.8 42356.4 42362.0 42470.0 9536.4 9661.5 9684.5 9695.5 9736.9 9552.2 9684.7 9685.9 9707.8 9717.9 9668.5 9696.6 9703.9 9707.6 9710.4 9583.5 9645.0 9672.1 9676.9 9678.8 9404.5 9508.3 9519.0 9527.5 9529.2 39838.6 40079.2 40127.4 40340.4 40394.0 3301.3 3351.2 3383.3 3453.2 3782.8 27297.0 28699.4 31066.8 31178.0 31348.2 3180.1 3198.3 3329.3 3338.0 3363.8 475210.0 475557.0 479308.0 479914.6 480467.0 59369.4 59562.0 59667.7 59808.0 59854.2 62452.7 62747.4 62844.7 62849.7 62917.1 15780.5 15783.1 15806.5 15917.4 15932.4 12286.0 12329.6 12351.6 12400.9 12403.4 16036.4 16644.8 16765.9 16817.9 16855.8 5284.8 5294.8 6210.4 6268.6 6320.7 772.5 807.3 881.4 913.3 967.7 23735.5 23911.8 26238.0 29842.6 30613.5 924.9 967.7 973.0 1027.0 1032.1 14881.5 15056.5 15230.7 15308.2 15439.3 642360.0 646408.0 649107.0 649513.0 650845.0 55830.0 56117.7 56123.0 56147.0 56147.4 1298.3 1344.4 1362.0 1363.8 1365.6 27661.2 28487.9 28862.9 28956.0 29049.9 49672.0 49835.5 49989.8 49990.3 50154.2 493939.0 576266.0 576994.0 577737.0 579803.0 24935.9 25812.0 25894.4 25936.5 25974.9 37890.0 45284.5 46850.3 46972.1 47575.8 30953.4 36559.8 37003.4 37765.6 37979.8 37330.3 37383.1 38320.7 38649.0 38756.4 36025.6 36079.7 36682.4 36803.0 36966.9 29386.6 30719.0 30890.7 30972.1 31017.5 23082.5 23150.7 23482.0 23580.9 23592.8 20:23:47 INFO - PROCESS | 1656 | 20:23:47 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:33197.59 Median:366.24 stddev:95298.02 (26020.7%) stddev-sans-first:95533.36 20:23:47 INFO - PROCESS | 1656 | Values: 313.0 365.5 366.9 386.8 400.4 18867.9 19486.6 20070.5 20494.5 20719.1 17410.8 17667.7 17768.0 18129.4 20364.5 5728.3 5808.2 6001.0 6020.0 6029.3 4939.8 5878.1 5952.4 5957.4 5958.9 4899.5 5084.9 5805.0 5899.4 5906.5 5109.6 5752.2 5929.1 5956.2 5967.8 4967.3 5084.3 5174.3 5175.9 5182.0 21096.4 24513.8 24668.4 24829.3 24859.8 2140.7 2313.3 2425.2 2442.7 2892.4 9438.0 9854.6 9901.4 10182.6 10413.3 1444.2 1821.6 1896.8 1948.1 1971.4 122094.0 123769.0 124122.0 124736.0 125059.4 11041.7 11463.7 11550.4 11704.6 11991.3 14373.1 14382.3 14390.5 14401.3 14477.6 8118.1 8422.0 8431.2 8487.5 8540.9 8254.8 8263.7 8270.3 8408.0 8456.8 4343.7 4528.4 4562.7 4577.6 4589.2 1904.8 1906.2 1910.9 1917.3 1931.9 784.6 846.2 874.8 911.8 916.1 19293.8 20446.6 21842.6 22058.1 22834.8 866.2 891.7 926.9 938.4 989.7 11014.9 11297.5 11429.5 11437.1 11454.9 563259.0 571970.0 572559.0 572640.0 579733.0 54924.0 60740.9 63760.0 132967.3 139606.0 835.5 995.2 1000.8 1004.4 1008.5 14497.7 18154.6 18424.6 18636.3 19123.1 38632.8 54841.5 54883.1 55266.7 55516.2 70593.3 77217.0 79389.0 79756.6 83630.2 7037.5 7210.9 7560.9 7966.3 8322.7 8765.3 12090.9 13221.7 13231.5 13524.6 11170.5 11303.2 11387.4 11438.0 11670.7 8715.6 9548.9 10647.9 10982.7 11282.6 10443.3 11168.1 11217.3 11908.6 11996.6 7105.3 7357.7 7433.9 7900.3 8465.3 10738.0 11175.8 11389.9 11514.4 11633.3 20:23:47 INFO - PROCESS | 1656 | 20:23:47 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5483.58 Median:5213.15 stddev:3029.41 (58.1%) stddev-sans-first:3022.98 20:23:47 INFO - PROCESS | 1656 | Values: 1482.2 1514.1 1686.4 1794.3 1796.3 6966.3 7369.2 8096.9 8600.4 8611.4 8055.3 8352.8 8398.7 8482.6 8490.7 2268.3 2316.1 2318.4 2320.7 2337.5 2309.9 2345.7 2364.4 2366.4 2369.4 2306.6 2318.8 2331.4 2333.8 2336.6 2259.2 2273.6 2289.0 2289.5 2292.3 2322.1 2337.0 2337.3 2338.8 2349.5 8413.1 8531.0 8546.6 8551.2 8561.3 5080.8 5239.6 5254.0 5262.2 5266.4 3289.7 3438.6 3572.1 3629.5 3636.3 1024.1 1038.7 1041.5 1052.7 1074.0 10882.5 10918.7 10966.6 10992.1 11000.0 4462.4 4519.9 4694.3 4710.8 4730.2 9503.1 9541.3 9553.6 9554.8 9579.5 5211.7 5214.6 5217.1 5219.2 5221.0 4174.2 4189.6 4701.1 4718.1 4777.7 3743.9 3788.8 3868.7 3885.4 3887.7 1071.6 1079.4 1151.0 1248.3 1259.5 730.2 835.0 894.4 895.6 924.7 5751.8 5761.2 5854.9 5857.3 6091.1 847.5 863.9 875.9 896.3 915.5 3549.8 3868.2 3886.9 3925.8 3989.5 9738.9 10203.8 10204.1 10359.3 10378.2 10300.8 10317.6 10318.0 10341.8 10463.7 7045.5 7799.3 7801.3 8009.9 8036.6 5521.4 5583.0 5905.3 5916.9 5933.3 8834.4 9496.4 9499.2 9503.7 9624.6 9371.1 9565.7 9687.6 9770.1 9801.7 6555.8 6754.2 7050.9 7247.8 7436.7 7852.6 8094.5 8094.9 8099.2 8112.8 6138.7 7157.3 7232.3 7348.2 7392.8 6845.7 6982.8 7011.1 7077.9 7143.6 6638.0 6700.8 6769.6 6795.2 6808.5 3817.1 3922.9 4219.9 4884.3 5068.9 4749.4 4929.9 4963.9 5048.5 5078.7 20:23:47 INFO - PROCESS | 1656 | 20:23:47 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4673.44 Median:5372.68 stddev:1459.30 (27.2%) stddev-sans-first:1426.00 20:23:47 INFO - PROCESS | 1656 | Values: 300.7 304.9 306.9 310.1 311.4 5490.6 5530.8 5554.7 5575.9 5594.2 5070.4 5200.3 5255.2 5477.0 5480.9 3070.1 4056.6 4067.1 4118.9 4167.3 3431.0 4137.4 4159.8 4171.7 4194.7 3611.4 4145.5 4153.7 4159.7 4185.2 3877.5 4138.8 4164.5 4199.5 4201.4 3181.5 3583.1 3640.3 3651.8 3661.7 4458.7 4621.5 5364.5 5371.8 5373.6 4668.5 4756.3 4799.5 4846.7 4847.3 5761.5 5762.0 5778.8 5824.4 5836.5 3668.1 3737.3 4023.1 4027.8 4039.6 5390.2 5693.0 6085.5 6088.7 6128.4 3908.4 3928.8 3930.1 3945.7 3968.7 3484.7 3933.0 3997.7 4028.0 4035.2 4169.1 4613.4 4718.6 4742.4 4748.4 4758.0 4801.4 5385.9 5832.4 5889.3 1204.6 1219.9 1360.2 1407.8 1424.1 777.7 824.4 877.4 895.5 899.2 4718.6 5382.5 5534.9 5540.8 5545.4 5584.3 5752.4 6051.3 6054.9 6156.0 5270.6 5481.5 5510.8 5555.5 5568.0 2538.0 2674.3 2871.6 3174.3 3185.7 5357.7 5762.7 6128.3 6133.1 6163.5 4642.0 5572.4 5851.8 6128.4 6141.1 5631.9 5658.0 6118.5 6134.8 6135.4 5609.5 5978.6 5986.6 6015.5 6048.9 5494.8 5819.8 5839.9 5876.9 5905.9 5307.9 5453.4 6089.8 6120.4 6148.7 3465.2 3852.6 4060.9 4074.6 4077.5 5081.9 5761.3 5916.7 5966.1 5985.8 5240.9 5579.9 5776.4 5826.3 5840.5 5060.5 5421.5 5717.7 5742.3 5815.5 5235.8 5658.8 5680.6 5704.2 5752.0 4844.1 5486.0 5496.0 5767.7 5843.5 5110.8 5276.0 5680.6 5688.4 5764.8 20:23:47 INFO - PROCESS | 1656 | 20:23:47 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:19613.20 Median:26146.71 stddev:28155.06 (107.7%) stddev-sans-first:28197.81 20:23:47 INFO - PROCESS | 1656 | Values: 589.3 765.4 769.8 772.5 804.1 14280.3 14759.5 14787.8 15081.0 15103.0 13778.4 13927.0 13937.7 13947.3 13972.2 657.6 685.2 803.4 826.5 834.5 823.1 844.6 849.4 850.3 851.1 893.8 893.9 894.9 896.5 897.4 888.0 889.3 891.4 892.5 892.6 653.2 661.7 662.2 663.7 663.7 13920.4 14264.4 14287.2 14291.5 14833.6 13766.2 13885.9 14170.6 14188.0 14238.6 11348.8 11427.8 11474.2 11664.2 11793.5 3006.2 3714.5 3767.6 3772.4 3889.8 87543.0 89713.1 89878.0 91356.5 91825.0 10367.2 10392.7 10479.4 10481.1 10681.0 17577.2 20572.5 20922.6 20952.0 21034.8 7828.8 7842.6 7880.0 7902.8 7972.4 4270.2 4386.3 4428.5 4435.4 4537.1 3272.9 3335.9 3346.2 3374.0 3378.1 485.9 562.9 604.8 616.2 633.4 548.7 561.1 579.0 631.9 652.8 8303.8 8689.6 8857.2 9157.4 9179.2 571.3 608.7 648.8 651.6 654.2 4106.0 4482.0 4523.1 4547.5 4584.0 73948.6 93853.6 109240.0 120745.8 125237.4 63568.0 64699.7 70349.0 70525.6 83067.3 76279.9 76566.0 76857.0 84143.0 84778.2 9276.9 9597.9 9606.2 9639.1 9646.0 51807.5 52577.5 55060.0 55660.0 56371.4 55766.0 79070.6 79404.6 79774.0 80113.6 10074.9 11982.5 12357.4 12386.3 12673.4 13571.5 13839.2 13927.9 13938.0 13961.8 12512.2 12597.7 12812.9 12864.5 13197.1 12383.8 12831.9 12847.5 13365.2 13430.9 12354.5 12610.5 13013.5 13070.7 13116.1 9344.6 10041.1 10173.6 10181.5 10250.7 8232.2 8629.5 10092.4 10636.6 11190.6 20:23:47 INFO - PROCESS | 1656 | -------- Summary: end -------- 20:23:47 INFO - PROCESS | 1656 | 20:23:48 INFO - TEST-INFO | 1656: exit 0 20:23:48 INFO - TEST-OK | dromaeo_css | took 1116756ms 20:23:48 INFO - TEST-START | kraken 20:23:48 INFO - operating with platform_type : mac_ 20:23:48 INFO - Initialising browser for kraken test... 20:23:48 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpZUyINs/profile http://localhost:49273/getInfo.html) 20:23:50 INFO - PROCESS | 1667 | 2016-11-02 20:23:50.975 plugin-container[1668:9946] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x474b, name = 'com.apple.tsm.portname' 20:23:50 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:23:50 INFO - PROCESS | 1667 | 2016-11-02 20:23:50.980 plugin-container[1668:9946] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 20:23:50 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:23:50 INFO - PROCESS | 1667 | 2016-11-02 20:23:50.980 plugin-container[1668:9946] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:23:51 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 20:23:51 INFO - PROCESS | 1667 | colorDepth:24 20:23:51 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 20:23:51 INFO - PROCESS | 1667 | __metrics 20:23:51 ERROR - PROCESS | 1667 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 20:23:51 INFO - TEST-INFO | 1667: exit 0 20:23:51 INFO - Browser initialized. 20:23:51 INFO - Running cycle 1/1 for kraken test... 20:23:51 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpZUyINs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:23:54 INFO - PROCESS | 1669 | 2016-11-02 20:23:54.004 plugin-container[1670:10057] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8847, name = 'com.apple.tsm.portname' 20:23:54 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:23:54 INFO - PROCESS | 1669 | 2016-11-02 20:23:54.005 plugin-container[1670:10057] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441b, name = 'com.apple.CFPasteboardClient' 20:23:54 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:23:54 INFO - PROCESS | 1669 | 2016-11-02 20:23:54.005 plugin-container[1670:10057] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:24:03 INFO - PROCESS | 1669 | RSS: Main: 237764608 20:24:03 INFO - PROCESS | 1669 | 20:24:06 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 20:24:07 INFO - PROCESS | 1669 | RSS: Main: 250163200 20:24:07 INFO - PROCESS | 1669 | 20:24:10 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 20:24:11 INFO - PROCESS | 1669 | RSS: Main: 257695744 20:24:11 INFO - PROCESS | 1669 | 20:24:14 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 20:24:15 INFO - PROCESS | 1669 | RSS: Main: 260640768 20:24:15 INFO - PROCESS | 1669 | 20:24:17 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 20:24:17 INFO - PROCESS | 1669 | RSS: Main: 261705728 20:24:17 INFO - PROCESS | 1669 | 20:24:19 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:24:20 INFO - PROCESS | 1669 | RSS: Main: 263278592 20:24:20 INFO - PROCESS | 1669 | 20:24:23 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 20:24:23 INFO - PROCESS | 1669 | RSS: Main: 263352320 20:24:23 INFO - PROCESS | 1669 | 20:24:26 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 20:24:27 INFO - PROCESS | 1669 | RSS: Main: 263585792 20:24:27 INFO - PROCESS | 1669 | 20:24:30 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 20:24:31 INFO - PROCESS | 1669 | RSS: Main: 263602176 20:24:31 INFO - PROCESS | 1669 | 20:24:32 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:24:33 INFO - PROCESS | 1669 | RSS: Main: 263426048 20:24:33 INFO - PROCESS | 1669 | 20:24:35 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:24:36 INFO - PROCESS | 1669 | RSS: Main: 263458816 20:24:36 INFO - PROCESS | 1669 | 20:24:38 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:24:39 INFO - PROCESS | 1669 | RSS: Main: 263585792 20:24:39 INFO - PROCESS | 1669 | 20:24:42 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:24:42 INFO - PROCESS | 1669 | RSS: Main: 263811072 20:24:42 INFO - PROCESS | 1669 | 20:24:45 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:24:46 INFO - PROCESS | 1669 | RSS: Main: 263618560 20:24:46 INFO - PROCESS | 1669 | 20:24:48 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 20:24:48 INFO - PROCESS | 1669 | RSS: Main: 265293824 20:24:48 INFO - PROCESS | 1669 | 20:24:48 INFO - PROCESS | 1669 | __start_tp_report 20:24:48 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 20:24:48 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 20:24:48 INFO - PROCESS | 1669 | |i|pagename|runs| 20:24:48 INFO - PROCESS | 1669 | |0;ai-astar;151;174;138;117;117;118;118;120;122;118 20:24:48 INFO - PROCESS | 1669 | |1;audio-beat-detection;154;132;115;152;113;115;117;116;156;151 20:24:48 INFO - PROCESS | 1669 | |2;audio-dft;199;211;280;276;222;194;202;235;274;200 20:24:48 INFO - PROCESS | 1669 | |3;audio-fft;81;59;61;84;60;63;73;81;69;74 20:24:48 INFO - PROCESS | 1669 | |4;audio-oscillator;92;70;87;74;70;71;86;70;74;89 20:24:48 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;82;134;136;86;90;86;90;89;90;86 20:24:48 INFO - PROCESS | 1669 | |6;imaging-darkroom;125;95;95;95;96;94;95;95;95;94 20:24:48 INFO - PROCESS | 1669 | |7;imaging-desaturate;89;97;130;133;101;133;142;93;130;134 20:24:48 INFO - PROCESS | 1669 | |8;json-parse-financial;57;58;59;71;58;51;49;81;57;49 20:24:48 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;69;116;79;79;75;71;95;73;71;104 20:24:48 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;104;73;102;109;110;110;91;95;90;91 20:24:48 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;166;160;161;161;157;149;151;154;202;151 20:24:48 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;181;178;188;212;175;175;173;176;177;179 20:24:48 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;68;71;71;91;92;90;91;91;82;79 20:24:48 INFO - PROCESS | 1669 | __end_tp_report 20:24:48 INFO - PROCESS | 1669 | __start_cc_report 20:24:48 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,954 20:24:48 INFO - PROCESS | 1669 | __end_cc_report 20:24:48 INFO - PROCESS | 1669 | __startTimestamp1478143488629__endTimestamp 20:24:48 INFO - PROCESS | 1669 | 20:24:48 INFO - PROCESS | 1669 | ------- Summary: start ------- 20:24:48 INFO - PROCESS | 1669 | Number of tests: 14 20:24:48 INFO - PROCESS | 1669 | 20:24:48 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:129.30 Median:121.00 stddev:19.35 (16.0%) stddev-sans-first:18.86 20:24:48 INFO - PROCESS | 1669 | Values: 151.0 174.0 138.0 117.0 117.0 118.0 118.0 120.0 122.0 118.0 20:24:48 INFO - PROCESS | 1669 | 20:24:48 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:132.10 Median:141.50 stddev:18.98 (13.4%) stddev-sans-first:18.40 20:24:48 INFO - PROCESS | 1669 | Values: 154.0 132.0 115.0 152.0 113.0 115.0 117.0 116.0 156.0 151.0 20:24:48 INFO - PROCESS | 1669 | 20:24:48 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:229.30 Median:228.50 stddev:34.86 (15.3%) stddev-sans-first:35.21 20:24:48 INFO - PROCESS | 1669 | Values: 199.0 211.0 280.0 276.0 222.0 194.0 202.0 235.0 274.0 200.0 20:24:48 INFO - PROCESS | 1669 | 20:24:48 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:70.50 Median:73.50 stddev:9.50 (12.9%) stddev-sans-first:9.29 20:24:48 INFO - PROCESS | 1669 | Values: 81.0 59.0 61.0 84.0 60.0 63.0 73.0 81.0 69.0 74.0 20:24:48 INFO - PROCESS | 1669 | 20:24:48 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:78.30 Median:80.00 stddev:9.03 (11.3%) stddev-sans-first:8.11 20:24:48 INFO - PROCESS | 1669 | Values: 92.0 70.0 87.0 74.0 70.0 71.0 86.0 70.0 74.0 89.0 20:24:48 INFO - PROCESS | 1669 | 20:24:48 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:96.90 Median:87.50 stddev:20.25 (23.1%) stddev-sans-first:20.74 20:24:48 INFO - PROCESS | 1669 | Values: 82.0 134.0 136.0 86.0 90.0 86.0 90.0 89.0 90.0 86.0 20:24:48 INFO - PROCESS | 1669 | 20:24:48 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:97.90 Median:95.00 stddev:9.54 (10.0%) stddev-sans-first:0.60 20:24:48 INFO - PROCESS | 1669 | Values: 125.0 95.0 95.0 95.0 96.0 94.0 95.0 95.0 95.0 94.0 20:24:48 INFO - PROCESS | 1669 | 20:24:48 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:118.20 Median:138.00 stddev:20.45 (14.8%) stddev-sans-first:18.77 20:24:48 INFO - PROCESS | 1669 | Values: 89.0 97.0 130.0 133.0 101.0 133.0 142.0 93.0 130.0 134.0 20:24:48 INFO - PROCESS | 1669 | 20:24:48 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:59.00 Median:58.00 stddev:10.01 (17.3%) stddev-sans-first:10.59 20:24:48 INFO - PROCESS | 1669 | Values: 57.0 58.0 59.0 71.0 58.0 51.0 49.0 81.0 57.0 49.0 20:24:48 INFO - PROCESS | 1669 | 20:24:48 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:83.20 Median:74.00 stddev:16.17 (21.9%) stddev-sans-first:16.32 20:24:48 INFO - PROCESS | 1669 | Values: 69.0 116.0 79.0 79.0 75.0 71.0 95.0 73.0 71.0 104.0 20:24:48 INFO - PROCESS | 1669 | 20:24:48 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:97.50 Median:81.50 stddev:11.81 (14.5%) stddev-sans-first:12.29 20:24:48 INFO - PROCESS | 1669 | Values: 104.0 73.0 102.0 109.0 110.0 110.0 91.0 95.0 90.0 91.0 20:24:48 INFO - PROCESS | 1669 | 20:24:48 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:161.20 Median:160.50 stddev:15.33 (9.6%) stddev-sans-first:16.16 20:24:48 INFO - PROCESS | 1669 | Values: 166.0 160.0 161.0 161.0 157.0 149.0 151.0 154.0 202.0 151.0 20:24:48 INFO - PROCESS | 1669 | 20:24:48 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.40 Median:178.50 stddev:11.54 (6.5%) stddev-sans-first:12.24 20:24:48 INFO - PROCESS | 1669 | Values: 181.0 178.0 188.0 212.0 175.0 175.0 173.0 176.0 177.0 179.0 20:24:48 INFO - PROCESS | 1669 | 20:24:48 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.60 Median:90.50 stddev:9.72 (10.7%) stddev-sans-first:8.76 20:24:48 INFO - PROCESS | 1669 | Values: 68.0 71.0 71.0 91.0 92.0 90.0 91.0 91.0 82.0 79.0 20:24:48 INFO - PROCESS | 1669 | -------- Summary: end -------- 20:24:48 INFO - PROCESS | 1669 | 20:24:49 INFO - TEST-INFO | 1669: exit 0 20:24:49 INFO - TEST-OK | kraken | took 61344ms 20:24:49 INFO - SUITE-END | took 1178s 20:24:49 INFO - Completed test suite (00:19:38) 20:24:49 INFO - javascript benchmark 20:24:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7159.523104056861, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3276.131825066356, 3313.8508767105486, 3326.3513614845083, 3332.650071742467, 3348.3638108759333, 5562.860928476785, 5745.942540574593, 5800.941990580093, 5834.474897259246, 5869.677167755777, 5501.972490137544, 5646.040173170561, 5701.287339082614, 5784.132380142991, 5811.622244554101, 4272.252355837732, 5144.871378215537, 5194.8441546753465, 5231.136862417697, 5258.7370631468275, 5182.37811462625, 5330.493603107699, 5333.253344531769, 5369.40936496985, 5399.298091248133, 5137.332146820927, 5270.39390470097, 5278.551323137519, 5288.762005709714, 5328.706921119337, 5139.7687104080405, 5164.741762911839, 5181.663191892515, 5203.687778733288, 5209.1404918188455, 5090.516400941905, 5114.514121158484, 5164.741762911877, 5182.626603949956, 5244.187150991603, 5834.058529550718, 5856.912146317766, 5868.4718375346, 5869.354371019184, 5897.911531327033, 6101.186783078283, 6159.353267906895, 6177.135201071854, 6203.317635060185, 6236.095766113888, 5297.549708274802, 6196.132541444202, 6211.937880621181, 6227.09707092469, 6238.656873871935, 5753.654780713171, 5821.27234095738, 5976.252968378953, 5981.910271345933, 5988.131720900445, 6177.62934223948, 6199.5660303779205, 6213.471854892385, 6268.686565671698, 6296.087067375296, 6156.630602163885, 6161.137440758297, 6162.599431036968, 6188.597741146811, 6224.190855188887, 6183.752649894044, 6190.350013248544, 6203.96898015507, 6211.254649442096, 6212.534059945522, 6050.909236361458, 6096.889554637438, 6109.586862300737, 6136.048912418493, 6144.863200307957, 6180.196574445384, 6184.536159788034, 6189.226346706661, 6207.844803879939, 6214.285357183891, 4937.580305674058, 4957.702537847741, 4977.800887964513, 4991.650584459126, 4991.875203119951, 4384.167008268419, 4423.270160423558, 4433.844815431509, 4460.888477788017, 4514.187446259705, 5930.911036334458, 5977.910331345033, 5989.610675306091, 6018.548608854353, 6059.48494377974, 5133.974330128325, 6226.097215903668, 6230.096635987847, 6235.812925612239, 6239.126522286883, 5789.2763404574425, 5852.590318677651, 5927.170196172539, 5966.701664916824, 5974.551908606872, 5451.182322651634, 5478.205660179252, 5484.095124304525, 5519.69641669712, 5529.502344788988, 4790.496997815179, 5495.6153069286365, 5524.530414914777, 5885.852258809577, 6264.373562643699, 5103.234514822806, 6146.340488068107, 6156.29202641702, 6163.8150855474405, 6227.19046523949, 6150.923588372107, 6157.784477543264, 6168.506519478521, 6243.96878015607, 6272.529560282906, 5291.629585928948, 6070.2412198475195, 6159.784407545714, 6202.472789812917, 6212.937870621417, 5105.438401775876, 6094.984975752976, 6217.96891015542, 6227.564070515021, 6230.470410015199, 4727.463574338285, 5029.170186919264, 5563.332400112013, 6070.757169713162, 6111.419415155553, 5003.424606170337, 6002.069679199731, 6132.9062451666905, 6145.893739126821, 6148.585825260126, 5127.871868188992, 5903.616264942765, 5942.108683697307, 5975.940240597538, 6044.818655440344, 6077.726502307319, 6094.542909281733, 6097.0, 6102.786402475892, 6121.959266924722, 6004.819855404345, 6005.339412664691, 6032.547558933185, 6036.336003039574, 6076.179715738319], "value": 5642.2522390828635, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.8738097023318, 464.16913724433266, 467.107824056053, 492.09454603529497, 508.96946183229034, 38965.02587435315, 40343.99140021501, 40463.79768101155, 40487.79756101215, 42824.57175428245, 34851.651483485155, 41828.79085604575, 42356.3646545302, 42362.0, 42470.0, 9536.380135291218, 9661.516924153799, 9684.515774211297, 9695.515224238796, 9736.853947190797, 9552.235821134293, 9684.70945871625, 9685.903140968605, 9707.805843883118, 9717.854232186522, 9668.468234247113, 9696.563654635558, 9703.902960970407, 9707.563159657833, 9710.417374957526, 9583.520823958808, 9644.951775241148, 9672.129508344247, 9676.903230967671, 9678.75803104921, 9404.482753448558, 9508.286878484141, 9519.0, 9527.52362381878, 9529.237660987104, 39838.60161398378, 40079.19841603152, 40127.39808902869, 40340.39489407661, 40394.0, 3301.3067255876294, 3351.2124650707065, 3383.2721500148778, 3453.1539772755586, 3782.7730336179643, 27297.0, 28699.426011479864, 31066.844665776527, 31178.0, 31348.21629459259, 3180.061881744891, 3198.2643991881764, 3329.267561136546, 3337.983310083434, 3363.764536482447, 475210.0, 475557.0, 479308.0, 479914.6004269991, 480466.9999999965, 59369.40630593725, 59562.0, 59667.70166149142, 59808.0, 59854.21166306685, 62452.68773656103, 62747.37252627507, 62844.74310513764, 62849.68575157095, 62917.056244156374, 15780.526584202493, 15783.053016819027, 15806.525804225892, 15917.442889498812, 15932.442364517186, 12286.0, 12329.568465103679, 12351.567695130627, 12400.875991240153, 12403.379831008593, 16036.358545658042, 16644.750328745078, 16765.91617041907, 16817.915910420616, 16855.831441685426, 5284.841454756286, 5294.77619399853, 6210.441060304594, 6268.62388256697, 6320.652364119926, 772.4901564967207, 807.3299161695778, 881.3742243007537, 913.3104506097853, 967.7435479597913, 23735.525289494115, 23911.7608823913, 26238.0, 29842.552361714592, 30613.540796888065, 924.8901318417926, 967.690339091484, 972.9735129438369, 1027.0294571629888, 1032.0814475117152, 14881.47914822976, 15056.473023444347, 15230.695386092217, 15308.234588270541, 15439.30523126462, 642360.0, 646408.0, 649107.0, 649513.0, 650845.0, 55830.0, 56117.71941140268, 56123.0, 56147.0, 56147.438525615034, 1298.2924306253115, 1344.37486568746, 1361.952331668387, 1363.8090667306585, 1365.5630198336437, 27661.170164895084, 28487.857560712062, 28862.855685721435, 28956.0, 29049.85475072611, 49672.00655986788, 49835.50164498309, 49989.75005124951, 49990.25014624856, 50154.24768628547, 493939.0, 576266.0, 576994.0, 577737.0, 579803.0, 24935.87532062328, 25812.0, 25894.35264118412, 25936.481270374865, 25974.87012564925, 37890.0, 45284.54715452803, 46850.29724554066, 46972.06055878795, 47575.76212119056, 30953.380932380776, 36559.81720091489, 37003.44494832634, 37765.62234377731, 37979.81010094932, 37330.25339493249, 37383.12179206595, 38320.65877694267, 38649.0, 38756.41865372078, 36025.63974360328, 36079.73720919755, 36682.44976325304, 36803.0, 36966.890993270245, 29386.55920161242, 30719.0, 30890.69109308968, 30972.0708378749, 31017.534736978512, 23082.53834923326, 23150.652740208574, 23482.0, 23580.88209558941, 23592.764072359743], "value": 19252.86139884722, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.99530507042397, 365.53029357275904, 366.948627192193, 386.83366152554424, 400.415393525453, 18867.905660471697, 19486.610267794636, 20070.49823754403, 20494.487637809045, 20719.06764195611, 17410.82589174108, 17667.734983975217, 17768.0, 18129.365472208476, 20364.490887727814, 5728.283964504437, 5808.186853840466, 6000.969995150018, 6020.0, 6029.306629737571, 4939.753012349386, 5878.1476685880525, 5952.434518720715, 5957.434043765857, 5958.851028724295, 4899.510048995107, 5084.898302033976, 5805.042168042289, 5899.439553242457, 5906.527477801787, 5109.616778741609, 5752.194692743019, 5929.051351783715, 5956.16613674086, 5967.791127310524, 4967.304577359173, 5084.262781896604, 5174.275601415805, 5175.922361164586, 5182.041322355339, 21096.367108986757, 24513.754862451504, 24668.383290417703, 24829.25512234636, 24859.751402485923, 2140.7003019577273, 2313.3175713164655, 2425.2360506440414, 2442.7297805141407, 2892.363679990399, 9438.0, 9854.556544955494, 9901.405915645084, 10182.643607473703, 10413.323133996266, 1444.248990524921, 1821.5992481654148, 1896.8198021187966, 1948.1136083082163, 1971.447994561525, 122094.0, 123769.0, 124122.0, 124736.0, 125059.3747031259, 11041.736444028955, 11463.713407164722, 11550.422478876022, 11704.648860534198, 11991.340476273888, 14373.137611743328, 14382.28088595566, 14390.496332628307, 14401.279936003159, 14477.638059048608, 8118.066422361504, 8422.0, 8431.15688431152, 8487.490750554987, 8540.914590854136, 8254.752357429286, 8263.669453221924, 8270.297024752843, 8408.0, 8456.78858028542, 4343.67422443318, 4528.43394575678, 4562.726236425825, 4577.633789296849, 4589.196890544142, 1904.7523821903064, 1906.1803424527586, 1910.9331173408864, 1917.2522716140716, 1931.9227230910606, 784.6233807772302, 846.1792061700141, 874.8294082653948, 911.8221946720458, 916.1342531307984, 19293.8070619292, 20446.591068178852, 21842.563148736936, 22058.117675292808, 22834.771652283263, 866.2247288676725, 891.7012800711705, 926.9258459323238, 938.3772356264258, 989.7129832348683, 11014.889851101547, 11297.49161287744, 11429.485673144729, 11437.085033197323, 11454.885451145381, 563259.0, 571970.0, 572559.0, 572640.0, 579733.0, 54924.0, 60740.925907408906, 63760.0, 132967.33516332356, 139606.0, 835.4527784301292, 995.1690338567232, 1000.8148492528906, 1004.4425343934187, 1008.5259927833906, 14497.710045798814, 18154.636907262044, 18424.631507370046, 18636.254549817855, 19123.148733940994, 38632.80683596564, 54841.451585483635, 54883.08115543278, 55266.72366638141, 55516.16725749198, 70593.29406706072, 77217.0, 79389.0, 79756.60121699587, 83630.21882702471, 7037.542559733601, 7210.9278907210255, 7560.903668967859, 7966.28303452692, 8322.70870519529, 8765.342599305206, 12090.879091208975, 13221.735565288447, 13231.486017656614, 13524.59426217215, 11170.49732762044, 11303.208775385645, 11387.373694446553, 11438.0, 11670.70823229426, 8715.607797649247, 9548.904510954802, 10647.893521065, 10982.725431864268, 11282.605108821152, 10443.321184123008, 11168.050715689258, 11217.326960382403, 11908.642740717793, 11996.58011969577, 7105.253948335557, 7357.742479013209, 7433.9256607433235, 7900.3284720799375, 8465.28045116172, 10737.975549540364, 11175.832362514406, 11389.886101138882, 11514.424278786195, 11633.302001879914], "value": 9295.652686446922, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1482.221833537393, 1514.1066770605341, 1686.3591835102666, 1794.300222913065, 1796.3173993882324, 6966.268541803113, 7369.189389167201, 8096.878546821795, 8600.397972141953, 8611.35414843886, 8055.275025247726, 8352.791180220498, 8398.706045288402, 8482.57587120643, 8490.745277641681, 2268.3421807675795, 2316.061994892063, 2318.3624503261653, 2320.709911261092, 2337.544178885118, 2309.9422514437106, 2345.695059642244, 2364.420716924356, 2366.4438856868624, 2369.443180852507, 2306.5617532668825, 2318.7681231876845, 2331.4171457135717, 2333.8482998605123, 2336.6144586143264, 2259.1641092795608, 2273.5680220758004, 2289.0, 2289.461976435545, 2292.289390289016, 2322.1175953137868, 2337.0, 2337.2637619149914, 2338.75443078476, 2349.5183487385043, 8413.074561798197, 8531.018932822742, 8546.572671366406, 8551.18763717452, 8561.272291855199, 5080.847574572768, 5239.5546378557865, 5253.973730131325, 5262.158054711266, 5266.368035835686, 3289.687479689426, 3438.6045604755273, 3572.1069732566857, 3629.5281613390353, 3636.2727454509204, 1024.129489933558, 1038.6573783321599, 1041.5209003858233, 1052.6578861869923, 1073.983890241647, 10882.510287037103, 10918.727031824188, 10966.56133754657, 10992.065674417685, 11000.0, 4462.397576327155, 4519.864404067883, 4694.2723877799, 4710.7880145392855, 4730.172219861511, 9503.144716975505, 9541.284403669753, 9553.570089345996, 9554.761130971643, 9579.473128977834, 5211.687298762086, 5214.60890433219, 5217.113090774577, 5219.164933610604, 5221.034108689904, 4174.186033723456, 4189.581041895786, 4701.106789709944, 4718.079974405026, 4777.737224452689, 3743.8689645862264, 3788.8105594720705, 3868.729188956746, 3885.378339465672, 3887.6976212968407, 1071.5874388360537, 1079.4171147580375, 1150.9539618415338, 1248.3071895098308, 1259.5087915712695, 730.2077246187946, 834.995825020871, 894.3918135667808, 895.5969813583783, 924.6717415317465, 5751.769929202785, 5761.222234998306, 5854.941450585525, 5857.26784151981, 6091.14723938649, 847.4533925618065, 863.9136086391436, 875.8554838451712, 896.3322324868016, 915.4781774388534, 3549.8047607381327, 3868.2456920900718, 3886.8833934981994, 3925.7840818755244, 3989.5212574490665, 9738.902610973799, 10203.846942295871, 10204.081632653097, 10359.32664376813, 10378.169746420268, 10300.845487317696, 10317.638882639072, 10318.0, 10341.844872326921, 10463.738406539898, 7045.542039767502, 7799.259070388304, 7801.336886364609, 8009.919900801034, 8036.598170091472, 5521.3926468089285, 5582.972085139549, 5905.291365036223, 5916.852078698067, 5933.317668468181, 8834.381593288665, 9496.43021418717, 9499.240060794982, 9503.66737164196, 9624.566894489904, 9371.109744574254, 9565.713028609152, 9687.612495500101, 9770.071843174886, 9801.656942006995, 6555.770548030987, 6754.155730533684, 7050.858982820418, 7247.818804529929, 7436.73971410998, 7852.646630901738, 8094.473859199134, 8094.919050809416, 8099.190080991853, 8112.797180070545, 6138.693065346804, 7157.320054594805, 7232.276772322725, 7348.191698913222, 7392.778216653509, 6845.657717114024, 6982.825429364307, 7011.053507776385, 7077.929220707933, 7143.571385716874, 6638.0374845648075, 6700.83247918806, 6769.593824370553, 6795.150606174228, 6808.455323574201, 3817.0648191193336, 3922.8626998054933, 4219.915601688011, 4884.291777692286, 5068.949310506995, 4749.35883655702, 4929.852104436873, 4963.938476251213, 5048.520390563038, 5078.669886457368], "value": 4426.615397461219, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.7278413036202, 304.94968330225515, 306.94321550513155, 310.12389998254935, 311.4052160373687, 5490.615656904019, 5530.778768849246, 5554.694491802949, 5575.944240557593, 5594.24477695511, 5070.416902056265, 5200.297959775429, 5255.237990491386, 5477.0, 5480.91778623321, 3070.065858509136, 4056.634902858742, 4067.1458993611377, 4118.855840045599, 4167.291410521687, 3431.0, 4137.379393091031, 4159.836884819907, 4171.728837625545, 4194.706370554062, 3611.368010598148, 4145.461090058303, 4153.7300075494995, 4159.667226621863, 4185.246655601991, 3877.515310586177, 4138.793060347, 4164.479440069991, 4199.538050814408, 4201.399467582992, 3181.475056615656, 3583.1009564930628, 3640.290143422034, 3651.7808931464197, 3661.7253705972157, 4458.687891847572, 4621.537846215385, 5364.517193452569, 5371.785128594852, 5373.570114390839, 4668.509806470306, 4756.31033500144, 4799.544043315879, 4846.684965477232, 4847.345608342864, 5761.53907687384, 5761.991651460978, 5778.826635200909, 5824.388439213864, 5836.474717275466, 3668.064643515913, 3737.3459644562095, 4023.1350259694013, 4027.818748156311, 4039.616236457561, 5390.218418329359, 5693.0, 6085.452309292141, 6088.695565221721, 6128.44843964041, 3908.3941988991182, 3928.7839168845417, 3930.0960779020984, 3945.743526670757, 3968.7023473239624, 3484.6758231871727, 3932.9970857431745, 3997.700172487075, 4027.959720402817, 4035.1727895781296, 4169.145325208325, 4613.404613404587, 4718.575328220477, 4742.383490146259, 4748.406449193851, 4758.048390321949, 4801.399825021872, 5385.892282154335, 5832.445917637818, 5889.263842019747, 1204.602481181208, 1219.8536175658978, 1360.211077575004, 1407.8380986186514, 1424.0956992309957, 777.6694904665494, 824.4105464592845, 877.4077497689162, 895.4582477600958, 899.1817446123996, 4718.598919091847, 5382.542483888913, 5534.861628459321, 5540.806071787549, 5545.362283337387, 5584.301962254718, 5752.395998420189, 6051.304100028465, 6054.878902421927, 6156.0, 5270.604704647167, 5481.534069604048, 5510.752016159283, 5555.50000499957, 5567.995521612216, 2537.974620253811, 2674.3180488975577, 2871.6266885304776, 3174.2857856981996, 3185.729213016873, 5357.678539287655, 5762.654240745569, 6128.26460824695, 6133.141360209485, 6163.537734669918, 4642.048380082075, 5572.3870374258795, 5851.795187168429, 6128.448439640454, 6141.140240366352, 5631.887362252732, 5658.0, 6118.479929205978, 6134.78528251509, 6135.44780969706, 5609.49514543693, 5978.641281523122, 5986.580939334379, 6015.5488338373925, 6048.909266361096, 5494.835154945358, 5819.836032793373, 5839.8832023358445, 5876.94123058781, 5905.940940590539, 5307.858810355792, 5453.400125986059, 6089.817305480843, 6120.44915957566, 6148.661823599745, 3465.185681364827, 3852.5762166162262, 4060.8629583715424, 4074.633283004544, 4077.4903137107863, 5081.85658226896, 5761.279840019997, 5916.67458289781, 5966.105084237399, 5985.760569577168, 5240.868978275574, 5579.8884022320135, 5776.364599894092, 5826.329972053099, 5840.503557197516, 5060.468650791614, 5421.5391691706645, 5717.685527296038, 5742.310922689304, 5815.534757219328, 5235.790568377374, 5658.801941932177, 5680.573956953328, 5704.172894930295, 5751.971240143773, 4844.079624871263, 5486.0, 5496.0, 5767.653940763567, 5843.480695019241, 5110.795568177231, 5275.973620131875, 5680.573956953162, 5688.402717714746, 5764.798232061858], "value": 4198.519844264466, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.3281658908844, 765.3685709289837, 769.8113962079295, 772.4940164192452, 804.1395706593943, 14280.285985700712, 14759.483418080355, 14787.778183327258, 15081.0, 15103.018303810271, 13778.44886204553, 13927.0, 13937.651558711037, 13947.302634868267, 13972.231527265996, 657.5824351536761, 685.1948959972037, 803.4014659078965, 826.450410477032, 834.4993004197494, 823.1192623892438, 844.5523872347666, 849.371465115813, 850.2942557677113, 851.1233429567525, 893.803363260085, 893.8748575199477, 894.9149830766099, 896.5472436419599, 897.3673560140086, 888.0098689960712, 889.2752406788511, 891.4250308551013, 892.5091199840094, 892.6250974590688, 653.2128784814288, 661.6658587413374, 662.1557514169473, 663.7212370804274, 663.7477758451806, 13920.37358318878, 14264.35810388535, 14287.214203218819, 14291.499797507142, 14833.629159270997, 13766.174029558259, 13885.930570347084, 14170.645733856632, 14188.0, 14238.644033899132, 11348.773024539545, 11427.828582571268, 11474.196806223568, 11664.241824281395, 11793.528258869635, 3006.2334104803354, 3714.53568303962, 3767.566729826078, 3772.3586990211456, 3889.7666140031406, 87543.0, 89713.10286897112, 89878.0, 91356.54321728348, 91825.0, 10367.170626349875, 10392.688219353431, 10479.42363170033, 10481.056704896597, 10681.03870651645, 17577.20902559388, 20572.485687857923, 20922.581548368948, 20952.0, 21034.78965210328, 7828.84342313162, 7842.5686587237105, 7879.960600197077, 7902.841943161219, 7972.402069844785, 4270.185171302246, 4386.342048692658, 4428.512863585003, 4435.4233949587, 4537.0925814837165, 3272.918177045593, 3335.916602084919, 3346.2470944037395, 3374.0383990562573, 3378.0710304666413, 485.94549826875766, 562.8967224240535, 604.7762327938691, 616.2450997527992, 633.3539789414771, 548.7393488093123, 561.1386521689187, 578.9710514474259, 631.8988961766096, 652.7617419641797, 8303.792405189919, 8689.56552172389, 8857.247133993555, 9157.358984871129, 9179.219766319804, 571.3372487913999, 608.695652173913, 648.753473680008, 651.6383407209045, 654.1692051095083, 4105.97947010263, 4482.0, 4523.11799199153, 4547.545245475492, 4584.0, 73948.63025684944, 93853.59219611711, 109240.0, 120745.755254685, 125237.37381313035, 63568.0, 64699.67650161719, 70349.0, 70525.64737176281, 83067.3386532266, 76279.85580216302, 76566.0, 76857.0, 84143.0, 84778.15221847827, 9276.907230927603, 9597.856032159663, 9606.183474404754, 9639.084286992724, 9646.0, 51807.48192518027, 52577.47422525879, 55060.0, 55660.0, 56371.43628563662, 55766.0, 79070.6046469764, 79404.60297698475, 79774.0, 80113.59943200246, 10074.89925100769, 11982.520699171935, 12357.382130893599, 12386.3187524687, 12673.429695663533, 13571.52499662507, 13839.16964982064, 13927.930360348133, 13938.0, 13961.790573141208, 12512.249265044127, 12597.724591606431, 12812.893128485548, 12864.549740759028, 13197.076204665966, 12383.75232495327, 12831.935840320739, 12847.486100555872, 13365.198088114745, 13430.865691342962, 12354.505819767468, 12610.49558017669, 13013.544525941546, 13070.738585228051, 13116.08187426871, 9344.649314168028, 10041.146502547364, 10173.643922462974, 10181.541830617789, 10250.692479225636, 8232.17678232172, 8629.525376104375, 10092.444915529717, 10636.627718029831, 11190.608328708457], "value": 7175.7045091650925, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1617.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 174.0, 138.0, 117.0, 117.0, 118.0, 118.0, 120.0, 122.0, 118.0], "value": 129.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 132.0, 115.0, 152.0, 113.0, 115.0, 117.0, 116.0, 156.0, 151.0], "value": 132.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 211.0, 280.0, 276.0, 222.0, 194.0, 202.0, 235.0, 274.0, 200.0], "value": 229.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 61.0, 84.0, 60.0, 63.0, 73.0, 81.0, 69.0, 74.0], "value": 70.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 70.0, 87.0, 74.0, 70.0, 71.0, 86.0, 70.0, 74.0, 89.0], "value": 78.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 134.0, 136.0, 86.0, 90.0, 86.0, 90.0, 89.0, 90.0, 86.0], "value": 96.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 95.0, 95.0, 95.0, 96.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 97.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 97.0, 130.0, 133.0, 101.0, 133.0, 142.0, 93.0, 130.0, 134.0], "value": 118.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 59.0, 71.0, 58.0, 51.0, 49.0, 81.0, 57.0, 49.0], "value": 59.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 116.0, 79.0, 79.0, 75.0, 71.0, 95.0, 73.0, 71.0, 104.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 73.0, 102.0, 109.0, 110.0, 110.0, 91.0, 95.0, 90.0, 91.0], "value": 97.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 160.0, 161.0, 161.0, 157.0, 149.0, 151.0, 154.0, 202.0, 151.0], "value": 161.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 178.0, 188.0, 212.0, 175.0, 175.0, 173.0, 176.0, 177.0, 179.0], "value": 181.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 71.0, 91.0, 92.0, 90.0, 91.0, 91.0, 82.0, 79.0], "value": 82.6, "unit": "score"}]}]} 20:24:49 INFO - Return code: 0 20:24:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:24:49 ERROR - # TBPL SUCCESS # 20:24:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:24:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:24:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:24:49 INFO - 2016-11-02 20:24:49.932 screenresolution[1674:10438] starting screenresolution argv=screenresolution get 20:24:49 INFO - 2016-11-02 20:24:49.935 screenresolution[1674:10438] Display 0: 1600x1200x32@60 20:24:49 INFO - 2016-11-02 20:24:49.941 screenresolution[1675:10441] starting screenresolution argv=screenresolution list 20:24:49 INFO - Available Modes on Display 0 20:24:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:24:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:24:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:24:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:24:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:24:50 INFO - Intel Iris: 20:24:50 INFO - Chipset Model: Intel Iris 20:24:50 INFO - Type: GPU 20:24:50 INFO - Bus: Built-In 20:24:50 INFO - VRAM (Dynamic, Max): 1536 MB 20:24:50 INFO - Vendor: Intel (0x8086) 20:24:50 INFO - Device ID: 0x0a2e 20:24:50 INFO - Revision ID: 0x0009 20:24:50 INFO - Displays: 20:24:50 INFO - Display: 20:24:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:24:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:24:50 INFO - Display Serial Number: 1600x1200 60 20:24:50 INFO - Main Display: Yes 20:24:50 INFO - Mirror: Off 20:24:50 INFO - Online: Yes 20:24:50 INFO - Rotation: Supported 20:24:50 INFO - Return code: 0 20:24:50 INFO - Running post-action listener: _resource_record_post_action 20:24:50 INFO - [mozharness: 2016-11-03 03:24:50.178105Z] Finished run-tests step (success) 20:24:50 INFO - Running post-run listener: _resource_record_post_run 20:24:50 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 38562304; Write bytes: 709466112; Read time: 248; Write time: 2013 20:24:50 INFO - TinderboxPrint: CPU usage
54.5% 20:24:50 INFO - TinderboxPrint: I/O read bytes / time
38,562,304 / 248 20:24:50 INFO - TinderboxPrint: I/O write bytes / time
709,466,112 / 2,013 20:24:50 INFO - TinderboxPrint: CPU idle
2,208.1 (45.5%) 20:24:50 INFO - TinderboxPrint: CPU system
980.2 (20.2%) 20:24:50 INFO - TinderboxPrint: CPU user
1,665.2 (34.3%) 20:24:50 INFO - TinderboxPrint: Swap in / out
444,497,920 / 0 20:24:50 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398920192; Write bytes: 392800256; Read time: 29534; Write time: 835 20:24:50 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 36502016; Write bytes: 300244992; Read time: 228; Write time: 1130 20:24:50 INFO - Running post-run listener: _upload_blobber_files 20:24:50 INFO - Blob upload gear active. 20:24:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:24:50 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 20:24:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:24:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:24:51 INFO - (blobuploader) - INFO - Open directory for files ... 20:24:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:24:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:24:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:24:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:24:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:24:51 INFO - (blobuploader) - INFO - Done attempting. 20:24:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:24:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:24:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:24:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:24:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:24:52 INFO - (blobuploader) - INFO - Done attempting. 20:24:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:24:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:24:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:24:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:24:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:24:54 INFO - (blobuploader) - INFO - Done attempting. 20:24:54 INFO - (blobuploader) - INFO - Iteration through files over. 20:24:54 INFO - Return code: 0 20:24:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:24:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:24:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5dc754480e34b2d1b28bcf319a580e5f06693e103617b91a07391201a2a44d627db13be7fabc5bd0c4554264d2c98bb081919dfc95f2ff34f819dff0518407e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0562c3e04f6ef5ca5969a0ba84973f6548712b4d854f2e029efd04a8999bc4927eeab91c91ca4b75c9d9652541c5421f1e506e1a1cb40f4781c8835a05e1b05", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9421b48e9fc391c1a87f4ab0520665bc5b094db954b75cb92b43c7eb747e7d8b7c56acfdf9a3c5fe277d8721af253109268ed599a029cea8c4fa19a4c1d15d5f"} 20:24:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:24:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:24:54 INFO - Contents: 20:24:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5dc754480e34b2d1b28bcf319a580e5f06693e103617b91a07391201a2a44d627db13be7fabc5bd0c4554264d2c98bb081919dfc95f2ff34f819dff0518407e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0562c3e04f6ef5ca5969a0ba84973f6548712b4d854f2e029efd04a8999bc4927eeab91c91ca4b75c9d9652541c5421f1e506e1a1cb40f4781c8835a05e1b05", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9421b48e9fc391c1a87f4ab0520665bc5b094db954b75cb92b43c7eb747e7d8b7c56acfdf9a3c5fe277d8721af253109268ed599a029cea8c4fa19a4c1d15d5f"} 20:24:54 INFO - Running post-run listener: copy_logs_to_upload_dir 20:24:54 INFO - Copying logs to upload dir... 20:24:54 INFO - mkdir: /builds/slave/test/build/upload/logs 20:24:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.427401 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-02 20:24:55.021203) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-02 20:24:55.024847) ========= 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.27KSup06V9/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.OlbVQYAbgC/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5dc754480e34b2d1b28bcf319a580e5f06693e103617b91a07391201a2a44d627db13be7fabc5bd0c4554264d2c98bb081919dfc95f2ff34f819dff0518407e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0562c3e04f6ef5ca5969a0ba84973f6548712b4d854f2e029efd04a8999bc4927eeab91c91ca4b75c9d9652541c5421f1e506e1a1cb40f4781c8835a05e1b05", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9421b48e9fc391c1a87f4ab0520665bc5b094db954b75cb92b43c7eb747e7d8b7c56acfdf9a3c5fe277d8721af253109268ed599a029cea8c4fa19a4c1d15d5f"} build_url:https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011624 build_url: 'https://queue.taskcluster.net/v1/task/ES5nAo8OQXabiC3pOmL8DQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5dc754480e34b2d1b28bcf319a580e5f06693e103617b91a07391201a2a44d627db13be7fabc5bd0c4554264d2c98bb081919dfc95f2ff34f819dff0518407e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0562c3e04f6ef5ca5969a0ba84973f6548712b4d854f2e029efd04a8999bc4927eeab91c91ca4b75c9d9652541c5421f1e506e1a1cb40f4781c8835a05e1b05", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9421b48e9fc391c1a87f4ab0520665bc5b094db954b75cb92b43c7eb747e7d8b7c56acfdf9a3c5fe277d8721af253109268ed599a029cea8c4fa19a4c1d15d5f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-02 20:24:55.056435) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:24:55.056739) ========= 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.27KSup06V9/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.OlbVQYAbgC/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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.005291 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:24:55.105298) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-02 20:24:55.105613) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-02 20:24:55.105926) ========= ========= Total master_lag: 0.17 =========