builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0324 starttime: 1476880252.07 results: success (0) buildid: 20161019030208 builduid: 6be7e2a95936440a94c0d0edce1810db revision: 90d8afaddf9150853b0b68b35b30c1e54a8683e7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 05:30:52.066144) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 05:30:52.066606) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 05:30:52.066884) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 05:30:52.092528) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 05:30:52.092908) ========= 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.2Va9BBWrVV/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.QDokAe62VO/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-19 05:30:52-- 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% 6.40M=0.002s 2016-10-19 05:30:52 (6.40 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.663194 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 05:30:52.788211) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 05:30:52.788580) ========= 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.2Va9BBWrVV/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.QDokAe62VO/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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.024262 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 05:30:52.879842) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 05:30:52.880222) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 90d8afaddf9150853b0b68b35b30c1e54a8683e7 --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 90d8afaddf9150853b0b68b35b30c1e54a8683e7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2Va9BBWrVV/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.QDokAe62VO/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-19 05:30:52,963 truncating revision to first 12 chars 2016-10-19 05:30:52,963 Setting DEBUG logging. 2016-10-19 05:30:52,963 attempt 1/10 2016-10-19 05:30:52,963 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/90d8afaddf91?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-19 05:30:53,625 unpacking tar archive at: mozilla-central-90d8afaddf91/testing/mozharness/ program finished with exit code 0 elapsedTime=0.929018 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 05:30:53.836079) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 05:30:53.836396) ========= script_repo_revision: 90d8afaddf9150853b0b68b35b30c1e54a8683e7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 05:30:53.836807) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 05:30:53.837083) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 05:30:53.896028) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-10-19 05:30:53.896419) ========= /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.2Va9BBWrVV/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.QDokAe62VO/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 05:30:54 INFO - MultiFileLogger online at 20161019 05:30:54 in /builds/slave/test 05:30:54 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 05:30:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:30:54 INFO - {'append_to_log': False, 05:30:54 INFO - 'base_work_dir': '/builds/slave/test', 05:30:54 INFO - 'blob_upload_branch': 'Firefox', 05:30:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:30:54 INFO - 'branch': 'Firefox', 05:30:54 INFO - 'buildbot_json_path': 'buildprops.json', 05:30:54 INFO - 'config_files': ('talos/mac_config.py',), 05:30:54 INFO - 'default_actions': ('clobber', 05:30:54 INFO - 'read-buildbot-config', 05:30:54 INFO - 'download-and-extract', 05:30:54 INFO - 'populate-webroot', 05:30:54 INFO - 'create-virtualenv', 05:30:54 INFO - 'install', 05:30:54 INFO - 'run-tests'), 05:30:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:30:54 INFO - 'download_minidump_stackwalk': True, 05:30:54 INFO - 'download_symbols': 'ondemand', 05:30:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:30:54 INFO - 'tooltool.py': '/tools/tooltool.py', 05:30:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:30:54 INFO - '/tools/misc-python/virtualenv.py')}, 05:30:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:30:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:30:54 INFO - 'installer_path': 'installer.exe', 05:30:54 INFO - 'log_level': 'info', 05:30:54 INFO - 'log_name': 'talos', 05:30:54 INFO - 'log_to_console': True, 05:30:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:30:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:30:54 INFO - 'opt_config_files': (), 05:30:54 INFO - 'pip_index': False, 05:30:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:30:54 INFO - 'cmd': ('bash', 05:30:54 INFO - '-c', 05:30:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:30:54 INFO - 'enabled': True, 05:30:54 INFO - 'halt_on_failure': False, 05:30:54 INFO - 'name': 'check_screen_resolution'},), 05:30:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:30:54 INFO - 'cmd': ('bash', 05:30:54 INFO - '-c', 05:30:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:30:54 INFO - 'enabled': True, 05:30:54 INFO - 'halt_on_failure': False, 05:30:54 INFO - 'name': 'check_screen_resolution'},), 05:30:54 INFO - 'run_cmd_checks_enabled': True, 05:30:54 INFO - 'sps_profile': False, 05:30:54 INFO - 'sps_profile_interval': 0, 05:30:54 INFO - 'suite': 'dromaeojs-e10s', 05:30:54 INFO - 'system_bits': '32', 05:30:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:30:54 INFO - 'title': 't-yosemite-r7-0324', 05:30:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:30:54 INFO - 'use_talos_json': True, 05:30:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:30:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:30:54 INFO - 'work_dir': 'build'} 05:30:54 INFO - [mozharness: 2016-10-19 12:30:54.108102Z] Running clobber step. 05:30:54 INFO - Running pre-action listener: _resource_record_pre_action 05:30:54 INFO - Running main action method: clobber 05:30:54 INFO - rmtree: /builds/slave/test/build 05:30:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:30:56 INFO - Running post-action listener: _resource_record_post_action 05:30:56 INFO - [mozharness: 2016-10-19 12:30:56.314333Z] Finished clobber step (success) 05:30:56 INFO - [mozharness: 2016-10-19 12:30:56.314441Z] Running read-buildbot-config step. 05:30:56 INFO - Running pre-action listener: _resource_record_pre_action 05:30:56 INFO - Running main action method: read_buildbot_config 05:30:56 INFO - Using buildbot properties: 05:30:56 INFO - { 05:30:56 INFO - "project": "", 05:30:56 INFO - "product": "firefox", 05:30:56 INFO - "script_repo_revision": "production", 05:30:56 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:30:56 INFO - "repository": "", 05:30:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:30:56 INFO - "buildid": "20161019030208", 05:30:56 INFO - "stage_platform": "macosx64", 05:30:56 INFO - "basedir": "/builds/slave/test", 05:30:56 INFO - "nightly_build": "True", 05:30:56 INFO - "buildnumber": 24, 05:30:56 INFO - "slavename": "t-yosemite-r7-0324", 05:30:56 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:30:56 INFO - "platform": "yosemite_r7", 05:30:56 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:30:56 INFO - "branch": "mozilla-central", 05:30:56 INFO - "repo_path": "mozilla-central", 05:30:56 INFO - "revision": "90d8afaddf9150853b0b68b35b30c1e54a8683e7", 05:30:56 INFO - "pgo_build": "False", 05:30:56 INFO - "builduid": "6be7e2a95936440a94c0d0edce1810db", 05:30:56 INFO - "slavebuilddir": "test" 05:30:56 INFO - } 05:30:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:30:56 INFO - Running post-action listener: _resource_record_post_action 05:30:56 INFO - [mozharness: 2016-10-19 12:30:56.317345Z] Finished read-buildbot-config step (success) 05:30:56 INFO - [mozharness: 2016-10-19 12:30:56.317456Z] Running download-and-extract step. 05:30:56 INFO - Running pre-action listener: _resource_record_pre_action 05:30:56 INFO - Running main action method: download_and_extract 05:30:56 INFO - mkdir: /builds/slave/test/build/tests 05:30:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:56 INFO - trying https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:30:56 INFO - Downloading https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/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 05:30:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/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 05:30:57 INFO - Downloaded 1199 bytes. 05:30:57 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:30:57 INFO - Using the following test package requirements: 05:30:57 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:30:57 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:30:57 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:30:57 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:30:57 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:30:57 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:30:57 INFO - u'jsshell-mac.zip'], 05:30:57 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:30:57 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:30:57 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:30:57 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:30:57 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:30:57 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:30:57 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:30:57 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:30:57 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:30:57 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:30:57 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:30:57 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:30:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:30:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:30:57 INFO - Fetch https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:30:58 INFO - Http code: 200 05:30:58 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 05:30:58 INFO - x-amz-cf-id: 2UbGMBnfXr5MxHwK_Gdbcbicw_5bddUYVHx1H1wgOK34Cr2hA-prmQ== 05:30:58 INFO - x-amz-version-id: ebjiOG1juwZ4_M0JAip.LLScCiEXEWev 05:30:58 INFO - Expected file size: 16500068 05:30:58 INFO - Obtained file size: 16500068 05:31:05 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 05:31:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:31:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:31:05 INFO - Fetch https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:31:05 INFO - Http code: 200 05:31:05 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 05:31:05 INFO - x-amz-cf-id: 3BeHPzeSk5Y7-LJJqy7MErlTPvZoWwEIJx9ZcgRF4GmjPgh_icDI2Q== 05:31:05 INFO - x-amz-version-id: ebjiOG1juwZ4_M0JAip.LLScCiEXEWev 05:31:06 INFO - Expected file size: 16500068 05:31:06 INFO - Obtained file size: 16500068 05:31:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:31:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:31:12 INFO - Fetch https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:31:13 INFO - Http code: 200 05:31:13 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 05:31:13 INFO - x-amz-cf-id: gzLT71bpCXevLQKXbrDDBdfvI3BX_Fcw6r3v7ssN0kxiHgNEzFZNng== 05:31:13 INFO - x-amz-version-id: wVMqC5J91RlvSqVNlEz.ni05QGHjMBak 05:31:14 INFO - Expected file size: 14409028 05:31:14 INFO - Obtained file size: 14409028 05:31: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')]} 05:31:14 INFO - trying https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:31:14 INFO - Downloading https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:31:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/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 05:31:21 INFO - Downloaded 111799087 bytes. 05:31:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:31:21 INFO - mkdir: /builds/slave/test/properties 05:31:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:31:21 INFO - Writing to file /builds/slave/test/properties/build_url 05:31:21 INFO - Contents: 05:31:21 INFO - build_url:https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:31:22 INFO - Running post-action listener: _resource_record_post_action 05:31:22 INFO - Running post-action listener: set_extra_try_arguments 05:31:22 INFO - [mozharness: 2016-10-19 12:31:22.535071Z] Finished download-and-extract step (success) 05:31:22 INFO - [mozharness: 2016-10-19 12:31:22.535225Z] Running populate-webroot step. 05:31:22 INFO - Running pre-action listener: _resource_record_pre_action 05:31:22 INFO - Running main action method: populate_webroot 05:31:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:31:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:31:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:31:22 INFO - '--tppagecycles', 05:31:22 INFO - '7'], 05:31:22 INFO - 'tests': ['tsvgm']}}, 05:31:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:31:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:31:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:31:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:31:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:31:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:31:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:22 INFO - 'talos_options': ['--disable-e10s'], 05:31:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:22 INFO - 'talos_options': ['--disable-e10s'], 05:31:22 INFO - 'tests': ['damp', 'tps']}, 05:31:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:22 INFO - 'tests': ['damp', 'tps']}, 05:31:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:31:22 INFO - 'tests': ['dromaeo_dom']}, 05:31:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:31:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:31:22 INFO - 'tests': ['basic_compositor_video']}, 05:31:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:31:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:31:22 INFO - 'tests': ['a11yr', 05:31:22 INFO - 'ts_paint', 05:31:22 INFO - 'tpaint', 05:31:22 INFO - 'sessionrestore', 05:31:22 INFO - 'sessionrestore_no_auto_restore', 05:31:22 INFO - 'tabpaint']}, 05:31:22 INFO - 'other-e10s': {'tests': ['a11yr', 05:31:22 INFO - 'ts_paint', 05:31:22 INFO - 'tpaint', 05:31:22 INFO - 'sessionrestore', 05:31:22 INFO - 'sessionrestore_no_auto_restore', 05:31:22 INFO - 'tabpaint']}, 05:31:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:31:22 INFO - 'ts_paint', 05:31:22 INFO - 'tpaint', 05:31:22 INFO - 'sessionrestore', 05:31:22 INFO - 'sessionrestore_no_auto_restore', 05:31:22 INFO - 'tabpaint']}, 05:31:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:31:22 INFO - 'ts_paint', 05:31:22 INFO - 'tpaint', 05:31:22 INFO - 'sessionrestore', 05:31:22 INFO - 'sessionrestore_no_auto_restore', 05:31:22 INFO - 'tabpaint']}, 05:31:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:31:22 INFO - 'tests': ['a11yr', 05:31:22 INFO - 'ts_paint', 05:31:22 INFO - 'tpaint', 05:31:22 INFO - 'sessionrestore', 05:31:22 INFO - 'sessionrestore_no_auto_restore', 05:31:22 INFO - 'tabpaint']}, 05:31:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:31:22 INFO - 'tests': ['a11yr', 05:31:22 INFO - 'ts_paint', 05:31:22 INFO - 'tpaint', 05:31:22 INFO - 'sessionrestore', 05:31:22 INFO - 'sessionrestore_no_auto_restore', 05:31:22 INFO - 'tabpaint']}, 05:31:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:31:22 INFO - 'tests': ['tsvgx', 05:31:22 INFO - 'tsvgr_opacity', 05:31:22 INFO - 'tart', 05:31:22 INFO - 'tscrollx', 05:31:22 INFO - 'cart']}, 05:31:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:31:22 INFO - 'tsvgr_opacity', 05:31:22 INFO - 'tart', 05:31:22 INFO - 'tscrollx', 05:31:22 INFO - 'cart']}, 05:31:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:22 INFO - 'talos_options': ['--disable-e10s'], 05:31:22 INFO - 'tests': ['tp5o']}, 05:31:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:22 INFO - 'tests': ['tp5o']}, 05:31:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:22 INFO - 'talos_options': ['--disable-e10s', 05:31:22 INFO - '--xperf_path', 05:31:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:22 INFO - 'tests': ['tp5n']}, 05:31:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:22 INFO - 'talos_options': ['--xperf_path', 05:31:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:22 INFO - 'tests': ['tp5n']}}, 05:31:22 INFO - 'talos.zip': {'path': '', 05:31:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:31:22 INFO - Running post-action listener: _resource_record_post_action 05:31:22 INFO - [mozharness: 2016-10-19 12:31:22.554003Z] Finished populate-webroot step (success) 05:31:22 INFO - [mozharness: 2016-10-19 12:31:22.554135Z] Running create-virtualenv step. 05:31:22 INFO - Running pre-action listener: _resource_record_pre_action 05:31:22 INFO - Running main action method: create_virtualenv 05:31:22 INFO - Creating virtualenv /builds/slave/test/build/venv 05:31:22 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 05:31:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:31:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:31:22 INFO - Using real prefix '/tools/python27' 05:31:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:31:23 INFO - Installing distribute.............................................................................................................................................................................................done. 05:31:26 INFO - Installing pip.................done. 05:31:26 INFO - Return code: 0 05:31:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:31:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:31:26 INFO - Reading from file tmpfile_stdout 05:31:26 INFO - Output received: 05:31:26 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) 05:31:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:31:26 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')]} 05:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:26 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')]} 05:31:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:26 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 0x101468490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb68c78390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127cd30>, '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 0x1010a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101274b20>, '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.2Va9BBWrVV/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.QDokAe62VO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 05:31:26 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 05:31:26 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 05:31:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Va9BBWrVV/Render', 05:31:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:26 INFO - 'HOME': '/Users/cltbld', 05:31:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:26 INFO - 'LOGNAME': 'cltbld', 05:31:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:26 INFO - 'MOZ_NO_REMOTE': '1', 05:31:26 INFO - 'NO_EM_RESTART': '1', 05:31:26 INFO - 'PAGER': '/bin/cat', 05:31:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:26 INFO - 'PWD': '/builds/slave/test', 05:31:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:26 INFO - 'SHELL': '/bin/bash', 05:31:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QDokAe62VO/Listeners', 05:31:26 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 05:31:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:26 INFO - 'USER': 'cltbld', 05:31:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:26 INFO - 'XPC_FLAGS': '0x0', 05:31:26 INFO - 'XPC_SERVICE_NAME': '0', 05:31:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:26 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 05:31:26 INFO - Cleaning up... 05:31:26 INFO - Return code: 0 05:31:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:31:26 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')]} 05:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:26 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')]} 05:31:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:26 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 0x101468490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb68c78390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127cd30>, '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 0x1010a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101274b20>, '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.2Va9BBWrVV/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.QDokAe62VO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 05:31:26 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 05:31:26 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 05:31:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Va9BBWrVV/Render', 05:31:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:26 INFO - 'HOME': '/Users/cltbld', 05:31:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:26 INFO - 'LOGNAME': 'cltbld', 05:31:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:26 INFO - 'MOZ_NO_REMOTE': '1', 05:31:26 INFO - 'NO_EM_RESTART': '1', 05:31:26 INFO - 'PAGER': '/bin/cat', 05:31:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:26 INFO - 'PWD': '/builds/slave/test', 05:31:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:26 INFO - 'SHELL': '/bin/bash', 05:31:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QDokAe62VO/Listeners', 05:31:26 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 05:31:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:26 INFO - 'USER': 'cltbld', 05:31:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:26 INFO - 'XPC_FLAGS': '0x0', 05:31:26 INFO - 'XPC_SERVICE_NAME': '0', 05:31:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:27 INFO - Downloading/unpacking psutil>=3.1.1 05:31:27 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 05:31:27 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 05:31:27 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 05:31:27 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 05:31:27 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 05:31:27 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 05:31:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:31:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:30 INFO - Installing collected packages: psutil 05:31:30 INFO - Running setup.py install for psutil 05:31:31 INFO - building 'psutil._psutil_osx' extension 05:31:31 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 05:31:31 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 05:31:31 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 05:31:31 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 05:31:31 INFO - building 'psutil._psutil_posix' extension 05:31:31 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 05:31:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:31:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:31:31 INFO - ^ 05:31:31 INFO - 1 warning generated. 05:31:31 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 05:31:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:32 INFO - Successfully installed psutil 05:31:32 INFO - Cleaning up... 05:31:32 INFO - Return code: 0 05:31:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101468490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb68c78390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127cd30>, '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 0x1010a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101274b20>, '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.2Va9BBWrVV/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.QDokAe62VO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 05:31:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:31:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Va9BBWrVV/Render', 05:31:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:32 INFO - 'HOME': '/Users/cltbld', 05:31:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:32 INFO - 'LOGNAME': 'cltbld', 05:31:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:32 INFO - 'MOZ_NO_REMOTE': '1', 05:31:32 INFO - 'NO_EM_RESTART': '1', 05:31:32 INFO - 'PAGER': '/bin/cat', 05:31:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:32 INFO - 'PWD': '/builds/slave/test', 05:31:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:32 INFO - 'SHELL': '/bin/bash', 05:31:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QDokAe62VO/Listeners', 05:31:32 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 05:31:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:32 INFO - 'USER': 'cltbld', 05:31:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:32 INFO - 'XPC_FLAGS': '0x0', 05:31:32 INFO - 'XPC_SERVICE_NAME': '0', 05:31:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:31:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:31:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:31:34 INFO - Installing collected packages: mozsystemmonitor 05:31:34 INFO - Running setup.py install for mozsystemmonitor 05:31:35 INFO - Successfully installed mozsystemmonitor 05:31:35 INFO - Cleaning up... 05:31:35 INFO - Return code: 0 05:31:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:31: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')]} 05:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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')]} 05:31:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:35 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 0x101468490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb68c78390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127cd30>, '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 0x1010a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101274b20>, '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.2Va9BBWrVV/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.QDokAe62VO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 05:31:35 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 05:31:35 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 05:31:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Va9BBWrVV/Render', 05:31:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:35 INFO - 'HOME': '/Users/cltbld', 05:31:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:35 INFO - 'LOGNAME': 'cltbld', 05:31:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:35 INFO - 'MOZ_NO_REMOTE': '1', 05:31:35 INFO - 'NO_EM_RESTART': '1', 05:31:35 INFO - 'PAGER': '/bin/cat', 05:31:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:35 INFO - 'PWD': '/builds/slave/test', 05:31:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:35 INFO - 'SHELL': '/bin/bash', 05:31:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QDokAe62VO/Listeners', 05:31:35 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 05:31:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:35 INFO - 'USER': 'cltbld', 05:31:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:35 INFO - 'XPC_FLAGS': '0x0', 05:31:35 INFO - 'XPC_SERVICE_NAME': '0', 05:31:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:35 INFO - Downloading/unpacking jsonschema==2.5.1 05:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:31:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:38 INFO - Downloading functools32-3.2.3-2.tar.gz 05:31:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:31:38 INFO - warning: no files found matching '*.txt' 05:31:38 INFO - no previously-included directories found matching 'build' 05:31:38 INFO - no previously-included directories found matching 'dist' 05:31:38 INFO - no previously-included directories found matching '.git*' 05:31:38 INFO - Installing collected packages: jsonschema, functools32 05:31:38 INFO - Running setup.py install for functools32 05:31:38 INFO - warning: no files found matching '*.txt' 05:31:38 INFO - no previously-included directories found matching 'build' 05:31:38 INFO - no previously-included directories found matching 'dist' 05:31:38 INFO - no previously-included directories found matching '.git*' 05:31:38 INFO - Successfully installed jsonschema functools32 05:31:38 INFO - Cleaning up... 05:31:38 INFO - Return code: 0 05:31:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101468490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb68c78390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127cd30>, '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 0x1010a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101274b20>, '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.2Va9BBWrVV/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.QDokAe62VO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 05:31:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:31:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Va9BBWrVV/Render', 05:31:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:38 INFO - 'HOME': '/Users/cltbld', 05:31:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:38 INFO - 'LOGNAME': 'cltbld', 05:31:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:38 INFO - 'MOZ_NO_REMOTE': '1', 05:31:38 INFO - 'NO_EM_RESTART': '1', 05:31:38 INFO - 'PAGER': '/bin/cat', 05:31:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:38 INFO - 'PWD': '/builds/slave/test', 05:31:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:38 INFO - 'SHELL': '/bin/bash', 05:31:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QDokAe62VO/Listeners', 05:31:38 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 05:31:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:38 INFO - 'USER': 'cltbld', 05:31:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:38 INFO - 'XPC_FLAGS': '0x0', 05:31:38 INFO - 'XPC_SERVICE_NAME': '0', 05:31:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:31:39 INFO - Cleaning up... 05:31:39 INFO - Return code: 0 05:31:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101468490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb68c78390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127cd30>, '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 0x1010a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101274b20>, '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.2Va9BBWrVV/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.QDokAe62VO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 05:31:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:31:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Va9BBWrVV/Render', 05:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:39 INFO - 'HOME': '/Users/cltbld', 05:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:39 INFO - 'LOGNAME': 'cltbld', 05:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:39 INFO - 'MOZ_NO_REMOTE': '1', 05:31:39 INFO - 'NO_EM_RESTART': '1', 05:31:39 INFO - 'PAGER': '/bin/cat', 05:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:39 INFO - 'PWD': '/builds/slave/test', 05:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:39 INFO - 'SHELL': '/bin/bash', 05:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QDokAe62VO/Listeners', 05:31:39 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 05:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:39 INFO - 'USER': 'cltbld', 05:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:39 INFO - 'XPC_FLAGS': '0x0', 05:31:39 INFO - 'XPC_SERVICE_NAME': '0', 05:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:39 INFO - Downloading/unpacking blobuploader==1.2.4 05:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:41 INFO - Downloading blobuploader-1.2.4.tar.gz 05:31:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:31:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:31:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:43 INFO - Downloading docopt-0.6.1.tar.gz 05:31:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:31:43 INFO - Installing collected packages: blobuploader, requests, docopt 05:31:43 INFO - Running setup.py install for blobuploader 05:31:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:31:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:31:43 INFO - Running setup.py install for requests 05:31:43 INFO - Running setup.py install for docopt 05:31:43 INFO - Successfully installed blobuploader requests docopt 05:31:43 INFO - Cleaning up... 05:31:44 INFO - Return code: 0 05:31:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31: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')]} 05:31:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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')]} 05:31:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101468490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb68c78390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127cd30>, '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 0x1010a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101274b20>, '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.2Va9BBWrVV/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.QDokAe62VO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 05:31:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:31:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:31:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Va9BBWrVV/Render', 05:31:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:44 INFO - 'HOME': '/Users/cltbld', 05:31:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:44 INFO - 'LOGNAME': 'cltbld', 05:31:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:44 INFO - 'MOZ_NO_REMOTE': '1', 05:31:44 INFO - 'NO_EM_RESTART': '1', 05:31:44 INFO - 'PAGER': '/bin/cat', 05:31:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:44 INFO - 'PWD': '/builds/slave/test', 05:31:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:44 INFO - 'SHELL': '/bin/bash', 05:31:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QDokAe62VO/Listeners', 05:31:44 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 05:31:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:44 INFO - 'USER': 'cltbld', 05:31:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:44 INFO - 'XPC_FLAGS': '0x0', 05:31:44 INFO - 'XPC_SERVICE_NAME': '0', 05:31:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:31:44 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-ZHUK9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:31:44 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-lvpADY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:31:44 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-na8tNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:31:44 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-OBjoh5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:31:44 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-XG8V1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:31:44 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-7obDx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:31:44 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-rEER2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:31:45 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-7Mo2DV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:31:45 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-eeXdud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:31:45 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-iNX_H7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:31:45 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-MuoPn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:31:45 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-sLAv6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:31:45 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-zlTbhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:31:45 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-NTyktQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:45 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-Y0bLYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:31:45 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-07QgZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:31:45 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-qPq8BI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:31:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:31:46 INFO - Running setup.py install for manifestparser 05:31:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:31:46 INFO - Running setup.py install for mozcrash 05:31:46 INFO - Running setup.py install for mozdebug 05:31:46 INFO - Running setup.py install for mozdevice 05:31:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:31:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:31:46 INFO - Running setup.py install for mozfile 05:31:46 INFO - Running setup.py install for mozhttpd 05:31:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:31:46 INFO - Running setup.py install for mozinfo 05:31:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Running setup.py install for mozInstall 05:31:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Running setup.py install for mozleak 05:31:47 INFO - Running setup.py install for mozlog 05:31:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Running setup.py install for moznetwork 05:31:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Running setup.py install for mozprocess 05:31:47 INFO - Running setup.py install for mozprofile 05:31:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:31:48 INFO - Running setup.py install for mozrunner 05:31:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:31:48 INFO - Running setup.py install for mozscreenshot 05:31:48 INFO - Running setup.py install for moztest 05:31:48 INFO - Running setup.py install for mozversion 05:31:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:31:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:31:48 INFO - Cleaning up... 05:31:48 INFO - Return code: 0 05:31:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31:48 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')]} 05:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:48 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')]} 05:31:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:48 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 0x101468490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb68c78390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127cd30>, '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 0x1010a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101274b20>, '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.2Va9BBWrVV/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.QDokAe62VO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 05:31:48 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 05:31:48 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 05:31:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Va9BBWrVV/Render', 05:31:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:48 INFO - 'HOME': '/Users/cltbld', 05:31:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:48 INFO - 'LOGNAME': 'cltbld', 05:31:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:48 INFO - 'MOZ_NO_REMOTE': '1', 05:31:48 INFO - 'NO_EM_RESTART': '1', 05:31:48 INFO - 'PAGER': '/bin/cat', 05:31:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:48 INFO - 'PWD': '/builds/slave/test', 05:31:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:48 INFO - 'SHELL': '/bin/bash', 05:31:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QDokAe62VO/Listeners', 05:31:48 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 05:31:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:48 INFO - 'USER': 'cltbld', 05:31:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:48 INFO - 'XPC_FLAGS': '0x0', 05:31:48 INFO - 'XPC_SERVICE_NAME': '0', 05:31:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:31:48 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-o6UwuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:31:49 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)) 05:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:31:49 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-Ui5yVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:31:49 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)) 05:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:31:49 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-hxSbtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:31:49 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)) 05:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:31:49 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-cq8UrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:31:49 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)) 05:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:31:49 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-Rn_4O1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:31:49 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)) 05:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:31:49 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-IxWDNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:31:49 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)) 05:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:31:49 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-hevpLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:31:49 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)) 05:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:31:49 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-woJAF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:31:49 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)) 05:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:31:49 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-8_dgG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:31:49 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)) 05:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:31:49 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-Fd5yBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:31:49 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)) 05:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:31:49 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-4FrpPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:31:50 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)) 05:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:31:50 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-7R78Y7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:31:50 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)) 05:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:31:50 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-m5pvSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:31:50 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)) 05:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:31:50 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-S6CAgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:31:50 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)) 05:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:50 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-pGvm6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:50 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)) 05:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:31:50 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-zCUpJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:31:50 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)) 05:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:31:50 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-MhGMko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:31:50 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)) 05:31:50 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)) 05:31:50 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)) 05:31:50 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)) 05:31:50 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)) 05:31:50 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)) 05:31:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:31:50 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 05:31:50 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 05:31:50 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 05:31:50 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 05:31:50 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 05:31:50 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 05:31:53 INFO - Downloading blessings-1.6.tar.gz 05:31:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:31:53 INFO - Installing collected packages: blessings 05:31:53 INFO - Running setup.py install for blessings 05:31:53 INFO - Successfully installed blessings 05:31:53 INFO - Cleaning up... 05:31:53 INFO - Return code: 0 05:31:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:31:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:31:53 INFO - Reading from file tmpfile_stdout 05:31:53 INFO - Current package versions: 05:31:53 INFO - blessings == 1.6 05:31:53 INFO - blobuploader == 1.2.4 05:31:53 INFO - docopt == 0.6.1 05:31:53 INFO - functools32 == 3.2.3-2 05:31:53 INFO - jsonschema == 2.5.1 05:31:53 INFO - manifestparser == 1.1 05:31:53 INFO - mozInstall == 1.12 05:31:53 INFO - mozcrash == 0.17 05:31:53 INFO - mozdebug == 0.1 05:31:53 INFO - mozdevice == 0.48 05:31:53 INFO - mozfile == 1.2 05:31:53 INFO - mozhttpd == 0.7 05:31:53 INFO - mozinfo == 0.9 05:31:53 INFO - mozleak == 0.1 05:31:53 INFO - mozlog == 3.4 05:31:53 INFO - moznetwork == 0.27 05:31:53 INFO - mozprocess == 0.23 05:31:53 INFO - mozprofile == 0.28 05:31:53 INFO - mozrunner == 6.12 05:31:53 INFO - mozscreenshot == 0.1 05:31:53 INFO - mozsystemmonitor == 0.3 05:31:53 INFO - moztest == 0.7 05:31:53 INFO - mozversion == 1.4 05:31:53 INFO - psutil == 3.1.1 05:31:53 INFO - requests == 1.2.3 05:31:53 INFO - wsgiref == 0.1.2 05:31:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:53 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 0x101468490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb68c78390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127cd30>, '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 0x1010a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101274b20>, '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.2Va9BBWrVV/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.QDokAe62VO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 05:31:53 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 05:31:53 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 05:31:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Va9BBWrVV/Render', 05:31:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:53 INFO - 'HOME': '/Users/cltbld', 05:31:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:53 INFO - 'LOGNAME': 'cltbld', 05:31:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:53 INFO - 'MOZ_NO_REMOTE': '1', 05:31:53 INFO - 'NO_EM_RESTART': '1', 05:31:53 INFO - 'PAGER': '/bin/cat', 05:31:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:53 INFO - 'PWD': '/builds/slave/test', 05:31:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:53 INFO - 'SHELL': '/bin/bash', 05:31:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QDokAe62VO/Listeners', 05:31:53 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 05:31:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:53 INFO - 'USER': 'cltbld', 05:31:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:53 INFO - 'XPC_FLAGS': '0x0', 05:31:53 INFO - 'XPC_SERVICE_NAME': '0', 05:31:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:54 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)) 05:31:54 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)) 05:31:54 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)) 05:31:54 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)) 05:31:54 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)) 05:31:54 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)) 05:31:54 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)) 05:31:54 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)) 05:31:54 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)) 05:31:54 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)) 05:31:54 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)) 05:31:54 INFO - Cleaning up... 05:31:54 INFO - Return code: 0 05:31:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:54 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 0x101468490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb68c78390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127cd30>, '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 0x1010a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101274b20>, '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.2Va9BBWrVV/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.QDokAe62VO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 05:31:54 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 05:31:54 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 05:31:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Va9BBWrVV/Render', 05:31:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:54 INFO - 'HOME': '/Users/cltbld', 05:31:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:54 INFO - 'LOGNAME': 'cltbld', 05:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:54 INFO - 'MOZ_NO_REMOTE': '1', 05:31:54 INFO - 'NO_EM_RESTART': '1', 05:31:54 INFO - 'PAGER': '/bin/cat', 05:31:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:54 INFO - 'PWD': '/builds/slave/test', 05:31:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:54 INFO - 'SHELL': '/bin/bash', 05:31:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QDokAe62VO/Listeners', 05:31:54 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 05:31:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:54 INFO - 'USER': 'cltbld', 05:31:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:54 INFO - 'XPC_FLAGS': '0x0', 05:31:54 INFO - 'XPC_SERVICE_NAME': '0', 05:31:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:31:54 INFO - Cleaning up... 05:31:54 INFO - Return code: 0 05:31:54 INFO - Running post-action listener: _resource_record_post_action 05:31:54 INFO - Running post-action listener: _start_resource_monitoring 05:31:54 INFO - Starting resource monitoring. 05:31:54 INFO - [mozharness: 2016-10-19 12:31:54.447883Z] Finished create-virtualenv step (success) 05:31:54 INFO - [mozharness: 2016-10-19 12:31:54.448919Z] Running install step. 05:31:54 INFO - Running pre-action listener: _resource_record_pre_action 05:31:54 INFO - Running main action method: install 05:31:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:31:54 INFO - Reading from file tmpfile_stdout 05:31:54 INFO - Detecting whether we're running mozinstall >=1.0... 05:31:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:31:54 INFO - Reading from file tmpfile_stdout 05:31:54 INFO - Output received: 05:31:54 INFO - Usage: mozinstall [options] installer 05:31:54 INFO - Options: 05:31:54 INFO - -h, --help show this help message and exit 05:31:54 INFO - -d DEST, --destination=DEST 05:31:54 INFO - Directory to install application into. [default: 05:31:54 INFO - "/builds/slave/test"] 05:31:54 INFO - --app=APP Application being installed. [default: firefox] 05:31:54 INFO - mkdir: /builds/slave/test/build/application 05:31:54 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'] 05:31:54 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 05:32:28 INFO - Reading from file tmpfile_stdout 05:32:28 INFO - Output received: 05:32:28 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:32:28 INFO - Running post-action listener: _resource_record_post_action 05:32:28 INFO - [mozharness: 2016-10-19 12:32:28.898414Z] Finished install step (success) 05:32:28 INFO - [mozharness: 2016-10-19 12:32:28.898528Z] Running run-tests step. 05:32:28 INFO - Running pre-action listener: _resource_record_pre_action 05:32:28 INFO - Running main action method: run_tests 05:32:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:32:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:32:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:32:28 INFO - 2016-10-19 05:32:28.912 screenresolution[1657:8394] starting screenresolution argv=screenresolution get 05:32:28 INFO - 2016-10-19 05:32:28.915 screenresolution[1657:8394] Display 0: 1600x1200x32@60 05:32:28 INFO - 2016-10-19 05:32:28.921 screenresolution[1658:8396] starting screenresolution argv=screenresolution list 05:32:28 INFO - Available Modes on Display 0 05:32:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:32:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:32:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:32:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:32:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:32:29 INFO - Intel Iris: 05:32:29 INFO - Chipset Model: Intel Iris 05:32:29 INFO - Type: GPU 05:32:29 INFO - Bus: Built-In 05:32:29 INFO - VRAM (Dynamic, Max): 1536 MB 05:32:29 INFO - Vendor: Intel (0x8086) 05:32:29 INFO - Device ID: 0x0a2e 05:32:29 INFO - Revision ID: 0x0009 05:32:29 INFO - Displays: 05:32:29 INFO - Display: 05:32:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:32:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:32:29 INFO - Display Serial Number: 1600x1200 60 05:32:29 INFO - Main Display: Yes 05:32:29 INFO - Mirror: Off 05:32:29 INFO - Online: Yes 05:32:29 INFO - Rotation: Supported 05:32:29 INFO - Return code: 0 05:32:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:32:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:32:29 INFO - Python 2.7.3 05:32:29 INFO - Return code: 0 05:32:29 INFO - grabbing minidump binary from tooltool 05:32:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:29 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 0x10127cd30>, '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 0x1010a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101274b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:32:29 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 05:32:29 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 05:32:29 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 05:32:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:32:29 INFO - Return code: 0 05:32:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:32:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:32:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:32:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:32:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:32:29 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0324', '--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 05:32:29 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0324 --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 05:32:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Va9BBWrVV/Render', 05:32:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:29 INFO - 'HOME': '/Users/cltbld', 05:32:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:29 INFO - 'LOGNAME': 'cltbld', 05:32:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:32:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:32:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:29 INFO - 'MOZ_NO_REMOTE': '1', 05:32:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:32:29 INFO - 'NO_EM_RESTART': '1', 05:32:29 INFO - 'PAGER': '/bin/cat', 05:32:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:29 INFO - 'PWD': '/builds/slave/test', 05:32:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:32:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:29 INFO - 'SHELL': '/bin/bash', 05:32:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QDokAe62VO/Listeners', 05:32:29 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 05:32:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:29 INFO - 'USER': 'cltbld', 05:32:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:29 INFO - 'XPC_FLAGS': '0x0', 05:32:29 INFO - 'XPC_SERVICE_NAME': '0', 05:32:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:29 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0324', '--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 05:32:29 INFO - mozversion application_buildid: 20161019030208 05:32:29 INFO - mozversion application_changeset: 90d8afaddf9150853b0b68b35b30c1e54a8683e7 05:32:29 INFO - mozversion application_display_name: Nightly 05:32:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:32:29 INFO - mozversion application_name: Firefox 05:32:29 INFO - mozversion application_remotingname: firefox 05:32:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:32:29 INFO - mozversion application_vendor: Mozilla 05:32:29 INFO - mozversion application_version: 52.0a1 05:32:29 INFO - mozversion platform_buildid: 20161019030208 05:32:29 INFO - mozversion platform_changeset: 90d8afaddf9150853b0b68b35b30c1e54a8683e7 05:32:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:32:29 INFO - mozversion platform_version: 52.0a1 05:32:29 INFO - using testdate: 1476880349 05:32:29 INFO - actual date: 1476880349 05:32:29 INFO - starting webserver on 'localhost:49282' 05:32:29 INFO - SUITE-START | Running 2 tests 05:32:29 INFO - TEST-START | dromaeo_css 05:32:29 INFO - operating with platform_type : mac_ 05:32:29 INFO - Initialising browser for dromaeo_css test... 05:32:29 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpDnqPnl/profile http://localhost:49282/getInfo.html) 05:32:32 INFO - PROCESS | 1668 | 2016-10-19 05:32:32.667 plugin-container[1672:8578] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x913f, name = 'com.apple.tsm.portname' 05:32:32 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:32:32 INFO - PROCESS | 1668 | 2016-10-19 05:32:32.667 plugin-container[1672:8578] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 05:32:32 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:32:32 INFO - PROCESS | 1668 | 2016-10-19 05:32:32.667 plugin-container[1672:8578] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:32:32 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 05:32:32 INFO - PROCESS | 1668 | colorDepth:24 05:32:32 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 05:32:32 INFO - PROCESS | 1668 | __metrics 05:32:32 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 05:32:33 INFO - TEST-INFO | 1668: exit 0 05:32:33 INFO - Browser initialized. 05:32:33 INFO - Running cycle 1/1 for dromaeo_css test... 05:32:33 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpDnqPnl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:32:35 INFO - PROCESS | 1673 | 2016-10-19 05:32:35.954 plugin-container[1674:8695] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 05:32:35 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:32:35 INFO - PROCESS | 1673 | 2016-10-19 05:32:35.955 plugin-container[1674:8695] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e03, name = 'com.apple.CFPasteboardClient' 05:32:35 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:32:35 INFO - PROCESS | 1673 | 2016-10-19 05:32:35.955 plugin-container[1674:8695] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:32:45 INFO - PROCESS | 1673 | RSS: Main: 237162496 05:32:45 INFO - PROCESS | 1673 | 05:33:35 INFO - PROCESS | 1673 | 1476880415313 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] 05:35:48 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49282/tests/dromaeo/cssquery-ext.html) 05:35:50 INFO - PROCESS | 1673 | RSS: Main: 251248640 05:35:50 INFO - PROCESS | 1673 | 05:38:52 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-ext.html (next: http://localhost:49282/tests/dromaeo/cssquery-jquery.html) 05:38:53 INFO - PROCESS | 1673 | RSS: Main: 263725056 05:38:53 INFO - PROCESS | 1673 | 05:41:55 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49282/tests/dromaeo/cssquery-mootools.html) 05:41:56 INFO - PROCESS | 1673 | RSS: Main: 268500992 05:41:56 INFO - PROCESS | 1673 | 05:44:58 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49282/tests/dromaeo/cssquery-prototype.html) 05:44:58 INFO - PROCESS | 1673 | RSS: Main: 268058624 05:44:58 INFO - PROCESS | 1673 | 05:48:01 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49282/tests/dromaeo/cssquery-yui.html) 05:48:02 INFO - PROCESS | 1673 | RSS: Main: 274411520 05:48:02 INFO - PROCESS | 1673 | 05:51:04 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-yui.html (next: http://localhost:49282/tests/dromaeo/cssquery-dojo.html) 05:51:05 INFO - PROCESS | 1673 | RSS: Main: 277020672 05:51:05 INFO - PROCESS | 1673 | 05:51:05 INFO - PROCESS | 1673 | __start_tp_report 05:51:05 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 05:51:05 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 05:51:05 INFO - PROCESS | 1673 | |i|pagename|runs| 05:51:05 INFO - PROCESS | 1673 | |0;dojo.html;3137.4352616529027;3390.711789497894;3394.7284217262622;3429.2284236046908;3456.7753096048764;5646.717664116798;5725.227094342263;5746.7413966371505;5799.739011744471;5834.56240781941;4802.1836287831065;5044.637947754104;5683.460071293221;5721.370649228582;5823.505002074829;5102.744862756866;5236.31927849379;5244.160934250521;5252.6323157379;5331.840044798662;5226.660267082628;5289.391719952197;5336.439673834249;5353.357597088336;5363.839084827442;5146.588272938156;5279.970405770876;5320.654157479771;5321.122014867542;5330.546903513206;5158.664686795346;5172.767225474862;5178.922316165261;5220.608454365938;5277.393099793535;5115.641905066647;5136.9743151284;5175.275461435402;5178.922316165261;5192.974035129838;5574.804881829116;5792.044312688401;5798.710064496759;5803.071508558611;5816.738246778905;6106.824719424884;6144.508439324888;6258.624482531062;6311.337309582474;6329.620222786648;4690.788914498856;5021.844975655619;5194.766235519411;5793.565482588822;5845.181674565564;4603;5774.191613174159;5884.676342801187;5888.263967004124;5929.792457264019;6179.474744646755;6199.597026193283;6210.441060304594;6264.342244064308;6269.7178626961895;5602.711376383455;5651.463111004448;6195.1636529068;6202.379762023852;6237.812865614039;6060.848478787977;6261.154744109487;6285.622862628257;6289.622622642564;6293.402126797947;6097.97841994295;6109.1010297800785;6138.7237574309265;6143.818944274807;6158.507319414436;5765.774710817687;6178.474829639531;6184.165137706442;6257.092721555349;6300.527460440485;4539.72761634303;4913.0173965207105;4970.055689418999;4983.953369792312;4990.251462280615;4322.783860806947;4375.321825117048;4405.669574781904;4406.713563618355;4433.733975961452;5824;5845.824625261249;5851.297844258716;5901.1148327750325;5955.702214889325;5204.271402003723;6227.501799856092;6241.812745617639;6273.874522509615;6291.5281353898645;5631.958087753778;5643.125315576092;5770.682612456355;5855.707214639251;5879.970600147058;5407.945920540823;5435.374931882884;5475.780968761284;5510.9448905511235;5543.223948647193;6361.522885783585;6390.233172019387;6395.872082558416;6412.166418365583;6414.679266036586;6171.990955184426;6177.104319873683;6243.906341404973;6255.96872015637;6280.685965701788;5340.546053585489;6271.655058971618;6273.717682704198;6277.780277690442;6324.715387807468;6224.62652240867;6230.937690623217;6235.345288744798;6253.218347706536;6278.8430289241305;5562.749676264659;6113.081058499921;6201.565890387629;6250.406211409909;6266.279377871603;6041.24484439445;6093.866968088049;6112.113743507078;6139.877202455837;6144.1398204250545;6096.677875983316;6110.43725517484;6118.908216376847;6133.110698948538;6141.263048434216;5861.6189947653265;5873.3832947539895;5940.376260492586;5980.192673988956;5999.550033747573;4923.433805112458;6126.235771789965;6150.538709596887;6168.198134242699;6207.131001659862;6003.099535069775;6030.849228769316;6032.668203248863;6033.78881739137;6066.878662426815 05:51:05 INFO - PROCESS | 1673 | |1;ext.html;466.98132074717;490.4408973769903;498.6833360815881;542.7204989430444;550.2599004339162;41068;42583.36124958124;42722.7863860681;42895.35656965148;43037.78481107598;33147.33705325892;34775.39184561156;35460.46809297863;36577;43823.56176438235;7676.53940763556;8784.560771961409;9570.425774453537;9695.36980096295;9740.415575065519;9613.951930240373;9677.177439917616;9691.176250018756;9717.222622190207;9717.514124293792;9015.737796708434;9628.614855405775;9677.419354838697;9721.31950763447;9725.854112188323;8925.866112008292;9600.03999600038;9649.372790768577;9678.516074196332;9682.612695492171;8754.080821513713;9108.59011344491;9550.90449095507;9637.855432168522;9657.903420965771;41477.1704565907;41535.1692966142;41569.96075098117;41827;41897.95255118616;3144.9685503144906;3331.4836200388977;3449.7412694047794;3526.2242306692488;3532.7880327180196;30313;31874.840625796955;32224.83887580547;32279.6772032279;32344.838275808706;3158.6683398243313;3159.3968605614145;3298.3238436120537;3354.9496757548654;3355.1947532592244;432421;469225;485044;487147;493073.53463232814;47796.522034779206;47849;59061.40938590645;59927;59941.700291498266;66469;67420.6628966852;67897.66051169712;67904;67941;16322.918385407997;16573.596828459806;16575.6684866302;16591.17044147812;16619.169041548117;12662.240265584094;12736;12755.362231888368;12795.296258705674;12800.295983720986;18005;18609.217343878045;19569.902150489157;19591.608167836563;19701;8096.878546821802;8138.877916831252;8302.8339433211;8308.833823323499;8313.833723325499;885.6014793343021;917.1424717888796;1049.9055085042382;1059.1156384419096;1117.7708569743183;30040.399192016037;31112.22219444532;31448.37103257922;31971.040868773973;33442.49836252458;1017.4454922067343;1020.9183265338755;1080.4111759091313;1114.375949468284;1115.486876037016;14714.411423543152;14877.479288225077;15428.768568471482;15564.766528502081;15878;658635;665754;667716;670328;671414;53751;56703.71648141733;57141.14288285679;57664.13503797446;57818;1348.1169833758904;1383.4950243161175;1389.1179101270766;1403.9859601403855;1409.1474657831864;24606.630900536147;28791;29311.85344073266;29832.701672982992;30035.3992920136;50529;50566.49433505618;51016.74491627518;51086.74456627693;51242.48757512377;589571;590812;591057;594292;595486;21403.036863340876;26974.730252697223;27135.45729085368;27149.72850271551;27166.72833271642;38138.42792358062;46969.53030469651;47493.76253118712;47994.520054799;48201.75899120482;39207.41188882112;39266;39327.6067239324;40733;40849.978750531474;29435.11694649164;32413.837930810194;38898.80550597229;39168;40546.1890762189;26294.868525658014;29844.253893652833;33840;37081.70214042495;38651.6134838648;28053.57919631248;33126.668733312355;35459.64540354667;35533.28933421369;35693.643063569034;23637.409064773517;25258.368540785894;25574.488510230065;25665.486690266465;26160.73839261583 05:51:05 INFO - PROCESS | 1673 | |2;jquery.html;375.12997706433424;376.20995908591954;381.7442313649855;386.480184152315;403.66495808478965;15844.683106337896;15877.761833572476;18889.905550472246;21507.032183551757;21768.782312176892;17528.298868045295;21206.151753929862;21526.246581369654;21552.76708493723;21873.781262187375;5790.334111577159;5942.227510423639;5971.686229029607;5993.220881285427;6069.848253793669;5761.9423805762035;5948.821535353946;5990;5996.700164991755;6007.249093863267;5640.520555752766;5867.794627188049;5898.7935422260225;5918.852028699274;5942.821715348547;5679.233303504016;5849.356570777232;5897.498712609433;5914.585978981473;5918.4673379395845;5072.72100034498;5177.378714554277;5180.792768289264;5182.067227898977;5194.324737784102;26347.604785928226;26396.60405093925;26592.202233933014;26605.467890642078;26950.865245673842;2288.5422915416984;2319.2926157521947;2506.9247922562354;2898.695636958131;2901.2601786544506;11640.4761785719;15039.420860809567;15444.691106177926;15554.766678499831;16504.58294226889;1815.1378095404577;1839.9172037258356;1854.239761697711;1876.9958072431184;1925.9325923592608;122576;124708.75291247062;125351;127321.36339318244;128021;8604.827903441896;10351.689449316533;10437.165026797838;11110.333379997226;11288.2662626929;13703.931480342535;13798.27521559805;13819.792703109462;13834.93082534581;13852.09961352529;8238.382121340923;8554.315654747605;8592.8711069334;8749.56252187388;8770.868436973451;8584.699535516227;8605.225529702357;8680.73957781255;8826.86759698605;8852.778680533038;6621.271660117383;6785.321467853175;6800.65996700163;6801.319868013159;6819.863602727918;1792.2203841329062;1821.207774618019;1832.8744520758867;1886.990565047166;1889.338731444011;879.3844308983737;897.3449381951082;933.9486328252011;934.1965909317981;983.188869182927;17550.210240539207;21565.784342156378;22001.779982199972;22595.774042259694;22629.981650825754;890.4167770110588;910.1490106750209;935.7613808478931;982.4995386740762;985.9105688214563;11086.889131108746;11432.885671143182;11787.587434439753;11973.52105915771;11996.820047699292;549671;569975;582115;593937;594566;121881;122687.38656306661;125832.74167258211;126432;126585;961.2550273537925;1012;1029.315505189041;1038.9272750907364;1042.4058286776465;16836.57908552247;17842.821571784636;18049.917004979743;19661;20071.49821254422;54879.725601371734;57380.71309643425;58048;58206;58206.708966456594;79718.40563188819;82220.355592889;83302;83753.58123209345;84577;6752.291009443938;8019.799505012421;8313.792155196168;8332.625031873702;8434.451760635538;11114.555417783198;12364.258144511357;12879.227246365248;13351.866481335062;13361.732765344834;11030.779384412106;11061.612843550438;11270.098392128773;11488.195826292414;11611.883881161419;10923.289986151192;11201.60794372193;11294.834648816131;11514.30914145154;11735.354555499529;9387.85918211241;11169.273997190157;11756.412179391167;11819.527218911491;11876.584319548774;8090.1797095655365;8272.462289951134;8319.417640765088;8328.250457458858;8389.874151887605;9827.754306142404;11248.718782030515;11609.941950290195;11620.6513804586;11698.824517632413 05:51:05 INFO - PROCESS | 1673 | |3;mootools.html;1543.606380373005;1578.0847108676965;1829.5700510380066;1905.4759941016227;1937.176699902542;7998.960005199966;8301.584920753954;8488.575571221438;8983.966843812963;9037.774055648604;8519.105493923142;8807.11928807119;8817.95591022044;8847.955760221206;8906.396848567256;2335.9883200584018;2384.475415408607;2386.6300723387817;2392.85642861428;2392.9641055384095;2347.9882600586975;2355.9764402355927;2380.3573035280465;2394.5091256292417;2398.424378149249;2380.131252092991;2384.3085505203558;2395.6765836612094;2396.0775101585928;2397.2209032064648;2316.3861576682193;2351.694279743631;2368.6683864259016;2372.205311220743;2380.8928598212947;2354.2466410748566;2356.375560476475;2363.8936247868887;2364.562555927159;2369.312899259213;8828;8871.689490867853;8879;8898.955505222497;8915.554222288925;5132.589392848563;5253.343332083489;5259.894802103937;5261.342332208474;5269.394019687747;3445.689887910075;3516.49010893419;3549.219171782204;3571.94642080369;3588.6949609283242;1040.8282633365486;1043.0143514378915;1047.010575006619;1060.7295139739394;1068.71144790906;10643.946780266126;10649.84025239622;10690;10694.839577406345;10755.731106722316;4224.091820258598;4275.29457639486;4520.841770538015;4663.976680116578;4718.905621887543;8226.670933162726;8835.337349698799;9380.718578442522;9383;9425.38734982224;5236.581073514109;5239.947600523946;5242.475752424803;5242.9213561796605;5250.264962905197;3512.297540491912;4212;4766.594839438687;4808.3989501312335;4918.8770280743265;3913;3969.146633473817;4014.598540145962;4037.192561487714;4052.6555242804693;1305.6344223617402;1309.8690130986824;1324.0665330941856;1326.8208791813174;1328.8671132886827;817.7015389382951;869.699953516036;891.0777345447431;891.4562117108559;911.9042500537348;5636.210930469738;5736.512396446349;5765.94234057654;5837.3870743571315;6019.759209631653;901.0763966933945;901.4366021236727;933.6032186320922;937.6718148648055;1000.849872519128;3519.3137338218803;3543.8659628919013;3724.2923844469465;3828.7128465364653;3934.960650393459;10388.844167337496;10621.628243011457;10716.35701857891;10736.08743256832;10769.623063192907;10682.092022178202;10694.732631684115;10694.983976522219;10695.572177112985;10732.302400343953;8126;8229.95885020571;8259.752207433787;8274.751757447286;8297.792555186048;4170.749755014709;5573.554115670656;5621.7189140542;5783.595148339576;5841.153032810304;8536.487810731127;9059.00350961407;9187.265018798615;9685.32202745801;10048.487878030492;8248.381371397289;9878.16035636979;9926.163659722974;10070.496475176065;10086.243531735003;6911.516193866381;7541.283578060075;7786.649600767866;8123.634436450256;8293.917060829555;8236.917630823615;8418.284445822173;8445.155484451505;8453.492790432594;8458.111898250596;6041.577089603861;7479.663415146223;7531.510451820614;7542.6605802740105;7560.886586701094;7286.96356518214;7307.3423391894985;7361.926380736124;7463.888041679271;7495.400367970658;6986;6996.125484314461;7053.188883278489;7058.435325174077;7062.752803651848;4671.789769460451;4865.489123642108;4936.407631084293;4950.8267210647455;5012.573931215888;4292.699511034198;4830.9371938173545;4871.2206047032305;4925;5111.900692184637 05:51:05 INFO - PROCESS | 1673 | |4;prototype.html;297.5046547498417;298.6998066942723;307.3760266658684;308.90339295500974;313.0748637775374;4302.440682711243;5204.219367094937;5336.065164159716;5410.756515956781;5434.402215756269;4817;5249.973750131244;5284.867878303045;5312.787488500456;5326.748214169667;4053.5135783705923;4119.464469618945;4121.4848143982;4126.441606204157;4149.937750933724;3869.5743468218475;3913.5108111486065;4061.5938406159444;4152.854650087247;4162.002238925317;4082.6733861291173;4094.222097801423;4094.7747873866924;4104.733192342503;4134.772587507686;3204.8237346945907;3794.9620503794886;4087.9780054986254;4113.958860411388;4130.277201489727;3479.68682818545;3588.049166970755;3624.7643903146477;3625.7099432045375;3640.7633503822167;4285.957140428618;4933.777979990909;5213.244079608436;5230.320058392423;5269.473052694738;3863.9034024149623;3937.7243592948507;4524.547545245449;4622.8150873965005;4712.835050773207;5681.23303354046;5691.630044047124;5711.514521265697;5725.398833122504;5732.426757324234;3486.38988177068;3700.703943684499;3860.498135242429;3907.8632247871474;3974.225026119908;5949.25634295713;6003.549733769941;6032.698365081772;6043.697815109271;6067.696615169224;3446.638102999149;3469.982650086784;3615.91522543236;3656.104254457675;4025.3156963316305;3218.5976752905885;3382.864685412605;3460.39443097462;3592.2815436912724;4045.8583949561626;4083.34666453372;4175;4480.58861458646;4672.76636168197;4709.95290047102;4785.617150627942;5683.204351390808;5691.235716927766;5773.24947756789;5779.739911703983;1308.900523560214;1359.294085922181;1363.175278956237;1386.5978866128912;1403.5087719298165;857.8370109679266;933.85992101184;941.5339406993583;959.5606590114828;978.3591747405461;4220.134872351161;4334.241507736134;4340.370646256339;5171.4052883918075;5207.3230480037355;5133.871653208625;5305.495977882095;5512.366077901012;5643.181738647873;5744.741486633112;4282.4583150065955;4379;4630.097131059479;5015.27278544609;5272.156454967187;2483.4784695214207;2642.3394151462135;2707.986460067687;2880;3016.8083606975124;5166.974165129225;5436.972815135899;5907.881842363215;6067.605605635707;6155.353687862799;5315.654482458628;5633.0987042073075;6035.547333949971;6091.451769340781;6093.878122437526;5331.573474122061;6021.277446706424;6061.333253342129;6098.664573448415;6113.480354169946;4550.179928028815;5338.03915295235;5926.614770039933;5954.76180952757;5977.3126090500145;5574.331080270394;5779.508741756998;5787.421257874179;5811;5857.861880413703;6111.327753947151;6157;6175.722092505758;6180.907286390798;6207.131001659862;3274.377868205129;3501.458525517943;3568.000959731279;3815.523059617548;3821.808909554567;5265.999460102568;5355.928814237246;5799.76800927975;5816.447437493432;5876.265466816648;4996.7001979881325;5498.45015498447;5667.914981275367;5723.914141287967;5758.884822303614;5139.199136470707;5629.493345598916;5711.685857277724;5712.942870571241;5729.770809167587;4877.561019508261;5520.227168196536;5569.470900264469;5582.748776305158;5624.240727501735;5450.427705091067;5734.684592347461;5787.813498232937;5855.00464920973;5859.355470898113;4638.744869032235;5246.921296180484;5336.745864721865;5386.4110087523495;5464.1257398816 05:51:05 INFO - PROCESS | 1673 | |5;yui.html;688.5971706551667;716.5879619218947;743.3384287983694;775.3680750188596;858.4720396955872;15585.376584936603;16269.674606507862;16463.917680411596;16472.176391180437;16507.91746041271;14567.92716036421;14895.553133406016;15083.09501429915;15101.62245943852;15130.319135638894;883.8718385834051;910.5629297936994;911.7720569857536;912.6851236323457;917.9127982841619;699.1819571101797;720.5172534401937;896.7982204004112;911.8586619074055;911.9785839859367;962.033156677538;970.917472014876;971.4851128901694;974.0648976982105;975.1029053270972;957.0716405087054;961.1253759079274;963.2005435488528;963.8554216867456;966.3525437956586;696.6760456387804;697.3340459860821;708.412018025038;719.5502810743285;721.9494635375526;12989;15496.457623983219;16351.427700030441;16391.09848958307;16392.75410868838;14561.490347837775;14589.6352591185;14801.481948131872;14814.111153330727;14888.330025148894;11903.464344104535;11975.94012029943;12146.878531214663;12232.81650775239;12278.079144064232;3758.962410375916;3957.881263562098;4006.6794656427705;4019.256437559061;4022.255882661717;110375;116169.41915290453;117274.41362793131;117455.41272293669;117543.4122829389;11168.944155279334;11418.600348987746;11431.14266430021;11447.599334023269;11628.360440175871;24035.038598456216;24100.39749006289;24100.638490422658;24112.87943560271;24534;8149.225823546753;8162.30620397261;8225.383096267804;8229.05365882919;8328.417010809306;4444.422225110779;4516.412866327357;4552.931706024412;4565.977170114128;4596.103759766814;3707.8146092695256;3804.771713697187;3806.08653923062;3819.1406933439753;3832.5784163741964;649.8960166373452;653.9803805885736;655.7016557466342;658.539022684123;676.5839009009424;603.9973643751351;614.0083764720034;630.6279295215753;636.8282360456782;663.6582160187507;9139.543022848831;9550.37922535033;9551.188149007412;9568.282378821477;9763.707088787347;573.5583600627493;621.2606997672755;646.524804268862;655.5968079631087;660.9768658096943;4600.056988317387;4707.31743897133;4728.338032675429;4737.95262047382;4749.836290108877;91642.16715665783;100819.49590252002;102344.48827755814;139250.60749392578;174868;111552;123025.15462268073;140171.47856517936;141205;143084;89951.10048899558;101228;102013.97986020193;121010.3949480247;128160.35919820527;9701.854472182782;9915.504224788876;9916.256280779115;10070.144037756872;10104.94947525287;49890;51318;56055.71972140113;60002.79994400175;63794;76161;85398.57300713705;101504.49247753713;102508;102898;14425.35085921157;14459.855401446272;14502.782458263346;14591.416343346147;14971.850281497045;15104.120117972869;15271.083734975937;15401.152936588147;15529.68940621204;15616.843831561539;14246.786298205743;14305;14323;14502.854971450572;14953.626159346104;14305.356258968162;14339.641508962359;14509.545622386571;14602.85397146015;14884.330205140577;13386.598402047954;13793.379297931819;13954.720905582035;14117.435302588192;14295.785563216352;11165.944170279097;11265.436728163395;11436.256643318491;11631.59289424866;11990.940045299718;9016.864747028669;9846.233750667883;10801.24391292602;11497.942510287396;11836.46735896904 05:51:05 INFO - PROCESS | 1673 | __end_tp_report 05:51:05 INFO - PROCESS | 1673 | __start_cc_report 05:51:05 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,375 05:51:05 INFO - PROCESS | 1673 | __end_cc_report 05:51:05 INFO - PROCESS | 1673 | __startTimestamp1476881465074__endTimestamp 05:51:05 INFO - PROCESS | 1673 | 05:51:05 INFO - PROCESS | 1673 | ------- Summary: start ------- 05:51:05 INFO - PROCESS | 1673 | Number of tests: 6 05:51:05 INFO - PROCESS | 1673 | 05:51:05 INFO - PROCESS | 1673 | [#0] dojo.html Cycles:165 Average:5694.14 Median:5873.38 stddev:655.70 (11.2%) stddev-sans-first:626.28 05:51:05 INFO - PROCESS | 1673 | Values: 3137.4 3390.7 3394.7 3429.2 3456.8 5646.7 5725.2 5746.7 5799.7 5834.6 4802.2 5044.6 5683.5 5721.4 5823.5 5102.7 5236.3 5244.2 5252.6 5331.8 5226.7 5289.4 5336.4 5353.4 5363.8 5146.6 5280.0 5320.7 5321.1 5330.5 5158.7 5172.8 5178.9 5220.6 5277.4 5115.6 5137.0 5175.3 5178.9 5193.0 5574.8 5792.0 5798.7 5803.1 5816.7 6106.8 6144.5 6258.6 6311.3 6329.6 4690.8 5021.8 5194.8 5793.6 5845.2 4603.0 5774.2 5884.7 5888.3 5929.8 6179.5 6199.6 6210.4 6264.3 6269.7 5602.7 5651.5 6195.2 6202.4 6237.8 6060.8 6261.2 6285.6 6289.6 6293.4 6098.0 6109.1 6138.7 6143.8 6158.5 5765.8 6178.5 6184.2 6257.1 6300.5 4539.7 4913.0 4970.1 4984.0 4990.3 4322.8 4375.3 4405.7 4406.7 4433.7 5824.0 5845.8 5851.3 5901.1 5955.7 5204.3 6227.5 6241.8 6273.9 6291.5 5632.0 5643.1 5770.7 5855.7 5880.0 5407.9 5435.4 5475.8 5510.9 5543.2 6361.5 6390.2 6395.9 6412.2 6414.7 6172.0 6177.1 6243.9 6256.0 6280.7 5340.5 6271.7 6273.7 6277.8 6324.7 6224.6 6230.9 6235.3 6253.2 6278.8 5562.7 6113.1 6201.6 6250.4 6266.3 6041.2 6093.9 6112.1 6139.9 6144.1 6096.7 6110.4 6118.9 6133.1 6141.3 5861.6 5873.4 5940.4 5980.2 5999.6 4923.4 6126.2 6150.5 6168.2 6207.1 6003.1 6030.8 6032.7 6033.8 6066.9 05:51:05 INFO - PROCESS | 1673 | 05:51:05 INFO - PROCESS | 1673 | [#1] ext.html Cycles:180 Average:70681.58 Median:40639.59 stddev:156014.42 (383.9%) stddev-sans-first:156363.02 05:51:05 INFO - PROCESS | 1673 | Values: 467.0 490.4 498.7 542.7 550.3 41068.0 42583.4 42722.8 42895.4 43037.8 33147.3 34775.4 35460.5 36577.0 43823.6 7676.5 8784.6 9570.4 9695.4 9740.4 9614.0 9677.2 9691.2 9717.2 9717.5 9015.7 9628.6 9677.4 9721.3 9725.9 8925.9 9600.0 9649.4 9678.5 9682.6 8754.1 9108.6 9550.9 9637.9 9657.9 41477.2 41535.2 41570.0 41827.0 41898.0 3145.0 3331.5 3449.7 3526.2 3532.8 30313.0 31874.8 32224.8 32279.7 32344.8 3158.7 3159.4 3298.3 3354.9 3355.2 432421.0 469225.0 485044.0 487147.0 493073.5 47796.5 47849.0 59061.4 59927.0 59941.7 66469.0 67420.7 67897.7 67904.0 67941.0 16322.9 16573.6 16575.7 16591.2 16619.2 12662.2 12736.0 12755.4 12795.3 12800.3 18005.0 18609.2 19569.9 19591.6 19701.0 8096.9 8138.9 8302.8 8308.8 8313.8 885.6 917.1 1049.9 1059.1 1117.8 30040.4 31112.2 31448.4 31971.0 33442.5 1017.4 1020.9 1080.4 1114.4 1115.5 14714.4 14877.5 15428.8 15564.8 15878.0 658635.0 665754.0 667716.0 670328.0 671414.0 53751.0 56703.7 57141.1 57664.1 57818.0 1348.1 1383.5 1389.1 1404.0 1409.1 24606.6 28791.0 29311.9 29832.7 30035.4 50529.0 50566.5 51016.7 51086.7 51242.5 589571.0 590812.0 591057.0 594292.0 595486.0 21403.0 26974.7 27135.5 27149.7 27166.7 38138.4 46969.5 47493.8 47994.5 48201.8 39207.4 39266.0 39327.6 40733.0 40850.0 29435.1 32413.8 38898.8 39168.0 40546.2 26294.9 29844.3 33840.0 37081.7 38651.6 28053.6 33126.7 35459.6 35533.3 35693.6 23637.4 25258.4 25574.5 25665.5 26160.7 05:51:05 INFO - PROCESS | 1673 | 05:51:05 INFO - PROCESS | 1673 | [#2] jquery.html Cycles:180 Average:35032.46 Median:26494.40 stddev:96855.88 (365.6%) stddev-sans-first:97092.63 05:51:05 INFO - PROCESS | 1673 | Values: 375.1 376.2 381.7 386.5 403.7 15844.7 15877.8 18889.9 21507.0 21768.8 17528.3 21206.2 21526.2 21552.8 21873.8 5790.3 5942.2 5971.7 5993.2 6069.8 5761.9 5948.8 5990.0 5996.7 6007.2 5640.5 5867.8 5898.8 5918.9 5942.8 5679.2 5849.4 5897.5 5914.6 5918.5 5072.7 5177.4 5180.8 5182.1 5194.3 26347.6 26396.6 26592.2 26605.5 26950.9 2288.5 2319.3 2506.9 2898.7 2901.3 11640.5 15039.4 15444.7 15554.8 16504.6 1815.1 1839.9 1854.2 1877.0 1925.9 122576.0 124708.8 125351.0 127321.4 128021.0 8604.8 10351.7 10437.2 11110.3 11288.3 13703.9 13798.3 13819.8 13834.9 13852.1 8238.4 8554.3 8592.9 8749.6 8770.9 8584.7 8605.2 8680.7 8826.9 8852.8 6621.3 6785.3 6800.7 6801.3 6819.9 1792.2 1821.2 1832.9 1887.0 1889.3 879.4 897.3 933.9 934.2 983.2 17550.2 21565.8 22001.8 22595.8 22630.0 890.4 910.1 935.8 982.5 985.9 11086.9 11432.9 11787.6 11973.5 11996.8 549671.0 569975.0 582115.0 593937.0 594566.0 121881.0 122687.4 125832.7 126432.0 126585.0 961.3 1012.0 1029.3 1038.9 1042.4 16836.6 17842.8 18049.9 19661.0 20071.5 54879.7 57380.7 58048.0 58206.0 58206.7 79718.4 82220.4 83302.0 83753.6 84577.0 6752.3 8019.8 8313.8 8332.6 8434.5 11114.6 12364.3 12879.2 13351.9 13361.7 11030.8 11061.6 11270.1 11488.2 11611.9 10923.3 11201.6 11294.8 11514.3 11735.4 9387.9 11169.3 11756.4 11819.5 11876.6 8090.2 8272.5 8319.4 8328.3 8389.9 9827.8 11248.7 11609.9 11620.7 11698.8 05:51:05 INFO - PROCESS | 1673 | 05:51:05 INFO - PROCESS | 1673 | [#3] mootools.html Cycles:180 Average:5600.19 Median:4930.70 stddev:3096.39 (62.8%) stddev-sans-first:3090.07 05:51:05 INFO - PROCESS | 1673 | Values: 1543.6 1578.1 1829.6 1905.5 1937.2 7999.0 8301.6 8488.6 8984.0 9037.8 8519.1 8807.1 8818.0 8848.0 8906.4 2336.0 2384.5 2386.6 2392.9 2393.0 2348.0 2356.0 2380.4 2394.5 2398.4 2380.1 2384.3 2395.7 2396.1 2397.2 2316.4 2351.7 2368.7 2372.2 2380.9 2354.2 2356.4 2363.9 2364.6 2369.3 8828.0 8871.7 8879.0 8899.0 8915.6 5132.6 5253.3 5259.9 5261.3 5269.4 3445.7 3516.5 3549.2 3571.9 3588.7 1040.8 1043.0 1047.0 1060.7 1068.7 10643.9 10649.8 10690.0 10694.8 10755.7 4224.1 4275.3 4520.8 4664.0 4718.9 8226.7 8835.3 9380.7 9383.0 9425.4 5236.6 5239.9 5242.5 5242.9 5250.3 3512.3 4212.0 4766.6 4808.4 4918.9 3913.0 3969.1 4014.6 4037.2 4052.7 1305.6 1309.9 1324.1 1326.8 1328.9 817.7 869.7 891.1 891.5 911.9 5636.2 5736.5 5765.9 5837.4 6019.8 901.1 901.4 933.6 937.7 1000.8 3519.3 3543.9 3724.3 3828.7 3935.0 10388.8 10621.6 10716.4 10736.1 10769.6 10682.1 10694.7 10695.0 10695.6 10732.3 8126.0 8230.0 8259.8 8274.8 8297.8 4170.7 5573.6 5621.7 5783.6 5841.2 8536.5 9059.0 9187.3 9685.3 10048.5 8248.4 9878.2 9926.2 10070.5 10086.2 6911.5 7541.3 7786.6 8123.6 8293.9 8236.9 8418.3 8445.2 8453.5 8458.1 6041.6 7479.7 7531.5 7542.7 7560.9 7287.0 7307.3 7361.9 7463.9 7495.4 6986.0 6996.1 7053.2 7058.4 7062.8 4671.8 4865.5 4936.4 4950.8 5012.6 4292.7 4830.9 4871.2 4925.0 5111.9 05:51:05 INFO - PROCESS | 1673 | 05:51:05 INFO - PROCESS | 1673 | [#4] prototype.html Cycles:180 Average:4553.24 Median:5205.77 stddev:1443.72 (27.7%) stddev-sans-first:1411.99 05:51:05 INFO - PROCESS | 1673 | Values: 297.5 298.7 307.4 308.9 313.1 4302.4 5204.2 5336.1 5410.8 5434.4 4817.0 5250.0 5284.9 5312.8 5326.7 4053.5 4119.5 4121.5 4126.4 4149.9 3869.6 3913.5 4061.6 4152.9 4162.0 4082.7 4094.2 4094.8 4104.7 4134.8 3204.8 3795.0 4088.0 4114.0 4130.3 3479.7 3588.0 3624.8 3625.7 3640.8 4286.0 4933.8 5213.2 5230.3 5269.5 3863.9 3937.7 4524.5 4622.8 4712.8 5681.2 5691.6 5711.5 5725.4 5732.4 3486.4 3700.7 3860.5 3907.9 3974.2 5949.3 6003.5 6032.7 6043.7 6067.7 3446.6 3470.0 3615.9 3656.1 4025.3 3218.6 3382.9 3460.4 3592.3 4045.9 4083.3 4175.0 4480.6 4672.8 4710.0 4785.6 5683.2 5691.2 5773.2 5779.7 1308.9 1359.3 1363.2 1386.6 1403.5 857.8 933.9 941.5 959.6 978.4 4220.1 4334.2 4340.4 5171.4 5207.3 5133.9 5305.5 5512.4 5643.2 5744.7 4282.5 4379.0 4630.1 5015.3 5272.2 2483.5 2642.3 2708.0 2880.0 3016.8 5167.0 5437.0 5907.9 6067.6 6155.4 5315.7 5633.1 6035.5 6091.5 6093.9 5331.6 6021.3 6061.3 6098.7 6113.5 4550.2 5338.0 5926.6 5954.8 5977.3 5574.3 5779.5 5787.4 5811.0 5857.9 6111.3 6157.0 6175.7 6180.9 6207.1 3274.4 3501.5 3568.0 3815.5 3821.8 5266.0 5355.9 5799.8 5816.4 5876.3 4996.7 5498.5 5667.9 5723.9 5758.9 5139.2 5629.5 5711.7 5712.9 5729.8 4877.6 5520.2 5569.5 5582.7 5624.2 5450.4 5734.7 5787.8 5855.0 5859.4 4638.7 5246.9 5336.7 5386.4 5464.1 05:51:05 INFO - PROCESS | 1673 | 05:51:05 INFO - PROCESS | 1673 | [#5] yui.html Cycles:180 Average:24544.49 Median:14120.91 stddev:38313.92 (271.3%) stddev-sans-first:38379.53 05:51:05 INFO - PROCESS | 1673 | Values: 688.6 716.6 743.3 775.4 858.5 15585.4 16269.7 16463.9 16472.2 16507.9 14567.9 14895.6 15083.1 15101.6 15130.3 883.9 910.6 911.8 912.7 917.9 699.2 720.5 896.8 911.9 912.0 962.0 970.9 971.5 974.1 975.1 957.1 961.1 963.2 963.9 966.4 696.7 697.3 708.4 719.6 721.9 12989.0 15496.5 16351.4 16391.1 16392.8 14561.5 14589.6 14801.5 14814.1 14888.3 11903.5 11975.9 12146.9 12232.8 12278.1 3759.0 3957.9 4006.7 4019.3 4022.3 110375.0 116169.4 117274.4 117455.4 117543.4 11168.9 11418.6 11431.1 11447.6 11628.4 24035.0 24100.4 24100.6 24112.9 24534.0 8149.2 8162.3 8225.4 8229.1 8328.4 4444.4 4516.4 4552.9 4566.0 4596.1 3707.8 3804.8 3806.1 3819.1 3832.6 649.9 654.0 655.7 658.5 676.6 604.0 614.0 630.6 636.8 663.7 9139.5 9550.4 9551.2 9568.3 9763.7 573.6 621.3 646.5 655.6 661.0 4600.1 4707.3 4728.3 4738.0 4749.8 91642.2 100819.5 102344.5 139250.6 174868.0 111552.0 123025.2 140171.5 141205.0 143084.0 89951.1 101228.0 102014.0 121010.4 128160.4 9701.9 9915.5 9916.3 10070.1 10104.9 49890.0 51318.0 56055.7 60002.8 63794.0 76161.0 85398.6 101504.5 102508.0 102898.0 14425.4 14459.9 14502.8 14591.4 14971.9 15104.1 15271.1 15401.2 15529.7 15616.8 14246.8 14305.0 14323.0 14502.9 14953.6 14305.4 14339.6 14509.5 14602.9 14884.3 13386.6 13793.4 13954.7 14117.4 14295.8 11165.9 11265.4 11436.3 11631.6 11990.9 9016.9 9846.2 10801.2 11497.9 11836.5 05:51:05 INFO - PROCESS | 1673 | -------- Summary: end -------- 05:51:05 INFO - PROCESS | 1673 | 05:51:05 INFO - TEST-INFO | 1673: exit 0 05:51:05 INFO - TEST-OK | dromaeo_css | took 1116211ms 05:51:05 INFO - TEST-START | kraken 05:51:05 INFO - operating with platform_type : mac_ 05:51:05 INFO - Initialising browser for kraken test... 05:51:05 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpZLxJ5R/profile http://localhost:49282/getInfo.html) 05:51:08 INFO - PROCESS | 1681 | 2016-10-19 05:51:08.310 plugin-container[1683:10715] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 05:51:08 INFO - PROCESS | 1681 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:51:08 INFO - PROCESS | 1681 | 2016-10-19 05:51:08.311 plugin-container[1683:10715] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 05:51:08 INFO - PROCESS | 1681 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:51:08 INFO - PROCESS | 1681 | 2016-10-19 05:51:08.311 plugin-container[1683:10715] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:51:08 INFO - PROCESS | 1681 | __metrics Screen width/height:1600/1200 05:51:08 INFO - PROCESS | 1681 | colorDepth:24 05:51:08 INFO - PROCESS | 1681 | Browser inner width/height: 1024/689 05:51:08 INFO - PROCESS | 1681 | __metrics 05:51:08 ERROR - PROCESS | 1681 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 05:51:09 INFO - TEST-INFO | 1681: exit 0 05:51:09 INFO - Browser initialized. 05:51:09 INFO - Running cycle 1/1 for kraken test... 05:51:09 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpZLxJ5R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:51:11 INFO - PROCESS | 1684 | 2016-10-19 05:51:11.492 plugin-container[1685:10830] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 05:51:11 INFO - PROCESS | 1684 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:51:11 INFO - PROCESS | 1684 | 2016-10-19 05:51:11.493 plugin-container[1685:10830] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 05:51:11 INFO - PROCESS | 1684 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:51:11 INFO - PROCESS | 1684 | 2016-10-19 05:51:11.493 plugin-container[1685:10830] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:51:21 INFO - PROCESS | 1684 | RSS: Main: 237215744 05:51:21 INFO - PROCESS | 1684 | 05:51:24 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-beat-detection) 05:51:25 INFO - PROCESS | 1684 | RSS: Main: 250261504 05:51:25 INFO - PROCESS | 1684 | 05:51:28 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-dft) 05:51:28 INFO - PROCESS | 1684 | RSS: Main: 258207744 05:51:28 INFO - PROCESS | 1684 | 05:51:32 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-fft) 05:51:32 INFO - PROCESS | 1684 | RSS: Main: 260673536 05:51:32 INFO - PROCESS | 1684 | 05:51:34 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-oscillator) 05:51:35 INFO - PROCESS | 1684 | RSS: Main: 262770688 05:51:35 INFO - PROCESS | 1684 | 05:51:37 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49282/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:51:38 INFO - PROCESS | 1684 | RSS: Main: 262959104 05:51:38 INFO - PROCESS | 1684 | 05:51:41 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49282/tests/kraken/driver.html?testName=imaging-darkroom) 05:51:41 INFO - PROCESS | 1684 | RSS: Main: 263593984 05:51:41 INFO - PROCESS | 1684 | 05:51:44 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49282/tests/kraken/driver.html?testName=imaging-desaturate) 05:51:45 INFO - PROCESS | 1684 | RSS: Main: 264052736 05:51:45 INFO - PROCESS | 1684 | 05:51:48 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49282/tests/kraken/driver.html?testName=json-parse-financial) 05:51:49 INFO - PROCESS | 1684 | RSS: Main: 263897088 05:51:49 INFO - PROCESS | 1684 | 05:51:50 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49282/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:51:51 INFO - PROCESS | 1684 | RSS: Main: 263741440 05:51:51 INFO - PROCESS | 1684 | 05:51:53 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:51:53 INFO - PROCESS | 1684 | RSS: Main: 263786496 05:51:53 INFO - PROCESS | 1684 | 05:51:56 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:51:56 INFO - PROCESS | 1684 | RSS: Main: 263901184 05:51:56 INFO - PROCESS | 1684 | 05:51:59 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:52:00 INFO - PROCESS | 1684 | RSS: Main: 264290304 05:52:00 INFO - PROCESS | 1684 | 05:52:03 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:52:04 INFO - PROCESS | 1684 | RSS: Main: 264204288 05:52:04 INFO - PROCESS | 1684 | 05:52:05 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49282/tests/kraken/driver.html?testName=ai-astar) 05:52:06 INFO - PROCESS | 1684 | RSS: Main: 265748480 05:52:06 INFO - PROCESS | 1684 | 05:52:06 INFO - PROCESS | 1684 | __start_tp_report 05:52:06 INFO - PROCESS | 1684 | _x_x_mozilla_page_load 05:52:06 INFO - PROCESS | 1684 | _x_x_mozilla_page_load_details 05:52:06 INFO - PROCESS | 1684 | |i|pagename|runs| 05:52:06 INFO - PROCESS | 1684 | |0;ai-astar;117;173;103;166;178;176;119;119;117;117 05:52:06 INFO - PROCESS | 1684 | |1;audio-beat-detection;150;134;147;185;118;121;118;119;148;103 05:52:06 INFO - PROCESS | 1684 | |2;audio-dft;260;276;199;199;195;196;195;194;198;200 05:52:06 INFO - PROCESS | 1684 | |3;audio-fft;82;71;83;112;85;63;62;62;82;60 05:52:06 INFO - PROCESS | 1684 | |4;audio-oscillator;83;70;90;73;70;102;119;70;71;89 05:52:06 INFO - PROCESS | 1684 | |5;imaging-gaussian-blur;105;105;122;134;138;87;91;86;106;111 05:52:06 INFO - PROCESS | 1684 | |6;imaging-darkroom;95;126;151;153;95;96;98;97;93;152 05:52:06 INFO - PROCESS | 1684 | |7;imaging-desaturate;140;140;86;86;87;89;88;90;89;88 05:52:06 INFO - PROCESS | 1684 | |8;json-parse-financial;55;57;57;57;57;60;59;58;57;57 05:52:06 INFO - PROCESS | 1684 | |9;json-stringify-tinderbox;70;76;70;73;81;74;75;74;76;76 05:52:06 INFO - PROCESS | 1684 | |10;stanford-crypto-aes;89;83;82;106;110;115;101;91;87;92 05:52:06 INFO - PROCESS | 1684 | |11;stanford-crypto-ccm;140;167;163;160;163;160;155;176;176;159 05:52:06 INFO - PROCESS | 1684 | |12;stanford-crypto-pbkdf2;176;182;172;171;176;176;179;176;173;174 05:52:06 INFO - PROCESS | 1684 | |13;stanford-crypto-sha256-iterative;68;70;72;72;73;71;69;72;72;72 05:52:06 INFO - PROCESS | 1684 | __end_tp_report 05:52:06 INFO - PROCESS | 1684 | __start_cc_report 05:52:06 INFO - PROCESS | 1684 | _x_x_mozilla_cycle_collect,953 05:52:06 INFO - PROCESS | 1684 | __end_cc_report 05:52:06 INFO - PROCESS | 1684 | __startTimestamp1476881526221__endTimestamp 05:52:06 INFO - PROCESS | 1684 | 05:52:06 INFO - PROCESS | 1684 | ------- Summary: start ------- 05:52:06 INFO - PROCESS | 1684 | Number of tests: 14 05:52:06 INFO - PROCESS | 1684 | 05:52:06 INFO - PROCESS | 1684 | [#0] ai-astar Cycles:10 Average:138.50 Median:142.50 stddev:30.41 (21.3%) stddev-sans-first:31.24 05:52:06 INFO - PROCESS | 1684 | Values: 117.0 173.0 103.0 166.0 178.0 176.0 119.0 119.0 117.0 117.0 05:52:06 INFO - PROCESS | 1684 | 05:52:06 INFO - PROCESS | 1684 | [#1] audio-beat-detection Cycles:10 Average:134.30 Median:140.50 stddev:23.78 (16.9%) stddev-sans-first:24.53 05:52:06 INFO - PROCESS | 1684 | Values: 150.0 134.0 147.0 185.0 118.0 121.0 118.0 119.0 148.0 103.0 05:52:06 INFO - PROCESS | 1684 | 05:52:06 INFO - PROCESS | 1684 | [#2] audio-dft Cycles:10 Average:211.20 Median:199.00 stddev:30.24 (15.2%) stddev-sans-first:26.42 05:52:06 INFO - PROCESS | 1684 | Values: 260.0 276.0 199.0 199.0 195.0 196.0 195.0 194.0 198.0 200.0 05:52:06 INFO - PROCESS | 1684 | 05:52:06 INFO - PROCESS | 1684 | [#3] audio-fft Cycles:10 Average:76.20 Median:76.50 stddev:16.12 (21.1%) stddev-sans-first:16.96 05:52:06 INFO - PROCESS | 1684 | Values: 82.0 71.0 83.0 112.0 85.0 63.0 62.0 62.0 82.0 60.0 05:52:06 INFO - PROCESS | 1684 | 05:52:06 INFO - PROCESS | 1684 | [#4] audio-oscillator Cycles:10 Average:83.70 Median:72.00 stddev:16.63 (23.1%) stddev-sans-first:17.63 05:52:06 INFO - PROCESS | 1684 | Values: 83.0 70.0 90.0 73.0 70.0 102.0 119.0 70.0 71.0 89.0 05:52:06 INFO - PROCESS | 1684 | 05:52:06 INFO - PROCESS | 1684 | [#5] imaging-gaussian-blur Cycles:10 Average:108.50 Median:136.00 stddev:18.30 (13.5%) stddev-sans-first:19.37 05:52:06 INFO - PROCESS | 1684 | Values: 105.0 105.0 122.0 134.0 138.0 87.0 91.0 86.0 106.0 111.0 05:52:06 INFO - PROCESS | 1684 | 05:52:06 INFO - PROCESS | 1684 | [#6] imaging-darkroom Cycles:10 Average:115.60 Median:95.00 stddev:26.84 (28.3%) stddev-sans-first:27.42 05:52:06 INFO - PROCESS | 1684 | Values: 95.0 126.0 151.0 153.0 95.0 96.0 98.0 97.0 93.0 152.0 05:52:06 INFO - PROCESS | 1684 | 05:52:06 INFO - PROCESS | 1684 | [#7] imaging-desaturate Cycles:10 Average:98.30 Median:88.00 stddev:22.02 (25.0%) stddev-sans-first:17.43 05:52:06 INFO - PROCESS | 1684 | Values: 140.0 140.0 86.0 86.0 87.0 89.0 88.0 90.0 89.0 88.0 05:52:06 INFO - PROCESS | 1684 | 05:52:06 INFO - PROCESS | 1684 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:57.00 stddev:1.35 (2.4%) stddev-sans-first:1.12 05:52:06 INFO - PROCESS | 1684 | Values: 55.0 57.0 57.0 57.0 57.0 60.0 59.0 58.0 57.0 57.0 05:52:06 INFO - PROCESS | 1684 | 05:52:06 INFO - PROCESS | 1684 | [#9] json-stringify-tinderbox Cycles:10 Average:74.50 Median:75.50 stddev:3.21 (4.2%) stddev-sans-first:2.96 05:52:06 INFO - PROCESS | 1684 | Values: 70.0 76.0 70.0 73.0 81.0 74.0 75.0 74.0 76.0 76.0 05:52:06 INFO - PROCESS | 1684 | 05:52:06 INFO - PROCESS | 1684 | [#10] stanford-crypto-aes Cycles:10 Average:95.60 Median:85.00 stddev:11.63 (13.7%) stddev-sans-first:12.08 05:52:06 INFO - PROCESS | 1684 | Values: 89.0 83.0 82.0 106.0 110.0 115.0 101.0 91.0 87.0 92.0 05:52:06 INFO - PROCESS | 1684 | 05:52:06 INFO - PROCESS | 1684 | [#11] stanford-crypto-ccm Cycles:10 Average:161.90 Median:163.00 stddev:10.38 (6.4%) stddev-sans-first:7.38 05:52:06 INFO - PROCESS | 1684 | Values: 140.0 167.0 163.0 160.0 163.0 160.0 155.0 176.0 176.0 159.0 05:52:06 INFO - PROCESS | 1684 | 05:52:06 INFO - PROCESS | 1684 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.50 Median:176.00 stddev:3.27 (1.9%) stddev-sans-first:3.47 05:52:06 INFO - PROCESS | 1684 | Values: 176.0 182.0 172.0 171.0 176.0 176.0 179.0 176.0 173.0 174.0 05:52:06 INFO - PROCESS | 1684 | 05:52:06 INFO - PROCESS | 1684 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.10 Median:72.00 stddev:1.60 (2.2%) stddev-sans-first:1.24 05:52:06 INFO - PROCESS | 1684 | Values: 68.0 70.0 72.0 72.0 73.0 71.0 69.0 72.0 72.0 72.0 05:52:06 INFO - PROCESS | 1684 | -------- Summary: end -------- 05:52:06 INFO - PROCESS | 1684 | 05:52:07 INFO - TEST-INFO | 1684: exit 0 05:52:07 INFO - TEST-OK | kraken | took 61571ms 05:52:07 INFO - SUITE-END | took 1177s 05:52:07 INFO - Completed test suite (00:19:37) 05:52:07 INFO - javascript benchmark 05:52:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7408.945605299558, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3137.4352616529027, 3390.711789497894, 3394.7284217262622, 3429.2284236046908, 3456.7753096048764, 5646.717664116798, 5725.227094342263, 5746.7413966371505, 5799.739011744471, 5834.56240781941, 4802.1836287831065, 5044.637947754104, 5683.460071293221, 5721.370649228582, 5823.505002074829, 5102.744862756866, 5236.31927849379, 5244.160934250521, 5252.6323157379, 5331.840044798662, 5226.660267082628, 5289.391719952197, 5336.439673834249, 5353.357597088336, 5363.839084827442, 5146.588272938156, 5279.970405770876, 5320.654157479771, 5321.122014867542, 5330.546903513206, 5158.664686795346, 5172.767225474862, 5178.922316165261, 5220.608454365938, 5277.393099793535, 5115.641905066647, 5136.9743151284, 5175.275461435402, 5178.922316165261, 5192.974035129838, 5574.804881829116, 5792.044312688401, 5798.710064496759, 5803.071508558611, 5816.738246778905, 6106.824719424884, 6144.508439324888, 6258.624482531062, 6311.337309582474, 6329.620222786648, 4690.788914498856, 5021.844975655619, 5194.766235519411, 5793.565482588822, 5845.181674565564, 4603.0, 5774.191613174159, 5884.676342801187, 5888.263967004124, 5929.792457264019, 6179.474744646755, 6199.597026193283, 6210.441060304594, 6264.342244064308, 6269.7178626961895, 5602.711376383455, 5651.463111004448, 6195.1636529068, 6202.379762023852, 6237.812865614039, 6060.848478787977, 6261.154744109487, 6285.622862628257, 6289.622622642564, 6293.402126797947, 6097.97841994295, 6109.1010297800785, 6138.7237574309265, 6143.818944274807, 6158.507319414436, 5765.774710817687, 6178.474829639531, 6184.165137706442, 6257.092721555349, 6300.527460440485, 4539.72761634303, 4913.0173965207105, 4970.055689418999, 4983.953369792312, 4990.251462280615, 4322.783860806947, 4375.321825117048, 4405.669574781904, 4406.713563618355, 4433.733975961452, 5824.0, 5845.824625261249, 5851.297844258716, 5901.1148327750325, 5955.702214889325, 5204.271402003723, 6227.501799856092, 6241.812745617639, 6273.874522509615, 6291.5281353898645, 5631.958087753778, 5643.125315576092, 5770.682612456355, 5855.707214639251, 5879.970600147058, 5407.945920540823, 5435.374931882884, 5475.780968761284, 5510.9448905511235, 5543.223948647193, 6361.522885783585, 6390.233172019387, 6395.872082558416, 6412.166418365583, 6414.679266036586, 6171.990955184426, 6177.104319873683, 6243.906341404973, 6255.96872015637, 6280.685965701788, 5340.546053585489, 6271.655058971618, 6273.717682704198, 6277.780277690442, 6324.715387807468, 6224.62652240867, 6230.937690623217, 6235.345288744798, 6253.218347706536, 6278.8430289241305, 5562.749676264659, 6113.081058499921, 6201.565890387629, 6250.406211409909, 6266.279377871603, 6041.24484439445, 6093.866968088049, 6112.113743507078, 6139.877202455837, 6144.1398204250545, 6096.677875983316, 6110.43725517484, 6118.908216376847, 6133.110698948538, 6141.263048434216, 5861.6189947653265, 5873.3832947539895, 5940.376260492586, 5980.192673988956, 5999.550033747573, 4923.433805112458, 6126.235771789965, 6150.538709596887, 6168.198134242699, 6207.131001659862, 6003.099535069775, 6030.849228769316, 6032.668203248863, 6033.78881739137, 6066.878662426815], "value": 5654.2555350683215, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.98132074717, 490.4408973769903, 498.6833360815881, 542.7204989430444, 550.2599004339162, 41068.0, 42583.36124958124, 42722.7863860681, 42895.35656965148, 43037.78481107598, 33147.33705325892, 34775.39184561156, 35460.46809297863, 36577.0, 43823.56176438235, 7676.53940763556, 8784.560771961409, 9570.425774453537, 9695.36980096295, 9740.415575065519, 9613.951930240373, 9677.177439917616, 9691.176250018756, 9717.222622190207, 9717.514124293792, 9015.737796708434, 9628.614855405775, 9677.419354838697, 9721.31950763447, 9725.854112188323, 8925.866112008292, 9600.03999600038, 9649.372790768577, 9678.516074196332, 9682.612695492171, 8754.080821513713, 9108.59011344491, 9550.90449095507, 9637.855432168522, 9657.903420965771, 41477.1704565907, 41535.1692966142, 41569.96075098117, 41827.0, 41897.95255118616, 3144.9685503144906, 3331.4836200388977, 3449.7412694047794, 3526.2242306692488, 3532.7880327180196, 30313.0, 31874.840625796955, 32224.83887580547, 32279.6772032279, 32344.838275808706, 3158.6683398243313, 3159.3968605614145, 3298.3238436120537, 3354.9496757548654, 3355.1947532592244, 432421.0, 469225.0, 485044.0, 487147.0, 493073.53463232814, 47796.522034779206, 47849.0, 59061.40938590645, 59927.0, 59941.700291498266, 66469.0, 67420.6628966852, 67897.66051169712, 67904.0, 67941.0, 16322.918385407997, 16573.596828459806, 16575.6684866302, 16591.17044147812, 16619.169041548117, 12662.240265584094, 12736.0, 12755.362231888368, 12795.296258705674, 12800.295983720986, 18005.0, 18609.217343878045, 19569.902150489157, 19591.608167836563, 19701.0, 8096.878546821802, 8138.877916831252, 8302.8339433211, 8308.833823323499, 8313.833723325499, 885.6014793343021, 917.1424717888796, 1049.9055085042382, 1059.1156384419096, 1117.7708569743183, 30040.399192016037, 31112.22219444532, 31448.37103257922, 31971.040868773973, 33442.49836252458, 1017.4454922067343, 1020.9183265338755, 1080.4111759091313, 1114.375949468284, 1115.486876037016, 14714.411423543152, 14877.479288225077, 15428.768568471482, 15564.766528502081, 15878.0, 658635.0, 665754.0, 667716.0, 670328.0, 671414.0, 53751.0, 56703.71648141733, 57141.14288285679, 57664.13503797446, 57818.0, 1348.1169833758904, 1383.4950243161175, 1389.1179101270766, 1403.9859601403855, 1409.1474657831864, 24606.630900536147, 28791.0, 29311.85344073266, 29832.701672982992, 30035.3992920136, 50529.0, 50566.49433505618, 51016.74491627518, 51086.74456627693, 51242.48757512377, 589571.0, 590812.0, 591057.0, 594292.0, 595486.0, 21403.036863340876, 26974.730252697223, 27135.45729085368, 27149.72850271551, 27166.72833271642, 38138.42792358062, 46969.53030469651, 47493.76253118712, 47994.520054799, 48201.75899120482, 39207.41188882112, 39266.0, 39327.6067239324, 40733.0, 40849.978750531474, 29435.11694649164, 32413.837930810194, 38898.80550597229, 39168.0, 40546.1890762189, 26294.868525658014, 29844.253893652833, 33840.0, 37081.70214042495, 38651.6134838648, 28053.57919631248, 33126.668733312355, 35459.64540354667, 35533.28933421369, 35693.643063569034, 23637.409064773517, 25258.368540785894, 25574.488510230065, 25665.486690266465, 26160.73839261583], "value": 19900.728608063044, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.12997706433424, 376.20995908591954, 381.7442313649855, 386.480184152315, 403.66495808478965, 15844.683106337896, 15877.761833572476, 18889.905550472246, 21507.032183551757, 21768.782312176892, 17528.298868045295, 21206.151753929862, 21526.246581369654, 21552.76708493723, 21873.781262187375, 5790.334111577159, 5942.227510423639, 5971.686229029607, 5993.220881285427, 6069.848253793669, 5761.9423805762035, 5948.821535353946, 5990.0, 5996.700164991755, 6007.249093863267, 5640.520555752766, 5867.794627188049, 5898.7935422260225, 5918.852028699274, 5942.821715348547, 5679.233303504016, 5849.356570777232, 5897.498712609433, 5914.585978981473, 5918.4673379395845, 5072.72100034498, 5177.378714554277, 5180.792768289264, 5182.067227898977, 5194.324737784102, 26347.604785928226, 26396.60405093925, 26592.202233933014, 26605.467890642078, 26950.865245673842, 2288.5422915416984, 2319.2926157521947, 2506.9247922562354, 2898.695636958131, 2901.2601786544506, 11640.4761785719, 15039.420860809567, 15444.691106177926, 15554.766678499831, 16504.58294226889, 1815.1378095404577, 1839.9172037258356, 1854.239761697711, 1876.9958072431184, 1925.9325923592608, 122576.0, 124708.75291247062, 125351.0, 127321.36339318244, 128021.0, 8604.827903441896, 10351.689449316533, 10437.165026797838, 11110.333379997226, 11288.2662626929, 13703.931480342535, 13798.27521559805, 13819.792703109462, 13834.93082534581, 13852.09961352529, 8238.382121340923, 8554.315654747605, 8592.8711069334, 8749.56252187388, 8770.868436973451, 8584.699535516227, 8605.225529702357, 8680.73957781255, 8826.86759698605, 8852.778680533038, 6621.271660117383, 6785.321467853175, 6800.65996700163, 6801.319868013159, 6819.863602727918, 1792.2203841329062, 1821.207774618019, 1832.8744520758867, 1886.990565047166, 1889.338731444011, 879.3844308983737, 897.3449381951082, 933.9486328252011, 934.1965909317981, 983.188869182927, 17550.210240539207, 21565.784342156378, 22001.779982199972, 22595.774042259694, 22629.981650825754, 890.4167770110588, 910.1490106750209, 935.7613808478931, 982.4995386740762, 985.9105688214563, 11086.889131108746, 11432.885671143182, 11787.587434439753, 11973.52105915771, 11996.820047699292, 549671.0, 569975.0, 582115.0, 593937.0, 594566.0, 121881.0, 122687.38656306661, 125832.74167258211, 126432.0, 126585.0, 961.2550273537925, 1012.0, 1029.315505189041, 1038.9272750907364, 1042.4058286776465, 16836.57908552247, 17842.821571784636, 18049.917004979743, 19661.0, 20071.49821254422, 54879.725601371734, 57380.71309643425, 58048.0, 58206.0, 58206.708966456594, 79718.40563188819, 82220.355592889, 83302.0, 83753.58123209345, 84577.0, 6752.291009443938, 8019.799505012421, 8313.792155196168, 8332.625031873702, 8434.451760635538, 11114.555417783198, 12364.258144511357, 12879.227246365248, 13351.866481335062, 13361.732765344834, 11030.779384412106, 11061.612843550438, 11270.098392128773, 11488.195826292414, 11611.883881161419, 10923.289986151192, 11201.60794372193, 11294.834648816131, 11514.30914145154, 11735.354555499529, 9387.85918211241, 11169.273997190157, 11756.412179391167, 11819.527218911491, 11876.584319548774, 8090.1797095655365, 8272.462289951134, 8319.417640765088, 8328.250457458858, 8389.874151887605, 9827.754306142404, 11248.718782030515, 11609.941950290195, 11620.6513804586, 11698.824517632413], "value": 9838.037720487913, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1543.606380373005, 1578.0847108676965, 1829.5700510380066, 1905.4759941016227, 1937.176699902542, 7998.960005199966, 8301.584920753954, 8488.575571221438, 8983.966843812963, 9037.774055648604, 8519.105493923142, 8807.11928807119, 8817.95591022044, 8847.955760221206, 8906.396848567256, 2335.9883200584018, 2384.475415408607, 2386.6300723387817, 2392.85642861428, 2392.9641055384095, 2347.9882600586975, 2355.9764402355927, 2380.3573035280465, 2394.5091256292417, 2398.424378149249, 2380.131252092991, 2384.3085505203558, 2395.6765836612094, 2396.0775101585928, 2397.2209032064648, 2316.3861576682193, 2351.694279743631, 2368.6683864259016, 2372.205311220743, 2380.8928598212947, 2354.2466410748566, 2356.375560476475, 2363.8936247868887, 2364.562555927159, 2369.312899259213, 8828.0, 8871.689490867853, 8879.0, 8898.955505222497, 8915.554222288925, 5132.589392848563, 5253.343332083489, 5259.894802103937, 5261.342332208474, 5269.394019687747, 3445.689887910075, 3516.49010893419, 3549.219171782204, 3571.94642080369, 3588.6949609283242, 1040.8282633365486, 1043.0143514378915, 1047.010575006619, 1060.7295139739394, 1068.71144790906, 10643.946780266126, 10649.84025239622, 10690.0, 10694.839577406345, 10755.731106722316, 4224.091820258598, 4275.29457639486, 4520.841770538015, 4663.976680116578, 4718.905621887543, 8226.670933162726, 8835.337349698799, 9380.718578442522, 9383.0, 9425.38734982224, 5236.581073514109, 5239.947600523946, 5242.475752424803, 5242.9213561796605, 5250.264962905197, 3512.297540491912, 4212.0, 4766.594839438687, 4808.3989501312335, 4918.8770280743265, 3913.0, 3969.146633473817, 4014.598540145962, 4037.192561487714, 4052.6555242804693, 1305.6344223617402, 1309.8690130986824, 1324.0665330941856, 1326.8208791813174, 1328.8671132886827, 817.7015389382951, 869.699953516036, 891.0777345447431, 891.4562117108559, 911.9042500537348, 5636.210930469738, 5736.512396446349, 5765.94234057654, 5837.3870743571315, 6019.759209631653, 901.0763966933945, 901.4366021236727, 933.6032186320922, 937.6718148648055, 1000.849872519128, 3519.3137338218803, 3543.8659628919013, 3724.2923844469465, 3828.7128465364653, 3934.960650393459, 10388.844167337496, 10621.628243011457, 10716.35701857891, 10736.08743256832, 10769.623063192907, 10682.092022178202, 10694.732631684115, 10694.983976522219, 10695.572177112985, 10732.302400343953, 8126.0, 8229.95885020571, 8259.752207433787, 8274.751757447286, 8297.792555186048, 4170.749755014709, 5573.554115670656, 5621.7189140542, 5783.595148339576, 5841.153032810304, 8536.487810731127, 9059.00350961407, 9187.265018798615, 9685.32202745801, 10048.487878030492, 8248.381371397289, 9878.16035636979, 9926.163659722974, 10070.496475176065, 10086.243531735003, 6911.516193866381, 7541.283578060075, 7786.649600767866, 8123.634436450256, 8293.917060829555, 8236.917630823615, 8418.284445822173, 8445.155484451505, 8453.492790432594, 8458.111898250596, 6041.577089603861, 7479.663415146223, 7531.510451820614, 7542.6605802740105, 7560.886586701094, 7286.96356518214, 7307.3423391894985, 7361.926380736124, 7463.888041679271, 7495.400367970658, 6986.0, 6996.125484314461, 7053.188883278489, 7058.435325174077, 7062.752803651848, 4671.789769460451, 4865.489123642108, 4936.407631084293, 4950.8267210647455, 5012.573931215888, 4292.699511034198, 4830.9371938173545, 4871.2206047032305, 4925.0, 5111.900692184637], "value": 4531.391821661999, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.5046547498417, 298.6998066942723, 307.3760266658684, 308.90339295500974, 313.0748637775374, 4302.440682711243, 5204.219367094937, 5336.065164159716, 5410.756515956781, 5434.402215756269, 4817.0, 5249.973750131244, 5284.867878303045, 5312.787488500456, 5326.748214169667, 4053.5135783705923, 4119.464469618945, 4121.4848143982, 4126.441606204157, 4149.937750933724, 3869.5743468218475, 3913.5108111486065, 4061.5938406159444, 4152.854650087247, 4162.002238925317, 4082.6733861291173, 4094.222097801423, 4094.7747873866924, 4104.733192342503, 4134.772587507686, 3204.8237346945907, 3794.9620503794886, 4087.9780054986254, 4113.958860411388, 4130.277201489727, 3479.68682818545, 3588.049166970755, 3624.7643903146477, 3625.7099432045375, 3640.7633503822167, 4285.957140428618, 4933.777979990909, 5213.244079608436, 5230.320058392423, 5269.473052694738, 3863.9034024149623, 3937.7243592948507, 4524.547545245449, 4622.8150873965005, 4712.835050773207, 5681.23303354046, 5691.630044047124, 5711.514521265697, 5725.398833122504, 5732.426757324234, 3486.38988177068, 3700.703943684499, 3860.498135242429, 3907.8632247871474, 3974.225026119908, 5949.25634295713, 6003.549733769941, 6032.698365081772, 6043.697815109271, 6067.696615169224, 3446.638102999149, 3469.982650086784, 3615.91522543236, 3656.104254457675, 4025.3156963316305, 3218.5976752905885, 3382.864685412605, 3460.39443097462, 3592.2815436912724, 4045.8583949561626, 4083.34666453372, 4175.0, 4480.58861458646, 4672.76636168197, 4709.95290047102, 4785.617150627942, 5683.204351390808, 5691.235716927766, 5773.24947756789, 5779.739911703983, 1308.900523560214, 1359.294085922181, 1363.175278956237, 1386.5978866128912, 1403.5087719298165, 857.8370109679266, 933.85992101184, 941.5339406993583, 959.5606590114828, 978.3591747405461, 4220.134872351161, 4334.241507736134, 4340.370646256339, 5171.4052883918075, 5207.3230480037355, 5133.871653208625, 5305.495977882095, 5512.366077901012, 5643.181738647873, 5744.741486633112, 4282.4583150065955, 4379.0, 4630.097131059479, 5015.27278544609, 5272.156454967187, 2483.4784695214207, 2642.3394151462135, 2707.986460067687, 2880.0, 3016.8083606975124, 5166.974165129225, 5436.972815135899, 5907.881842363215, 6067.605605635707, 6155.353687862799, 5315.654482458628, 5633.0987042073075, 6035.547333949971, 6091.451769340781, 6093.878122437526, 5331.573474122061, 6021.277446706424, 6061.333253342129, 6098.664573448415, 6113.480354169946, 4550.179928028815, 5338.03915295235, 5926.614770039933, 5954.76180952757, 5977.3126090500145, 5574.331080270394, 5779.508741756998, 5787.421257874179, 5811.0, 5857.861880413703, 6111.327753947151, 6157.0, 6175.722092505758, 6180.907286390798, 6207.131001659862, 3274.377868205129, 3501.458525517943, 3568.000959731279, 3815.523059617548, 3821.808909554567, 5265.999460102568, 5355.928814237246, 5799.76800927975, 5816.447437493432, 5876.265466816648, 4996.7001979881325, 5498.45015498447, 5667.914981275367, 5723.914141287967, 5758.884822303614, 5139.199136470707, 5629.493345598916, 5711.685857277724, 5712.942870571241, 5729.770809167587, 4877.561019508261, 5520.227168196536, 5569.470900264469, 5582.748776305158, 5624.240727501735, 5450.427705091067, 5734.684592347461, 5787.813498232937, 5855.00464920973, 5859.355470898113, 4638.744869032235, 5246.921296180484, 5336.745864721865, 5386.4110087523495, 5464.1257398816], "value": 4099.842267389944, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.5971706551667, 716.5879619218947, 743.3384287983694, 775.3680750188596, 858.4720396955872, 15585.376584936603, 16269.674606507862, 16463.917680411596, 16472.176391180437, 16507.91746041271, 14567.92716036421, 14895.553133406016, 15083.09501429915, 15101.62245943852, 15130.319135638894, 883.8718385834051, 910.5629297936994, 911.7720569857536, 912.6851236323457, 917.9127982841619, 699.1819571101797, 720.5172534401937, 896.7982204004112, 911.8586619074055, 911.9785839859367, 962.033156677538, 970.917472014876, 971.4851128901694, 974.0648976982105, 975.1029053270972, 957.0716405087054, 961.1253759079274, 963.2005435488528, 963.8554216867456, 966.3525437956586, 696.6760456387804, 697.3340459860821, 708.412018025038, 719.5502810743285, 721.9494635375526, 12989.0, 15496.457623983219, 16351.427700030441, 16391.09848958307, 16392.75410868838, 14561.490347837775, 14589.6352591185, 14801.481948131872, 14814.111153330727, 14888.330025148894, 11903.464344104535, 11975.94012029943, 12146.878531214663, 12232.81650775239, 12278.079144064232, 3758.962410375916, 3957.881263562098, 4006.6794656427705, 4019.256437559061, 4022.255882661717, 110375.0, 116169.41915290453, 117274.41362793131, 117455.41272293669, 117543.4122829389, 11168.944155279334, 11418.600348987746, 11431.14266430021, 11447.599334023269, 11628.360440175871, 24035.038598456216, 24100.39749006289, 24100.638490422658, 24112.87943560271, 24534.0, 8149.225823546753, 8162.30620397261, 8225.383096267804, 8229.05365882919, 8328.417010809306, 4444.422225110779, 4516.412866327357, 4552.931706024412, 4565.977170114128, 4596.103759766814, 3707.8146092695256, 3804.771713697187, 3806.08653923062, 3819.1406933439753, 3832.5784163741964, 649.8960166373452, 653.9803805885736, 655.7016557466342, 658.539022684123, 676.5839009009424, 603.9973643751351, 614.0083764720034, 630.6279295215753, 636.8282360456782, 663.6582160187507, 9139.543022848831, 9550.37922535033, 9551.188149007412, 9568.282378821477, 9763.707088787347, 573.5583600627493, 621.2606997672755, 646.524804268862, 655.5968079631087, 660.9768658096943, 4600.056988317387, 4707.31743897133, 4728.338032675429, 4737.95262047382, 4749.836290108877, 91642.16715665783, 100819.49590252002, 102344.48827755814, 139250.60749392578, 174868.0, 111552.0, 123025.15462268073, 140171.47856517936, 141205.0, 143084.0, 89951.10048899558, 101228.0, 102013.97986020193, 121010.3949480247, 128160.35919820527, 9701.854472182782, 9915.504224788876, 9916.256280779115, 10070.144037756872, 10104.94947525287, 49890.0, 51318.0, 56055.71972140113, 60002.79994400175, 63794.0, 76161.0, 85398.57300713705, 101504.49247753713, 102508.0, 102898.0, 14425.35085921157, 14459.855401446272, 14502.782458263346, 14591.416343346147, 14971.850281497045, 15104.120117972869, 15271.083734975937, 15401.152936588147, 15529.68940621204, 15616.843831561539, 14246.786298205743, 14305.0, 14323.0, 14502.854971450572, 14953.626159346104, 14305.356258968162, 14339.641508962359, 14509.545622386571, 14602.85397146015, 14884.330205140577, 13386.598402047954, 13793.379297931819, 13954.720905582035, 14117.435302588192, 14295.785563216352, 11165.944170279097, 11265.436728163395, 11436.256643318491, 11631.59289424866, 11990.940045299718, 9016.864747028669, 9846.233750667883, 10801.24391292602, 11497.942510287396, 11836.46735896904], "value": 8041.553315011041, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1602.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 173.0, 103.0, 166.0, 178.0, 176.0, 119.0, 119.0, 117.0, 117.0], "value": 138.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 134.0, 147.0, 185.0, 118.0, 121.0, 118.0, 119.0, 148.0, 103.0], "value": 134.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [260.0, 276.0, 199.0, 199.0, 195.0, 196.0, 195.0, 194.0, 198.0, 200.0], "value": 211.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 83.0, 112.0, 85.0, 63.0, 62.0, 62.0, 82.0, 60.0], "value": 76.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 90.0, 73.0, 70.0, 102.0, 119.0, 70.0, 71.0, 89.0], "value": 83.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 105.0, 122.0, 134.0, 138.0, 87.0, 91.0, 86.0, 106.0, 111.0], "value": 108.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 126.0, 151.0, 153.0, 95.0, 96.0, 98.0, 97.0, 93.0, 152.0], "value": 115.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 86.0, 86.0, 87.0, 89.0, 88.0, 90.0, 89.0, 88.0], "value": 98.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 57.0, 57.0, 57.0, 60.0, 59.0, 58.0, 57.0, 57.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 76.0, 70.0, 73.0, 81.0, 74.0, 75.0, 74.0, 76.0, 76.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 83.0, 82.0, 106.0, 110.0, 115.0, 101.0, 91.0, 87.0, 92.0], "value": 95.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 167.0, 163.0, 160.0, 163.0, 160.0, 155.0, 176.0, 176.0, 159.0], "value": 161.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 182.0, 172.0, 171.0, 176.0, 176.0, 179.0, 176.0, 173.0, 174.0], "value": 175.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 72.0, 72.0, 73.0, 71.0, 69.0, 72.0, 72.0, 72.0], "value": 71.1, "unit": "score"}]}]} 05:52:07 INFO - Return code: 0 05:52:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:52:07 ERROR - # TBPL SUCCESS # 05:52:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:52:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:52:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:52:07 INFO - 2016-10-19 05:52:07.525 screenresolution[1689:11223] starting screenresolution argv=screenresolution get 05:52:07 INFO - 2016-10-19 05:52:07.529 screenresolution[1689:11223] Display 0: 1600x1200x32@60 05:52:07 INFO - 2016-10-19 05:52:07.535 screenresolution[1690:11226] starting screenresolution argv=screenresolution list 05:52:07 INFO - Available Modes on Display 0 05:52:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:52:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:52:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:52:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:52:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:52:07 INFO - Intel Iris: 05:52:07 INFO - Chipset Model: Intel Iris 05:52:07 INFO - Type: GPU 05:52:07 INFO - Bus: Built-In 05:52:07 INFO - VRAM (Dynamic, Max): 1536 MB 05:52:07 INFO - Vendor: Intel (0x8086) 05:52:07 INFO - Device ID: 0x0a2e 05:52:07 INFO - Revision ID: 0x0009 05:52:07 INFO - Displays: 05:52:07 INFO - Display: 05:52:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:52:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:52:07 INFO - Display Serial Number: 1600x1200 60 05:52:07 INFO - Main Display: Yes 05:52:07 INFO - Mirror: Off 05:52:07 INFO - Online: Yes 05:52:07 INFO - Rotation: Supported 05:52:07 INFO - Return code: 0 05:52:07 INFO - Running post-action listener: _resource_record_post_action 05:52:07 INFO - [mozharness: 2016-10-19 12:52:07.777529Z] Finished run-tests step (success) 05:52:07 INFO - Running post-run listener: _resource_record_post_run 05:52:07 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 32796672; Write bytes: 710216192; Read time: 201; Write time: 2010 05:52:07 INFO - TinderboxPrint: CPU usage
54.5% 05:52:07 INFO - TinderboxPrint: I/O read bytes / time
32,796,672 / 201 05:52:07 INFO - TinderboxPrint: I/O write bytes / time
710,216,192 / 2,010 05:52:07 INFO - TinderboxPrint: CPU idle
2,206.6 (45.5%) 05:52:07 INFO - TinderboxPrint: CPU system
977.8 (20.2%) 05:52:07 INFO - TinderboxPrint: CPU user
1,665.4 (34.3%) 05:52:07 INFO - TinderboxPrint: Swap in / out
436,002,816 / 0 05:52:07 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 399526400; Write bytes: 393537536; Read time: 29449; Write time: 833 05:52:07 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 30826496; Write bytes: 300409344; Read time: 184; Write time: 1134 05:52:08 INFO - Running post-run listener: _upload_blobber_files 05:52:08 INFO - Blob upload gear active. 05:52:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:52:08 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 05:52:08 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'] 05:52:08 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 05:52:08 INFO - (blobuploader) - INFO - Open directory for files ... 05:52:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:52:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:52:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:09 INFO - (blobuploader) - INFO - Done attempting. 05:52:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:52:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:52:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:11 INFO - (blobuploader) - INFO - Done attempting. 05:52:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:52:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:52:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:15 INFO - (blobuploader) - INFO - Done attempting. 05:52:15 INFO - (blobuploader) - INFO - Iteration through files over. 05:52:15 INFO - Return code: 0 05:52:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:52:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:52:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35ca39e17b0aea5fbd2f3138388a51ff79e470a1921685b92f995804593dc0d3914e06eb9c7d8f9c20dd61def5777638bafcecb392bdbd8edbadb6f97fe1a401", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88c6c416a4a1bdadcc93ffdc4cb67e6a5526d9a28810fe4c15748c8f82405866e0e37376f745e076c639793554ceaacb8b156ec7c06427739d9c1cd27f59ca60", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9ab357e1595c3b18ae5af962227843f6aadeeb97e34f532831e055fe4ed7d519056a403e3ddc2cf8a2a350c190448e346f0e876d1e581721b49f40a8d09a62"} 05:52:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:52:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:52:15 INFO - Contents: 05:52:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35ca39e17b0aea5fbd2f3138388a51ff79e470a1921685b92f995804593dc0d3914e06eb9c7d8f9c20dd61def5777638bafcecb392bdbd8edbadb6f97fe1a401", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88c6c416a4a1bdadcc93ffdc4cb67e6a5526d9a28810fe4c15748c8f82405866e0e37376f745e076c639793554ceaacb8b156ec7c06427739d9c1cd27f59ca60", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9ab357e1595c3b18ae5af962227843f6aadeeb97e34f532831e055fe4ed7d519056a403e3ddc2cf8a2a350c190448e346f0e876d1e581721b49f40a8d09a62"} 05:52:15 INFO - Running post-run listener: copy_logs_to_upload_dir 05:52:15 INFO - Copying logs to upload dir... 05:52:15 INFO - mkdir: /builds/slave/test/build/upload/logs 05:52:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.393337 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-10-19 05:52:15.338827) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 05:52:15.346648) ========= 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.2Va9BBWrVV/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.QDokAe62VO/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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/35ca39e17b0aea5fbd2f3138388a51ff79e470a1921685b92f995804593dc0d3914e06eb9c7d8f9c20dd61def5777638bafcecb392bdbd8edbadb6f97fe1a401", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88c6c416a4a1bdadcc93ffdc4cb67e6a5526d9a28810fe4c15748c8f82405866e0e37376f745e076c639793554ceaacb8b156ec7c06427739d9c1cd27f59ca60", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9ab357e1595c3b18ae5af962227843f6aadeeb97e34f532831e055fe4ed7d519056a403e3ddc2cf8a2a350c190448e346f0e876d1e581721b49f40a8d09a62"} build_url:https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011058 build_url: 'https://queue.taskcluster.net/v1/task/ETbF_WEkQE-z-MaNBfOCJA/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/35ca39e17b0aea5fbd2f3138388a51ff79e470a1921685b92f995804593dc0d3914e06eb9c7d8f9c20dd61def5777638bafcecb392bdbd8edbadb6f97fe1a401", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88c6c416a4a1bdadcc93ffdc4cb67e6a5526d9a28810fe4c15748c8f82405866e0e37376f745e076c639793554ceaacb8b156ec7c06427739d9c1cd27f59ca60", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9ab357e1595c3b18ae5af962227843f6aadeeb97e34f532831e055fe4ed7d519056a403e3ddc2cf8a2a350c190448e346f0e876d1e581721b49f40a8d09a62"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 05:52:15.381910) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 05:52:15.382281) ========= 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.2Va9BBWrVV/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.QDokAe62VO/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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.005194 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 05:52:15.430875) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 05:52:15.434270) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 05:52:15.434585) ========= ========= Total master_lag: 0.24 =========