builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0024 starttime: 1475152613.8 results: success (0) buildid: 20160929030426 builduid: a3238f9998bf4b2fb3fefb2ab531ef38 revision: f7d5008ee2ab9200052e45ad6ecc3f3a348f7f86 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 05:36:53.798385) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 05:36:53.798908) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 05:36:53.799292) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 05:36:53.828891) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 05:36:53.829235) ========= 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.Ls0ekER23f/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.mEd695bABe/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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-09-29 05:36:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 55.1M=0s 2016-09-29 05:36:53 (55.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.133255 ========= master_lag: 0.56 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 05:36:54.521446) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 05:36:54.521751) ========= 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.Ls0ekER23f/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.mEd695bABe/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.029714 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 05:36:54.571255) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-29 05:36:54.571578) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f7d5008ee2ab9200052e45ad6ecc3f3a348f7f86 --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 f7d5008ee2ab9200052e45ad6ecc3f3a348f7f86 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ls0ekER23f/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.mEd695bABe/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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-09-29 05:36:54,650 truncating revision to first 12 chars 2016-09-29 05:36:54,651 Setting DEBUG logging. 2016-09-29 05:36:54,651 attempt 1/10 2016-09-29 05:36:54,651 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f7d5008ee2ab?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-29 05:36:56,104 unpacking tar archive at: mozilla-central-f7d5008ee2ab/testing/mozharness/ program finished with exit code 0 elapsedTime=1.734130 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-29 05:36:56.327575) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 05:36:56.327936) ========= script_repo_revision: f7d5008ee2ab9200052e45ad6ecc3f3a348f7f86 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 05:36:56.328405) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 05:36:56.328706) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 05:36:56.347002) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-09-29 05:36:56.347584) ========= /tools/buildbot/bin/python 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', '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.Ls0ekER23f/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.mEd695bABe/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:36:56 INFO - MultiFileLogger online at 20160929 05:36:56 in /builds/slave/test 05:36:56 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:36:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:36:56 INFO - {'append_to_log': False, 05:36:56 INFO - 'base_work_dir': '/builds/slave/test', 05:36:56 INFO - 'blob_upload_branch': 'Firefox', 05:36:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:36:56 INFO - 'branch': 'Firefox', 05:36:56 INFO - 'buildbot_json_path': 'buildprops.json', 05:36:56 INFO - 'config_files': ('talos/mac_config.py',), 05:36:56 INFO - 'default_actions': ('clobber', 05:36:56 INFO - 'read-buildbot-config', 05:36:56 INFO - 'download-and-extract', 05:36:56 INFO - 'populate-webroot', 05:36:56 INFO - 'create-virtualenv', 05:36:56 INFO - 'install', 05:36:56 INFO - 'run-tests'), 05:36:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:36:56 INFO - 'download_minidump_stackwalk': True, 05:36:56 INFO - 'download_symbols': 'ondemand', 05:36:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:36:56 INFO - 'tooltool.py': '/tools/tooltool.py', 05:36:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:36:56 INFO - '/tools/misc-python/virtualenv.py')}, 05:36:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:36:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:36:56 INFO - 'installer_path': 'installer.exe', 05:36:56 INFO - 'log_level': 'info', 05:36:56 INFO - 'log_name': 'talos', 05:36:56 INFO - 'log_to_console': True, 05:36:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:36:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:36:56 INFO - 'opt_config_files': (), 05:36:56 INFO - 'pip_index': False, 05:36:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:36:56 INFO - 'cmd': ('bash', 05:36:56 INFO - '-c', 05:36:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:36:56 INFO - 'enabled': True, 05:36:56 INFO - 'halt_on_failure': False, 05:36:56 INFO - 'name': 'check_screen_resolution'},), 05:36:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:36:56 INFO - 'cmd': ('bash', 05:36:56 INFO - '-c', 05:36:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:36:56 INFO - 'enabled': True, 05:36:56 INFO - 'halt_on_failure': False, 05:36:56 INFO - 'name': 'check_screen_resolution'},), 05:36:56 INFO - 'run_cmd_checks_enabled': True, 05:36:56 INFO - 'sps_profile': False, 05:36:56 INFO - 'sps_profile_interval': 0, 05:36:56 INFO - 'suite': 'dromaeojs-e10s', 05:36:56 INFO - 'system_bits': '32', 05:36:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:36:56 INFO - 'title': 't-yosemite-r7-0024', 05:36:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:36:56 INFO - 'use_talos_json': True, 05:36:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:36:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:36:56 INFO - 'work_dir': 'build'} 05:36:56 INFO - [mozharness: 2016-09-29 12:36:56.554161Z] Running clobber step. 05:36:56 INFO - Running pre-action listener: _resource_record_pre_action 05:36:56 INFO - Running main action method: clobber 05:36:56 INFO - rmtree: /builds/slave/test/build 05:36:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:36:58 INFO - Running post-action listener: _resource_record_post_action 05:36:58 INFO - [mozharness: 2016-09-29 12:36:58.717590Z] Finished clobber step (success) 05:36:58 INFO - [mozharness: 2016-09-29 12:36:58.717768Z] Running read-buildbot-config step. 05:36:58 INFO - Running pre-action listener: _resource_record_pre_action 05:36:58 INFO - Running main action method: read_buildbot_config 05:36:58 INFO - Using buildbot properties: 05:36:58 INFO - { 05:36:58 INFO - "project": "", 05:36:58 INFO - "product": "firefox", 05:36:58 INFO - "script_repo_revision": "production", 05:36:58 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:36:58 INFO - "repository": "", 05:36:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:36:58 INFO - "buildid": "20160929030426", 05:36:58 INFO - "stage_platform": "macosx64", 05:36:58 INFO - "basedir": "/builds/slave/test", 05:36:58 INFO - "nightly_build": "True", 05:36:58 INFO - "buildnumber": 36, 05:36:58 INFO - "slavename": "t-yosemite-r7-0024", 05:36:58 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:36:58 INFO - "platform": "yosemite_r7", 05:36:58 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:36:58 INFO - "branch": "mozilla-central", 05:36:58 INFO - "repo_path": "mozilla-central", 05:36:58 INFO - "revision": "f7d5008ee2ab9200052e45ad6ecc3f3a348f7f86", 05:36:58 INFO - "pgo_build": "False", 05:36:58 INFO - "builduid": "a3238f9998bf4b2fb3fefb2ab531ef38", 05:36:58 INFO - "slavebuilddir": "test" 05:36:58 INFO - } 05:36:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:36:58 INFO - Running post-action listener: _resource_record_post_action 05:36:58 INFO - [mozharness: 2016-09-29 12:36:58.722081Z] Finished read-buildbot-config step (success) 05:36:58 INFO - [mozharness: 2016-09-29 12:36:58.722233Z] Running download-and-extract step. 05:36:58 INFO - Running pre-action listener: _resource_record_pre_action 05:36:58 INFO - Running main action method: download_and_extract 05:36:58 INFO - mkdir: /builds/slave/test/build/tests 05:36:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:58 INFO - trying https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:36:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/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:36:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/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:37:00 INFO - Downloaded 1199 bytes. 05:37:00 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:37:00 INFO - Using the following test package requirements: 05:37:00 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:37:00 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:00 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:37:00 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:00 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:37:00 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:00 INFO - u'jsshell-mac.zip'], 05:37:00 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:00 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:37:00 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:37:00 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:00 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:37:00 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:00 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:37:00 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:00 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:37:00 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:37:00 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:37:00 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:37:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:37:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:37:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:37:00 INFO - Http code: 200 05:37:00 INFO - Content-Encoding: None 05:37:00 INFO - Content-Type: application/zip 05:37:00 INFO - via: 1.1 02192a27c967e955f8c815efa939bfc8.cloudfront.net (CloudFront) 05:37:00 INFO - x-amz-cf-id: wSeygwiNFOULlGnxYCmYbGi-ReO5jrCrt5IJc7PiVS0s_pHv76whbw== 05:37:00 INFO - x-amz-version-id: ZX1DZptt7IIhULCl_RbxaAA0qHmAJeZg 05:37:00 INFO - x-cache: Hit from cloudfront 05:37:00 INFO - Expected file size: 16473343 05:37:00 INFO - Obtained file size: 16473343 05:37:07 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:37:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:37:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:37:07 INFO - Fetch https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:37:08 INFO - Http code: 200 05:37:08 INFO - Content-Encoding: None 05:37:08 INFO - Content-Type: application/zip 05:37:08 INFO - via: 1.1 68e4011ca1c00bec92bb202e1ddce131.cloudfront.net (CloudFront) 05:37:08 INFO - x-amz-cf-id: R9_D34j4Xv7gCMxHg_Xq16SihPWErZHK1n945k86hubksBwqVWfmvQ== 05:37:08 INFO - x-amz-version-id: ZX1DZptt7IIhULCl_RbxaAA0qHmAJeZg 05:37:08 INFO - x-cache: Hit from cloudfront 05:37:08 INFO - Expected file size: 16473343 05:37:08 INFO - Obtained file size: 16473343 05:37:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:37:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:37:15 INFO - Fetch https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:37:15 INFO - Http code: 200 05:37:15 INFO - Content-Encoding: None 05:37:15 INFO - Content-Type: application/zip 05:37:15 INFO - via: 1.1 dc7c4fb5024ff022cad1642ec506a6e8.cloudfront.net (CloudFront) 05:37:15 INFO - x-amz-cf-id: 9MVUPpQhITQHyZQJc5XS3KSITAyRH8MZth6Z81bvjkvLCn7H1iUfnA== 05:37:15 INFO - x-amz-version-id: Y1clA9Hc17rvQyukKJzm1j11mLLWOMKY 05:37:15 INFO - x-cache: Miss from cloudfront 05:37:16 INFO - Expected file size: 14414067 05:37:16 INFO - Obtained file size: 14414067 05:37:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:16 INFO - trying https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:37:16 INFO - Downloading https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:37:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/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:37:19 INFO - Downloaded 111927285 bytes. 05:37:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:37:19 INFO - mkdir: /builds/slave/test/properties 05:37:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:37:19 INFO - Writing to file /builds/slave/test/properties/build_url 05:37:19 INFO - Contents: 05:37:19 INFO - build_url:https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:37:20 INFO - Running post-action listener: _resource_record_post_action 05:37:20 INFO - Running post-action listener: set_extra_try_arguments 05:37:20 INFO - [mozharness: 2016-09-29 12:37:20.370135Z] Finished download-and-extract step (success) 05:37:20 INFO - [mozharness: 2016-09-29 12:37:20.370262Z] Running populate-webroot step. 05:37:20 INFO - Running pre-action listener: _resource_record_pre_action 05:37:20 INFO - Running main action method: populate_webroot 05:37:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:37:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:37:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:37:20 INFO - '--tppagecycles', 05:37:20 INFO - '7'], 05:37:20 INFO - 'tests': ['tsvgm']}}, 05:37:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:37:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:37:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:37:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:37:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:37:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:37:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:20 INFO - 'talos_options': ['--disable-e10s'], 05:37:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:37:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:37:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:20 INFO - 'talos_options': ['--disable-e10s'], 05:37:20 INFO - 'tests': ['damp', 'tps']}, 05:37:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:20 INFO - 'tests': ['damp', 'tps']}, 05:37:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:37:20 INFO - 'tests': ['dromaeo_dom']}, 05:37:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:37:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:37:20 INFO - 'tests': ['basic_compositor_video']}, 05:37:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:37:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:37:20 INFO - 'tests': ['a11yr', 05:37:20 INFO - 'ts_paint', 05:37:20 INFO - 'tpaint', 05:37:20 INFO - 'sessionrestore', 05:37:20 INFO - 'sessionrestore_no_auto_restore', 05:37:20 INFO - 'tabpaint']}, 05:37:20 INFO - 'other-e10s': {'tests': ['a11yr', 05:37:20 INFO - 'ts_paint', 05:37:20 INFO - 'tpaint', 05:37:20 INFO - 'sessionrestore', 05:37:20 INFO - 'sessionrestore_no_auto_restore', 05:37:20 INFO - 'tabpaint']}, 05:37:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:37:20 INFO - 'ts_paint', 05:37:20 INFO - 'tpaint', 05:37:20 INFO - 'sessionrestore', 05:37:20 INFO - 'sessionrestore_no_auto_restore', 05:37:20 INFO - 'tabpaint']}, 05:37:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:37:20 INFO - 'ts_paint', 05:37:20 INFO - 'tpaint', 05:37:20 INFO - 'sessionrestore', 05:37:20 INFO - 'sessionrestore_no_auto_restore', 05:37:20 INFO - 'tabpaint']}, 05:37:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:37:20 INFO - 'tests': ['a11yr', 05:37:20 INFO - 'ts_paint', 05:37:20 INFO - 'tpaint', 05:37:20 INFO - 'sessionrestore', 05:37:20 INFO - 'sessionrestore_no_auto_restore', 05:37:20 INFO - 'tabpaint']}, 05:37:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:37:20 INFO - 'tests': ['a11yr', 05:37:20 INFO - 'ts_paint', 05:37:20 INFO - 'tpaint', 05:37:20 INFO - 'sessionrestore', 05:37:20 INFO - 'sessionrestore_no_auto_restore', 05:37:20 INFO - 'tabpaint']}, 05:37:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:37:20 INFO - 'tests': ['tsvgx', 05:37:20 INFO - 'tsvgr_opacity', 05:37:20 INFO - 'tart', 05:37:20 INFO - 'tscrollx', 05:37:20 INFO - 'cart']}, 05:37:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:37:20 INFO - 'tsvgr_opacity', 05:37:20 INFO - 'tart', 05:37:20 INFO - 'tscrollx', 05:37:20 INFO - 'cart']}, 05:37:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:20 INFO - 'talos_options': ['--disable-e10s'], 05:37:20 INFO - 'tests': ['tp5o']}, 05:37:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:20 INFO - 'tests': ['tp5o']}, 05:37:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:20 INFO - 'talos_options': ['--disable-e10s', 05:37:20 INFO - '--xperf_path', 05:37:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:37:20 INFO - 'tests': ['tp5n']}, 05:37:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:20 INFO - 'talos_options': ['--xperf_path', 05:37:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:37:20 INFO - 'tests': ['tp5n']}}, 05:37:20 INFO - 'talos.zip': {'path': '', 05:37:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:37:20 INFO - Running post-action listener: _resource_record_post_action 05:37:20 INFO - [mozharness: 2016-09-29 12:37:20.388964Z] Finished populate-webroot step (success) 05:37:20 INFO - [mozharness: 2016-09-29 12:37:20.389089Z] Running create-virtualenv step. 05:37:20 INFO - Running pre-action listener: _resource_record_pre_action 05:37:20 INFO - Running main action method: create_virtualenv 05:37:20 INFO - Creating virtualenv /builds/slave/test/build/venv 05:37:20 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:37:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:37:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:37:20 INFO - Using real prefix '/tools/python27' 05:37:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:37:21 INFO - Installing distribute.............................................................................................................................................................................................done. 05:37:24 INFO - Installing pip.................done. 05:37:24 INFO - Return code: 0 05:37:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:37:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:37:24 INFO - Reading from file tmpfile_stdout 05:37:24 INFO - Output received: 05:37:24 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:37:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:37:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100d38490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbeb25a7f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4cd30>, '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 0x100972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b44b20>, '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.Ls0ekER23f/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.mEd695bABe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:37:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:37:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:37:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ls0ekER23f/Render', 05:37:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:24 INFO - 'HOME': '/Users/cltbld', 05:37:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:24 INFO - 'LOGNAME': 'cltbld', 05:37:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:24 INFO - 'MOZ_NO_REMOTE': '1', 05:37:24 INFO - 'NO_EM_RESTART': '1', 05:37:24 INFO - 'PAGER': '/bin/cat', 05:37:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:24 INFO - 'PWD': '/builds/slave/test', 05:37:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:24 INFO - 'SHELL': '/bin/bash', 05:37:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mEd695bABe/Listeners', 05:37:24 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 05:37:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:24 INFO - 'USER': 'cltbld', 05:37:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:24 INFO - 'XPC_FLAGS': '0x0', 05:37:24 INFO - 'XPC_SERVICE_NAME': '0', 05:37:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:24 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:37:24 INFO - Cleaning up... 05:37:24 INFO - Return code: 0 05:37:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:37:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100d38490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbeb25a7f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4cd30>, '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 0x100972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b44b20>, '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.Ls0ekER23f/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.mEd695bABe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:37:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:37:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:37:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ls0ekER23f/Render', 05:37:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:24 INFO - 'HOME': '/Users/cltbld', 05:37:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:24 INFO - 'LOGNAME': 'cltbld', 05:37:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:24 INFO - 'MOZ_NO_REMOTE': '1', 05:37:24 INFO - 'NO_EM_RESTART': '1', 05:37:24 INFO - 'PAGER': '/bin/cat', 05:37:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:24 INFO - 'PWD': '/builds/slave/test', 05:37:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:24 INFO - 'SHELL': '/bin/bash', 05:37:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mEd695bABe/Listeners', 05:37:24 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 05:37:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:24 INFO - 'USER': 'cltbld', 05:37:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:24 INFO - 'XPC_FLAGS': '0x0', 05:37:24 INFO - 'XPC_SERVICE_NAME': '0', 05:37:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:24 INFO - Downloading/unpacking psutil>=3.1.1 05:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:37:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:37:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:37:28 INFO - Installing collected packages: psutil 05:37:28 INFO - Running setup.py install for psutil 05:37:28 INFO - building 'psutil._psutil_osx' extension 05:37:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:37:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:37:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:37:29 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:37:29 INFO - building 'psutil._psutil_posix' extension 05:37:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:37:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:37:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:37:29 INFO - ^ 05:37:29 INFO - 1 warning generated. 05:37:29 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:37:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:37:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:37:29 INFO - Successfully installed psutil 05:37:29 INFO - Cleaning up... 05:37:29 INFO - Return code: 0 05:37:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:37: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:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37: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:37:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100d38490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbeb25a7f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4cd30>, '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 0x100972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b44b20>, '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.Ls0ekER23f/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.mEd695bABe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:37:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:37:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:37:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ls0ekER23f/Render', 05:37:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:29 INFO - 'HOME': '/Users/cltbld', 05:37:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:29 INFO - 'LOGNAME': 'cltbld', 05:37:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:29 INFO - 'MOZ_NO_REMOTE': '1', 05:37:29 INFO - 'NO_EM_RESTART': '1', 05:37:29 INFO - 'PAGER': '/bin/cat', 05:37:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:29 INFO - 'PWD': '/builds/slave/test', 05:37:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:29 INFO - 'SHELL': '/bin/bash', 05:37:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mEd695bABe/Listeners', 05:37:29 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 05:37:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:29 INFO - 'USER': 'cltbld', 05:37:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:29 INFO - 'XPC_FLAGS': '0x0', 05:37:29 INFO - 'XPC_SERVICE_NAME': '0', 05:37:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:37:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:37:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:37:33 INFO - Installing collected packages: mozsystemmonitor 05:37:33 INFO - Running setup.py install for mozsystemmonitor 05:37:33 INFO - Successfully installed mozsystemmonitor 05:37:33 INFO - Cleaning up... 05:37:33 INFO - Return code: 0 05:37:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100d38490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbeb25a7f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4cd30>, '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 0x100972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b44b20>, '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.Ls0ekER23f/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.mEd695bABe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:37:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:37:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:37:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ls0ekER23f/Render', 05:37:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:33 INFO - 'HOME': '/Users/cltbld', 05:37:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:33 INFO - 'LOGNAME': 'cltbld', 05:37:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:33 INFO - 'MOZ_NO_REMOTE': '1', 05:37:33 INFO - 'NO_EM_RESTART': '1', 05:37:33 INFO - 'PAGER': '/bin/cat', 05:37:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:33 INFO - 'PWD': '/builds/slave/test', 05:37:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:33 INFO - 'SHELL': '/bin/bash', 05:37:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mEd695bABe/Listeners', 05:37:33 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 05:37:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:33 INFO - 'USER': 'cltbld', 05:37:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:33 INFO - 'XPC_FLAGS': '0x0', 05:37:33 INFO - 'XPC_SERVICE_NAME': '0', 05:37:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:33 INFO - Downloading/unpacking jsonschema==2.5.1 05:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:37:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:36 INFO - Downloading functools32-3.2.3-2.tar.gz 05:37:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:37:36 INFO - warning: no files found matching '*.txt' 05:37:36 INFO - no previously-included directories found matching 'build' 05:37:36 INFO - no previously-included directories found matching 'dist' 05:37:36 INFO - no previously-included directories found matching '.git*' 05:37:36 INFO - Installing collected packages: jsonschema, functools32 05:37:36 INFO - Running setup.py install for functools32 05:37:36 INFO - warning: no files found matching '*.txt' 05:37:36 INFO - no previously-included directories found matching 'build' 05:37:36 INFO - no previously-included directories found matching 'dist' 05:37:36 INFO - no previously-included directories found matching '.git*' 05:37:36 INFO - Successfully installed jsonschema functools32 05:37:36 INFO - Cleaning up... 05:37:37 INFO - Return code: 0 05:37:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:37:37 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:37:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:37 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:37:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:37 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 0x100d38490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbeb25a7f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4cd30>, '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 0x100972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b44b20>, '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.Ls0ekER23f/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.mEd695bABe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:37:37 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:37:37 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:37:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ls0ekER23f/Render', 05:37:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:37 INFO - 'HOME': '/Users/cltbld', 05:37:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:37 INFO - 'LOGNAME': 'cltbld', 05:37:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:37 INFO - 'MOZ_NO_REMOTE': '1', 05:37:37 INFO - 'NO_EM_RESTART': '1', 05:37:37 INFO - 'PAGER': '/bin/cat', 05:37:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:37 INFO - 'PWD': '/builds/slave/test', 05:37:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:37 INFO - 'SHELL': '/bin/bash', 05:37:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mEd695bABe/Listeners', 05:37:37 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 05:37:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:37 INFO - 'USER': 'cltbld', 05:37:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:37 INFO - 'XPC_FLAGS': '0x0', 05:37:37 INFO - 'XPC_SERVICE_NAME': '0', 05:37:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:37:37 INFO - Cleaning up... 05:37:37 INFO - Return code: 0 05:37:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:37:37 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:37:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:37 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:37:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:37 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 0x100d38490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbeb25a7f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4cd30>, '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 0x100972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b44b20>, '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.Ls0ekER23f/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.mEd695bABe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:37:37 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:37:37 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:37:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ls0ekER23f/Render', 05:37:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:37 INFO - 'HOME': '/Users/cltbld', 05:37:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:37 INFO - 'LOGNAME': 'cltbld', 05:37:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:37 INFO - 'MOZ_NO_REMOTE': '1', 05:37:37 INFO - 'NO_EM_RESTART': '1', 05:37:37 INFO - 'PAGER': '/bin/cat', 05:37:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:37 INFO - 'PWD': '/builds/slave/test', 05:37:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:37 INFO - 'SHELL': '/bin/bash', 05:37:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mEd695bABe/Listeners', 05:37:37 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 05:37:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:37 INFO - 'USER': 'cltbld', 05:37:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:37 INFO - 'XPC_FLAGS': '0x0', 05:37:37 INFO - 'XPC_SERVICE_NAME': '0', 05:37:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:37 INFO - Downloading/unpacking blobuploader==1.2.4 05:37:37 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:37:37 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:37:37 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:37:37 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:37:37 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:37:37 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:37:40 INFO - Downloading blobuploader-1.2.4.tar.gz 05:37:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:37:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:37:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:41 INFO - Downloading docopt-0.6.1.tar.gz 05:37:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:37:41 INFO - Installing collected packages: blobuploader, requests, docopt 05:37:41 INFO - Running setup.py install for blobuploader 05:37:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:37:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:37:41 INFO - Running setup.py install for requests 05:37:41 INFO - Running setup.py install for docopt 05:37:42 INFO - Successfully installed blobuploader requests docopt 05:37:42 INFO - Cleaning up... 05:37:42 INFO - Return code: 0 05:37:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:37:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:42 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 0x100d38490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbeb25a7f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4cd30>, '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 0x100972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b44b20>, '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.Ls0ekER23f/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.mEd695bABe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:37:42 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:37:42 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:37:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ls0ekER23f/Render', 05:37:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:42 INFO - 'HOME': '/Users/cltbld', 05:37:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:42 INFO - 'LOGNAME': 'cltbld', 05:37:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:42 INFO - 'MOZ_NO_REMOTE': '1', 05:37:42 INFO - 'NO_EM_RESTART': '1', 05:37:42 INFO - 'PAGER': '/bin/cat', 05:37:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:42 INFO - 'PWD': '/builds/slave/test', 05:37:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:42 INFO - 'SHELL': '/bin/bash', 05:37:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mEd695bABe/Listeners', 05:37:42 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 05:37:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:42 INFO - 'USER': 'cltbld', 05:37:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:42 INFO - 'XPC_FLAGS': '0x0', 05:37:42 INFO - 'XPC_SERVICE_NAME': '0', 05:37:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:37:42 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-FUQwp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:37:42 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-gWBtPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:37:42 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-7EkEFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:37:42 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-4IXkdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:37:42 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-L4j0JI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:37:42 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-bwHfIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:37:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-6mybTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:37:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-g40VGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:37:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-3hehFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:37:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-ziqUjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:37:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-wUItSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:37:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-QMGqe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:37:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-F9yeJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:37:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-614Klo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:37:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-ywiOLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:37:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-DBHEBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:37:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-k9ut0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:37:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:37:44 INFO - Running setup.py install for manifestparser 05:37:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:37:44 INFO - Running setup.py install for mozcrash 05:37:44 INFO - Running setup.py install for mozdebug 05:37:44 INFO - Running setup.py install for mozdevice 05:37:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:37:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:37:44 INFO - Running setup.py install for mozfile 05:37:44 INFO - Running setup.py install for mozhttpd 05:37:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:37:45 INFO - Running setup.py install for mozinfo 05:37:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:37:45 INFO - Running setup.py install for mozInstall 05:37:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:37:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:37:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:37:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:37:45 INFO - Running setup.py install for mozleak 05:37:45 INFO - Running setup.py install for mozlog 05:37:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:37:45 INFO - Running setup.py install for moznetwork 05:37:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:37:45 INFO - Running setup.py install for mozprocess 05:37:45 INFO - Running setup.py install for mozprofile 05:37:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:37:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:37:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:37:46 INFO - Running setup.py install for mozrunner 05:37:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:37:46 INFO - Running setup.py install for mozscreenshot 05:37:46 INFO - Running setup.py install for moztest 05:37:46 INFO - Running setup.py install for mozversion 05:37:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:37:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:37:46 INFO - Cleaning up... 05:37:46 INFO - Return code: 0 05:37:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:37:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:46 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 0x100d38490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbeb25a7f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4cd30>, '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 0x100972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b44b20>, '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.Ls0ekER23f/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.mEd695bABe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:37:46 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:37:46 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:37:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ls0ekER23f/Render', 05:37:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:46 INFO - 'HOME': '/Users/cltbld', 05:37:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:46 INFO - 'LOGNAME': 'cltbld', 05:37:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:46 INFO - 'MOZ_NO_REMOTE': '1', 05:37:46 INFO - 'NO_EM_RESTART': '1', 05:37:46 INFO - 'PAGER': '/bin/cat', 05:37:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:46 INFO - 'PWD': '/builds/slave/test', 05:37:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:46 INFO - 'SHELL': '/bin/bash', 05:37:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mEd695bABe/Listeners', 05:37:46 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 05:37:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:46 INFO - 'USER': 'cltbld', 05:37:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:46 INFO - 'XPC_FLAGS': '0x0', 05:37:46 INFO - 'XPC_SERVICE_NAME': '0', 05:37:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:37:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-TgUkr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:37:47 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:37:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:37:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-bvC4Xn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:37:47 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:37:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:37:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-T_d_xE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:37:47 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:37:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:37:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Z5o68q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:37:47 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:37:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:37:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-TakSpq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:37:47 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:37:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:37:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-wE5rta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:37:47 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:37:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:37:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-g7jaTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:37:47 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:37:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:37:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-VSTC6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:37:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:37:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:37:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Jo6CKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:37:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:37:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:37:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-FSpVHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:37:48 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:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:37:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-l4KnV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:37:48 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:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:37:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-L8yKgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:37:48 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:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:37:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-0EOsGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:37:48 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:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:37:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-hby9Lu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:37:48 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:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:37:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-hWzQof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:37:48 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:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:37:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Sf6G7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:37:48 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:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:37:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-fOyzQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:37:48 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:37:48 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:37:48 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:37:48 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:37:48 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:37:48 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:37:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:37:48 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:37:48 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:37:48 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:37:48 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:37:48 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:37:48 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:37:51 INFO - Downloading blessings-1.6.tar.gz 05:37:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:37:51 INFO - Installing collected packages: blessings 05:37:51 INFO - Running setup.py install for blessings 05:37:52 INFO - Successfully installed blessings 05:37:52 INFO - Cleaning up... 05:37:52 INFO - Return code: 0 05:37:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:37:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:37:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:37:52 INFO - Reading from file tmpfile_stdout 05:37:52 INFO - Current package versions: 05:37:52 INFO - blessings == 1.6 05:37:52 INFO - blobuploader == 1.2.4 05:37:52 INFO - docopt == 0.6.1 05:37:52 INFO - functools32 == 3.2.3-2 05:37:52 INFO - jsonschema == 2.5.1 05:37:52 INFO - manifestparser == 1.1 05:37:52 INFO - mozInstall == 1.12 05:37:52 INFO - mozcrash == 0.17 05:37:52 INFO - mozdebug == 0.1 05:37:52 INFO - mozdevice == 0.48 05:37:52 INFO - mozfile == 1.2 05:37:52 INFO - mozhttpd == 0.7 05:37:52 INFO - mozinfo == 0.9 05:37:52 INFO - mozleak == 0.1 05:37:52 INFO - mozlog == 3.4 05:37:52 INFO - moznetwork == 0.27 05:37:52 INFO - mozprocess == 0.23 05:37:52 INFO - mozprofile == 0.28 05:37:52 INFO - mozrunner == 6.12 05:37:52 INFO - mozscreenshot == 0.1 05:37:52 INFO - mozsystemmonitor == 0.3 05:37:52 INFO - moztest == 0.7 05:37:52 INFO - mozversion == 1.4 05:37:52 INFO - psutil == 3.1.1 05:37:52 INFO - requests == 1.2.3 05:37:52 INFO - wsgiref == 0.1.2 05:37:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:52 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 0x100d38490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbeb25a7f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4cd30>, '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 0x100972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b44b20>, '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.Ls0ekER23f/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.mEd695bABe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:37:52 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:37:52 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:37:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ls0ekER23f/Render', 05:37:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:52 INFO - 'HOME': '/Users/cltbld', 05:37:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:52 INFO - 'LOGNAME': 'cltbld', 05:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:52 INFO - 'MOZ_NO_REMOTE': '1', 05:37:52 INFO - 'NO_EM_RESTART': '1', 05:37:52 INFO - 'PAGER': '/bin/cat', 05:37:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:52 INFO - 'PWD': '/builds/slave/test', 05:37:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:52 INFO - 'SHELL': '/bin/bash', 05:37:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mEd695bABe/Listeners', 05:37:52 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 05:37:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:52 INFO - 'USER': 'cltbld', 05:37:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:52 INFO - 'XPC_FLAGS': '0x0', 05:37:52 INFO - 'XPC_SERVICE_NAME': '0', 05:37:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:53 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:37:53 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:37:53 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:37:53 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:37:53 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:37:53 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:37:53 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:37:53 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:37:53 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:37:53 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:37:53 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:37:53 INFO - Cleaning up... 05:37:53 INFO - Return code: 0 05:37:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:37: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:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37: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:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100d38490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbeb25a7f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4cd30>, '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 0x100972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b44b20>, '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.Ls0ekER23f/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.mEd695bABe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:37:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:37:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:37:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ls0ekER23f/Render', 05:37:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:53 INFO - 'HOME': '/Users/cltbld', 05:37:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:53 INFO - 'LOGNAME': 'cltbld', 05:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:53 INFO - 'MOZ_NO_REMOTE': '1', 05:37:53 INFO - 'NO_EM_RESTART': '1', 05:37:53 INFO - 'PAGER': '/bin/cat', 05:37:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:53 INFO - 'PWD': '/builds/slave/test', 05:37:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:53 INFO - 'SHELL': '/bin/bash', 05:37:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mEd695bABe/Listeners', 05:37:53 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 05:37:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:53 INFO - 'USER': 'cltbld', 05:37:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:53 INFO - 'XPC_FLAGS': '0x0', 05:37:53 INFO - 'XPC_SERVICE_NAME': '0', 05:37:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:37:53 INFO - Cleaning up... 05:37:53 INFO - Return code: 0 05:37:53 INFO - Running post-action listener: _resource_record_post_action 05:37:53 INFO - Running post-action listener: _start_resource_monitoring 05:37:53 INFO - Starting resource monitoring. 05:37:53 INFO - [mozharness: 2016-09-29 12:37:53.409014Z] Finished create-virtualenv step (success) 05:37:53 INFO - [mozharness: 2016-09-29 12:37:53.409994Z] Running install step. 05:37:53 INFO - Running pre-action listener: _resource_record_pre_action 05:37:53 INFO - Running main action method: install 05:37:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:37:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:37:53 INFO - Reading from file tmpfile_stdout 05:37:53 INFO - Detecting whether we're running mozinstall >=1.0... 05:37:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:37:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:37:53 INFO - Reading from file tmpfile_stdout 05:37:53 INFO - Output received: 05:37:53 INFO - Usage: mozinstall [options] installer 05:37:53 INFO - Options: 05:37:53 INFO - -h, --help show this help message and exit 05:37:53 INFO - -d DEST, --destination=DEST 05:37:53 INFO - Directory to install application into. [default: 05:37:53 INFO - "/builds/slave/test"] 05:37:53 INFO - --app=APP Application being installed. [default: firefox] 05:37:53 INFO - mkdir: /builds/slave/test/build/application 05:37:53 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:37:53 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:38:27 INFO - Reading from file tmpfile_stdout 05:38:27 INFO - Output received: 05:38:27 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:38:27 INFO - Running post-action listener: _resource_record_post_action 05:38:27 INFO - [mozharness: 2016-09-29 12:38:27.559015Z] Finished install step (success) 05:38:27 INFO - [mozharness: 2016-09-29 12:38:27.559152Z] Running run-tests step. 05:38:27 INFO - Running pre-action listener: _resource_record_pre_action 05:38:27 INFO - Running main action method: run_tests 05:38:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:38:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:38:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:38:27 INFO - 2016-09-29 05:38:27.573 screenresolution[1633:7440] starting screenresolution argv=screenresolution get 05:38:27 INFO - 2016-09-29 05:38:27.576 screenresolution[1633:7440] Display 0: 1600x1200x32@60 05:38:27 INFO - 2016-09-29 05:38:27.581 screenresolution[1634:7442] starting screenresolution argv=screenresolution list 05:38:27 INFO - Available Modes on Display 0 05:38:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:38:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:38:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:38:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:38:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:38:27 INFO - Intel Iris: 05:38:27 INFO - Chipset Model: Intel Iris 05:38:27 INFO - Type: GPU 05:38:27 INFO - Bus: Built-In 05:38:27 INFO - VRAM (Dynamic, Max): 1536 MB 05:38:27 INFO - Vendor: Intel (0x8086) 05:38:27 INFO - Device ID: 0x0a2e 05:38:27 INFO - Revision ID: 0x0009 05:38:27 INFO - Displays: 05:38:27 INFO - Display: 05:38:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:38:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:38:27 INFO - Display Serial Number: 1600x1200 60 05:38:27 INFO - Main Display: Yes 05:38:27 INFO - Mirror: Off 05:38:27 INFO - Online: Yes 05:38:27 INFO - Rotation: Supported 05:38:27 INFO - Return code: 0 05:38:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:38:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:38:27 INFO - Python 2.7.3 05:38:27 INFO - Return code: 0 05:38:27 INFO - grabbing minidump binary from tooltool 05:38:27 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:38:27 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 0x100b4cd30>, '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 0x100972db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b44b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:38:27 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:38:27 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:38:27 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:38:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:38:27 INFO - Return code: 0 05:38:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:38:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:38:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:38:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:38:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:38:27 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/Bsb08oFwRAuFXdCZwpjl-A/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--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:38:27 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/Bsb08oFwRAuFXdCZwpjl-A/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0024 --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:38:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ls0ekER23f/Render', 05:38:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:27 INFO - 'HOME': '/Users/cltbld', 05:38:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:27 INFO - 'LOGNAME': 'cltbld', 05:38:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:38:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:38:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:27 INFO - 'MOZ_NO_REMOTE': '1', 05:38:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:38:27 INFO - 'NO_EM_RESTART': '1', 05:38:27 INFO - 'PAGER': '/bin/cat', 05:38:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:27 INFO - 'PWD': '/builds/slave/test', 05:38:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:38:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:27 INFO - 'SHELL': '/bin/bash', 05:38:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mEd695bABe/Listeners', 05:38:27 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 05:38:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:27 INFO - 'USER': 'cltbld', 05:38:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:27 INFO - 'XPC_FLAGS': '0x0', 05:38:27 INFO - 'XPC_SERVICE_NAME': '0', 05:38:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:27 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/Bsb08oFwRAuFXdCZwpjl-A/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--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:38:28 INFO - mozversion application_buildid: 20160929030426 05:38:28 INFO - mozversion application_changeset: f7d5008ee2ab9200052e45ad6ecc3f3a348f7f86 05:38:28 INFO - mozversion application_display_name: Nightly 05:38:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:38:28 INFO - mozversion application_name: Firefox 05:38:28 INFO - mozversion application_remotingname: firefox 05:38:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:38:28 INFO - mozversion application_vendor: Mozilla 05:38:28 INFO - mozversion application_version: 52.0a1 05:38:28 INFO - mozversion platform_buildid: 20160929030426 05:38:28 INFO - mozversion platform_changeset: f7d5008ee2ab9200052e45ad6ecc3f3a348f7f86 05:38:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:38:28 INFO - mozversion platform_version: 52.0a1 05:38:28 INFO - using testdate: 1475152708 05:38:28 INFO - actual date: 1475152708 05:38:28 INFO - starting webserver on 'localhost:49271' 05:38:28 INFO - SUITE-START | Running 2 tests 05:38:28 INFO - TEST-START | dromaeo_css 05:38:28 INFO - operating with platform_type : mac_ 05:38:28 INFO - Initialising browser for dromaeo_css test... 05:38:28 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpkpwgpn/profile http://localhost:49271/getInfo.html) 05:38:31 INFO - PROCESS | 1644 | 2016-09-29 05:38:31.250 plugin-container[1647:7612] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 05:38:31 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:38:31 INFO - PROCESS | 1644 | 2016-09-29 05:38:31.251 plugin-container[1647:7612] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d1b, name = 'com.apple.CFPasteboardClient' 05:38:31 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:38:31 INFO - PROCESS | 1644 | 2016-09-29 05:38:31.251 plugin-container[1647:7612] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:38:31 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 05:38:31 INFO - PROCESS | 1644 | colorDepth:24 05:38:31 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 05:38:31 INFO - PROCESS | 1644 | __metrics 05:38:31 ERROR - PROCESS | 1644 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3076: TypeError: this.tabs is undefined 05:38:32 INFO - TEST-INFO | 1644: exit 0 05:38:32 INFO - Browser initialized. 05:38:32 INFO - Running cycle 1/1 for dromaeo_css test... 05:38:32 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpkpwgpn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:38:34 INFO - PROCESS | 1648 | 2016-09-29 05:38:34.692 plugin-container[1650:7734] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x861f, name = 'com.apple.tsm.portname' 05:38:34 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:38:34 INFO - PROCESS | 1648 | 2016-09-29 05:38:34.693 plugin-container[1650:7734] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c07, name = 'com.apple.CFPasteboardClient' 05:38:34 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:38:34 INFO - PROCESS | 1648 | 2016-09-29 05:38:34.693 plugin-container[1650:7734] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:38:44 INFO - PROCESS | 1648 | RSS: Main: 240173056 05:38:44 INFO - PROCESS | 1648 | 05:39:33 INFO - PROCESS | 1648 | 1475152773946 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:41:45 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 05:41:47 INFO - PROCESS | 1648 | RSS: Main: 255721472 05:41:47 INFO - PROCESS | 1648 | 05:44:49 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 05:44:50 INFO - PROCESS | 1648 | RSS: Main: 266240000 05:44:50 INFO - PROCESS | 1648 | 05:47:52 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 05:47:52 INFO - PROCESS | 1648 | RSS: Main: 270401536 05:47:52 INFO - PROCESS | 1648 | 05:50:54 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 05:50:55 INFO - PROCESS | 1648 | RSS: Main: 270000128 05:50:55 INFO - PROCESS | 1648 | 05:53:58 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 05:53:58 INFO - PROCESS | 1648 | RSS: Main: 275382272 05:53:58 INFO - PROCESS | 1648 | 05:57:01 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 05:57:01 INFO - PROCESS | 1648 | RSS: Main: 278151168 05:57:01 INFO - PROCESS | 1648 | 05:57:01 INFO - PROCESS | 1648 | __start_tp_report 05:57:01 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 05:57:01 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 05:57:01 INFO - PROCESS | 1648 | |i|pagename|runs| 05:57:01 INFO - PROCESS | 1648 | |0;dojo.html;2924.429736201441;3355.211525291558;3378.645242249563;3395.270016946356;3419.6321471411416;5634.7182640867995;5637.718114094299;5648.830535083944;5684.118961560955;5728.570357223204;5628.577856660746;5666.320041595004;5737.110747834093;5774.82675519735;5828.213191219194;5160.56135228506;5164.173732202848;5283.920741188886;5316.20256961455;5324.813631522898;5210.895782084356;5353.678779273237;5354.732263386834;5364.704941228231;5422.83731488054;5148.536631703128;5190.24741412493;5218.982298451803;5284.4187139414635;5287.259783630295;5226.817061402832;5239.030779305841;5254.133068043769;5269.998700246979;5310.62825602208;5184.118699821001;5196.792128314863;5207.3490813648295;5272.367315922113;5275.630705850592;5525.972370138164;5606.523445507137;5631.943680563224;5682.8634273145535;5764.33710123337;4951.7771700273215;6073.605215660968;6181.134641150243;6217.844553886144;6231.626102433823;6242.188515493002;6258.968705156491;6269.96865015672;6300.086487459293;6305.968470157666;5751.683657398883;5754.568407369464;5788.957987562279;5826.766929322822;5827.388124246935;6223.973044447615;6286.811395658137;6286.842828929313;6287.811365659038;6289.7484100635465;6161.7227224775;6164.260288765377;6164.7842325518395;6169.8457538561;6178.907316390168;6119.41115851624;6144.494598823317;6156.876862462816;6171.13604095427;6219.689015549205;6222.12890195373;6227.875442491125;6232.127502149703;6239.407256310643;6246.718897649616;6178.598391104564;6231.937680623227;6248.968755156286;6252.374762523711;6262.405071518198;4819.807207711652;4855.198892182821;4871.756412179448;4886.755662216875;4943.703377797344;4394.2969124939855;4404.647628189737;4438.245498265303;4452.220861349251;4514.548545145525;5000.74996250186;5684.943150568524;5873.970630146822;5914.349421563625;5938.198343223696;6138.786925362681;6173.59871608344;6238.1266622672865;6244.562880598405;6249.25008998923;5826.766929322865;5937.584369094208;5945.435183657545;5963.4931030862;5972.283326000907;4794.089123066607;5414;5431.511163995259;5465.508104270555;5500.009998200282;4744.928826067612;5540.168974653673;6125.448709616156;6219.222597175353;6341.90487142684;6158.938410616016;6176.413240742122;6229.470495007794;6250.874982500233;6263.0605409187165;6351.2060992375955;6378.6810659467765;6379.904301435389;6388.8083357499345;6414.294427612866;5244.501772331622;6045.21412216409;6097.451229389376;6160.537959652955;6243.781467648792;5058.599073316951;5682.886342273048;5697.031504644303;6265.467435268053;6273.37266273369;5681.715914204356;6146.815595531963;6163.845903852261;6176.629402235701;6189.50483961273;5973.193618861398;5988.251468566429;6041.607295525777;6116.082587611894;6181.443670069715;5805.809808989232;5948.732307046107;5953.642781433128;5964.642121472725;5970.582059255984;6066.636001839904;6115.848865095124;6145.539084568586;6171.9691401542705;6190.288116866618;5985.25184351956;6030.216071910623;6034.9094763577705;6054.576179667381;6071.453569178795 05:57:01 INFO - PROCESS | 1648 | |1;ext.html;442.60165850734336;488.4114641856563;514.5163546266509;532.5100907165408;551.9061759500887;31156;41142.97142571437;41178.38232426512;41243.76268711944;41300;41827.79086104573;42346;42389;42418.36372454416;42475.57524424755;9623.518824058787;9791.216702663769;9802.872669643011;9844.261680374;9844.458554779485;9656.179224765903;9690.32167748258;9739.853902191473;9744.658936937172;9747;9755;9782.608695652165;9792;9792.755181120458;9798.853017204749;9683.709488715349;9705.708828735149;9731;9742.269329800258;9753.951230243874;9655.517224138835;9720.910004349029;9764.462954537568;9764.560594773253;9775.364601300964;35549.46675799865;37297.813510932545;39829.60170398288;40093;40410;2797.4544963732083;3030.6363236411553;3431.0564594736647;3637.418013117888;3887.805609719531;30776.692233077607;30921.536176957365;31571.842140789147;31639.525407118912;31643.525347119812;3256.8697252109887;3273.9836300818342;3310.658718183383;3408.863645454179;3428.45144776837;399762.0011899951;474864;478679;481794;485015;33387.16532086717;34351.14122146966;52486.73756631269;59472.40527594755;59635.403645962986;35192.29615407677;35524.111897202776;35742.463863042074;35919.82040089783;35993.92018239457;14609.561713148623;14650.853491464948;14757.40970361195;14769;14797.852021479863;12301.138920275494;12385.380730963416;12403.007759379228;12492.687682808002;12529.060320476001;7709.421793365408;7812.726554570563;7834.138244793068;7889.842203155905;7951.602419878983;2951.8376489293296;3305.7933854143066;3334.6498617645284;3347.3472672828566;3352.1619595101224;928.3176865004217;937.8593211018402;949.8907625623142;1044.38903241603;1049.7270709615555;29781.702182978326;29915;29924.952626657963;30012.099637010924;30115.247118822204;900.9774755631162;939.1594522901964;1040.7346126737784;1065.6909496246158;1066.221658189526;12520.81218781719;14385;15003.924980375028;15453.38186472551;15802.446914357943;682580;690435;694389;695438;695683;57624.42375576274;57998.42001580015;58185.12722309169;58342;58399.70800146057;1333.2134040915905;1342.7985802129758;1368.3158420789407;1370.6230786533583;1374.8625137486372;28063.859680701466;29246.415071698022;29277.268068298465;29651.40697186001;29737.553936690536;50248.497515024384;50292.982421054854;50423;50444.74777626088;50518;497470;566697;567069;568196;568392;26930.192094237205;27018.729812701622;27317.317067073483;27372.178834634993;27395.452090958468;46936.06127877492;47115.528844711116;47181.528184719085;47535.286970694775;48194;37941.241175176896;38775.41836872393;39485.80257098811;39525.40711889266;39920.38753995494;38765.22469550537;39168.41247381234;39268.80365598154;40297.59702402938;40410;38150.427743583314;38752.2249555013;38792;39610.60389396069;39614;32801.50797738084;33802.830985845896;34976.30047399089;35601;35897.74357897461;23245.3026409208;23580.88209558941;25439.87280063662;25844;25865.870670646527 05:57:01 INFO - PROCESS | 1648 | |2;jquery.html;338.4973314627777;364.4933542376096;376.25877022266144;383.69496250480876;390.95113110861143;18111.818881811196;19368.51578710533;19679;20572.588548229025;21144.788552114474;20829;20964.8951755241;21126.15495380183;21390.25134120306;21802;5174.301469301652;5812.244408226946;5832.387599302076;5987.101934709806;6079.544034197431;5877.441643043904;5901.763929442817;5998.070299103624;6005.819825405223;6030.155778191057;5706.1726049722765;5922.940770592303;5929.347771745104;5949.8215053548465;5961.731722072517;5896.616719913192;5960.642361458305;5979.252593425822;5989.580729348925;6003;4348.847790327323;4980.7509624518625;5196.92204616931;5204;5204.739763011834;21228.044737986827;25677.743222567722;25986;26008.86995565029;26101.477970440676;2162.42095393885;2165.34945370761;2224.0547767198877;2357.3635118518077;3076.8000079994727;15303.387864485407;15801.683966320725;15825.446109386115;15903.363865445368;16264.349426022945;1792.6504331655333;1850.8149185081518;1910.9044547772555;1962.8822270663807;1984.4641946674462;82769;105434.47282763614;107746.46126769316;117587;120081.39959300148;9571.664991725256;10429.947850260853;10542.789144217226;10600.151987840954;10779.78440431187;13554.118982266318;13598;13614.863851361557;13634.727305454035;13709.520166794315;7427.7771666850085;7849.293563579306;8374.120717324715;8499.74500764978;8569.528675922884;6719.328067193339;7131.536450130725;7984.600769961478;8042.115367309591;8659.52372619512;5631.155326700946;5746.370830223551;5810.331811841608;6437.905556055388;6656.500762442836;1524.3216768538116;1864.9160787764583;1877.183425210038;1888.6978083506574;1890.7825600055894;353.3604176440651;366.3863029425701;378.5400738103163;843.6330196364601;888.8755574219614;11547.769044619228;11707.239029463057;11925.880741192477;11977.341246231541;12122.27266364021;321.79808415567834;329.55839175504457;413.3304047443161;418.1553262409915;436.75105190042007;5970.940290597126;6038.175116032678;6113.449789518876;6191.535634827316;6196.659183744847;609643;610943;614319;616135;620139;128635;130214;132751;134061.32969335286;140361;984.3257368702461;1006.4766321512773;1022.539857064324;1024.5082360467018;1026.9948650256702;17132.914335428242;18357;19335.51661208481;19410.514737131685;19506.707399388735;53304.93390132253;53365.39903802892;54372;55115.44884551103;55365.44634553603;76329.61835190789;77185.2281477178;79712.60143699244;79725.60137199509;89232.5538372304;6796.93203067963;7758.277618592841;7991.360691144579;7998.520088794691;8084.595770211348;8935.597898094471;11347.262427942158;12191.207571507824;12553;12579.622611321674;10571.788564228826;10655.25413221067;10942.45287735626;11005.559777608805;11070.28043177191;10572.10137138321;10676.359418434919;10971.451427428437;11022.393768342818;11023.724406889893;8850.778730531789;10537.73547174344;10885.727856803644;11250.7749845001;11276.548938042386;6976.511644185061;7071.186813516511;7561.363217440265;7735.729249476241;7970.601469926596;9300.441973481342;9563.947965723626;9845.409275443497;10328.741781455523;10978.725531861768 05:57:01 INFO - PROCESS | 1648 | |3;mootools.html;1488.8064551608288;1537.746271865142;1735.5053809664248;1822.5625849796054;1858.8327050565456;8006.91993080069;8329.416940814146;8626.956865215672;9089.909100908997;9093.272538196945;8441.240288374045;8803.295736341093;8816.074312197223;8895.28837692983;8904.332175086878;2020.6968954656845;2366.041753089997;2386.0575072846214;2416.7583241675866;2423.164008417102;2061.608294424062;2337.450699085722;2374;2398.13667079852;2421.8667973261463;2347.85912845229;2369.644553316999;2382.9046838126455;2384.129792625688;2394.317619478449;2367.490989437271;2382.749811269809;2388.3790214544256;2391.6053851114543;2400.459896523286;2382.1305223593263;2388.1163969331283;2392.3660230039054;2394.1979436888664;2395.7125144982715;8953.283737301;8964.910350896473;8999.955000225022;9008.954955225248;9023.864642030374;4522.937109779199;4741.241401375798;5304.336957880265;5379.838604841861;5402.054640437907;3108.797928134664;3142.4186525492855;3578.8210589470423;3604.891853244407;3697.926041479155;1050.1913526584487;1064.834950582661;1066.4081434803682;1086.538221255963;1088.9782204355947;11775.05799536035;11789.94105029478;11824.349660768741;11891.405429728478;11907.583234586747;4026.879193624196;4622.306654001859;4648.395708557866;4861.489543597942;4865.635077369211;9004.50475223869;10144.087032167141;10165.339252948535;10199;10227;5120.615953803405;5457.345118585795;5461.617686761889;5461.6996065216;5470;4438.755868427267;4639.164950308977;4788.233882578771;4948.05986862495;4978.775955081957;3981.163955569305;4021.2560676274397;4080.367543030776;4092.7748973806724;4152.086540960984;1309.12942892977;1309.5874799438147;1311.6524121107914;1322.385090932721;1328.9269090200132;895.1987970766128;913.5980168725739;917.5137177296044;935.9333695094803;939.8252184769038;5735.541156707453;5984.2818861736;6040.939590604125;6046.0024096024545;6082.7262773174425;418.54587772266825;430.7716910037685;432.58687953004807;434.1707338982528;438.6753802186359;3966.5636779954184;3978.403239514038;4055.3511438169753;4073;4091.672666186698;10624;10832.620858269922;10949.945250273857;10962.342259464458;10971.890281097087;10849.78300433987;10866.891331086588;10870.130389568816;10877.401742904058;10893;7760.262775036363;8179;8191.098979112293;8388.999999999878;8408.831823363498;4598.310253461874;5626.549876009827;5762.855928601818;5843.1235314703135;5903.763849445974;9987.900120998696;10036.598536058767;10059.19526437898;10102.040306170902;10302.278840481395;10136.341137826017;10302.124319432633;10361.63734269297;10440.42577658236;10468.424236667057;6945.687444064928;6959.89560156588;7572.3942084632;7609.162992070978;7684.039495063117;8453.535055571756;8605.65577376898;8606.967163940368;8620.13798620133;8634;7476.626168691652;7573.5444407332525;7583.5070720403;7699.499532530368;7749;7356.374708149867;7432;7458.664360103699;7550.60313841941;7630.580318082559;6943.548669336477;6992.61540615271;7114.822129446805;7117.537360071578;7120.893186602101;4672.5327467253;4841.540053695035;4936.062148191832;5152.407473140637;5162.09663308927;5059.342285502861;5108.208227724708;5199.844004679866;5224;5305.257263982969 05:57:01 INFO - PROCESS | 1648 | |4;prototype.html;302.58243623799154;304.98475076246183;312.185196636778;316.83524567225027;319.9232184275773;4677.53224677532;5099.2917372680195;5247.2653828464045;5434.483724046209;5530.695811730348;5179.378474583055;5307.283516725251;5391.78432862685;5399.9730001350035;5433.1035379162395;3274.3123943971796;3615.915225432373;4145.937810932831;4148;4192.203481338551;3484.442489201728;3964.6431821136093;4138.4413104231;4177.811998460062;4204.810783514734;3731.4589384539227;4111.48606424197;4130.297849365615;4164.458620379346;4181.64456021237;3263.1189578813705;3818.4654148419245;4135.937960930589;4149.937750933738;4167.729097608676;3211.935761284785;3422.6748458896363;3629.383004889175;3640.5449318835144;3659.2864391443677;3933.5869733678123;4567.474740404863;5038.093143234217;5089.745512724349;5259.658122222043;4090.406891000788;4729.62163026957;4732.952670473285;4734.692245004064;4771.308160316769;5490.940897163564;5719.399463056402;5726.31284245889;5759.112598995703;5782.306123265193;2999.5950546676354;3884.4367566702954;3934.350832112698;3978.189923584762;4000.0399904023116;5683.119116536944;5989.652328226158;6008.699565021731;6029.487493563052;6101.694915254264;4019;4031.274370613318;4052.7365721228025;4054.3310353791294;4065.4714887065084;3447.5862896452586;3891.747036442622;4058.3912413138264;4069.552349241581;4110.506739191256;4123.340265557496;4171.374293855946;4556.154757323289;4691.906161876744;4706.846822528434;4327.805248763813;4552.658550608718;5583.497485226349;5797.942020579824;5821.592488525763;1253.1916913590865;1354;1358.436248956672;1397.9650508737363;1405.2903283841595;695.4992405468055;739.8335374540685;800.8027998142813;837.6607473972929;862.6764963138822;3480.7549130810485;3840.7695538267794;4060.9339948338816;4468.32975053738;4560.928181877256;4270.61564459194;4400.581944715247;4955.82654607087;4956.7112550737065;5052.423264135244;4531.592156705912;4548.295014272793;4558.202314594932;4570.623275896582;4619.745913974763;2271.670607761865;2313.993412865409;2346.343023953296;2437.987810060974;2615.018878478536;4979.514339962041;5044.924326135111;5061.544460998528;5095.541401273903;5104.540591346797;4360.563943605614;4501.549845015472;4665.930011049837;4964.652474326763;5042.899142017139;4445;4642.073539481213;4663.953360466419;4936.3829521309835;5106.183010718267;4063.756174629532;4695.647826413101;4730.47964723887;4908.779104940215;4936.950630493649;3924.331454610892;4063.0136849415067;4422.447194100738;4780.545848144421;4857.8299759508245;4873.586659868598;4888.340074089952;4941.529235382309;5016.573591244785;5152.458991805806;3292.423825830518;3318.8506517207265;3457.844397002091;3503.652552789586;3509.666581674737;4527.818887244473;4670.8365207217585;4796.616270698406;4829.444613869309;5008.223725322581;3766.642168993941;4555.1117532080925;4698.825293676581;5460.645058071213;5686.374498805211;4614.446266448048;4841.419029716316;5385.703786291791;5696.316442026818;5737.225474561048;4978.4025916890205;5274.657147285414;5383.40782513931;5446.809361672323;5636.308218117139;5482.150266708666;5651;5662.377138514677;5712.485876271156;5723.627964182315;5114.4374118847645;5363.7931465421525;5573.498385145356;5611.607187496836;5644.717764111696 05:57:01 INFO - PROCESS | 1648 | |5;yui.html;669.8191488298163;722.9060222171117;798.6086817459445;799.8480288745135;824.2211110500577;15794.289256983435;16541.51126398624;16549.66900661989;16581.41965031224;16617.41839035634;14893.10641361519;15005.774913376308;15036.548903532912;15063.322150503227;15089.84910150901;670.3028849995999;689.9137607799025;823.3495538524559;842.3513894301384;851.6806197675871;854.7777577829747;860.3418384935529;870.2559311788425;871.7733389318792;873.5151990645189;818.4598165210962;823.1521532821174;826.4173757500947;826.8057006603443;828.5360198288947;748.9850202995937;757.2578872704753;790.5335851847437;798.7763426240662;799.6521513141802;560.52915550937;565.9830205093854;607.2348840461007;608.3521050081649;608.4615115622662;14787.482438114723;14998.850011499855;15015.249237538188;15063.397464101423;15066.623334416618;14493.71012579753;14495.782563261455;14556.417743290256;14560.490382836655;14593.854061459355;11553.711157221052;11901.94049029758;11907.821382679267;11915.165938384316;11976.94438206449;3802.5954380796798;3872.341701910682;3878.941815872764;3879.3405121129194;4004.6996475264473;106196;108606;108774;108791;114081.85918140796;11314.547418103184;11339.829902551468;11407.885921140849;11445.656630301104;11727.768584298696;22266.332010039725;23127.653085203736;23147.189848355225;23176.884115579312;23195.53608927812;8028.71899483527;8116.391270654724;8169.30560902318;8171.591420428955;8178.427510074357;3817.1602247505502;3899.2591407633026;3956.3669812829808;4300.817275249331;4508.45898492183;3120.625524937022;3163.936721265608;3619.7285203609836;3727.6086010969;3732.0856390184576;651.1827656291324;652.1587152573222;663.5852592129919;675.4191395399952;678;571.4428432278578;604.9818505444844;663.7875879718443;667.8564108716649;687.8968154776724;8106.270435660819;8956.328275379372;9350.672726454677;9367.344285899922;9753.804923901482;634.8667628283491;656.6946369938001;659.4095151897966;670.4636290967206;680.8229860236275;4445.555444455593;4683.578477937002;4714.080754252887;4730.550597693214;4768.3085952536685;135216;170365;170601;174848;180326;77984;109171;112517;114388;119963.40018299852;111534.42327883573;118073;118655;118934;121388.99999999824;8988.505632190292;8990.415622984747;9057.139571740674;9118.452892826452;9197.908020919705;53938.30308484482;62685.79113402017;63743.98816130315;65655.67172164109;68417;86284.56857715671;91383.08616914012;99247;105877.94122058988;107884.4605776966;12923.289219092663;13041.152325098838;13152.736945261233;13196.60410187696;13686.247256400993;14736.115833050051;14848.85151148518;15209.923950380176;15325.616859578155;15595;10351;13895.235762033186;14140.858591414366;14570.05294655844;14625.122492650475;12462.43919023628;13462.932685336511;13825.654358641115;13924.789377335332;14019.018668693094;11699.356535390636;13303.49877949554;13441.395137219044;13675.726485470434;14005.668866821417;11061.612843550438;11405.942970285096;11515.481803319039;11575.479103440197;11860.22908510944;10847.294925829796;11385.829212561652;11697.23967942081;11711.76576468483;11763.117766167401 05:57:01 INFO - PROCESS | 1648 | __end_tp_report 05:57:01 INFO - PROCESS | 1648 | __start_cc_report 05:57:01 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,369 05:57:01 INFO - PROCESS | 1648 | __end_cc_report 05:57:01 INFO - PROCESS | 1648 | __startTimestamp1475153821948__endTimestamp 05:57:01 INFO - PROCESS | 1648 | 05:57:01 INFO - PROCESS | 1648 | ------- Summary: start ------- 05:57:01 INFO - PROCESS | 1648 | Number of tests: 6 05:57:01 INFO - PROCESS | 1648 | 05:57:01 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5713.44 Median:5948.73 stddev:650.95 (10.9%) stddev-sans-first:615.08 05:57:01 INFO - PROCESS | 1648 | Values: 2924.4 3355.2 3378.6 3395.3 3419.6 5634.7 5637.7 5648.8 5684.1 5728.6 5628.6 5666.3 5737.1 5774.8 5828.2 5160.6 5164.2 5283.9 5316.2 5324.8 5210.9 5353.7 5354.7 5364.7 5422.8 5148.5 5190.2 5219.0 5284.4 5287.3 5226.8 5239.0 5254.1 5270.0 5310.6 5184.1 5196.8 5207.3 5272.4 5275.6 5526.0 5606.5 5631.9 5682.9 5764.3 4951.8 6073.6 6181.1 6217.8 6231.6 6242.2 6259.0 6270.0 6300.1 6306.0 5751.7 5754.6 5789.0 5826.8 5827.4 6224.0 6286.8 6286.8 6287.8 6289.7 6161.7 6164.3 6164.8 6169.8 6178.9 6119.4 6144.5 6156.9 6171.1 6219.7 6222.1 6227.9 6232.1 6239.4 6246.7 6178.6 6231.9 6249.0 6252.4 6262.4 4819.8 4855.2 4871.8 4886.8 4943.7 4394.3 4404.6 4438.2 4452.2 4514.5 5000.7 5684.9 5874.0 5914.3 5938.2 6138.8 6173.6 6238.1 6244.6 6249.3 5826.8 5937.6 5945.4 5963.5 5972.3 4794.1 5414.0 5431.5 5465.5 5500.0 4744.9 5540.2 6125.4 6219.2 6341.9 6158.9 6176.4 6229.5 6250.9 6263.1 6351.2 6378.7 6379.9 6388.8 6414.3 5244.5 6045.2 6097.5 6160.5 6243.8 5058.6 5682.9 5697.0 6265.5 6273.4 5681.7 6146.8 6163.8 6176.6 6189.5 5973.2 5988.3 6041.6 6116.1 6181.4 5805.8 5948.7 5953.6 5964.6 5970.6 6066.6 6115.8 6145.5 6172.0 6190.3 5985.3 6030.2 6034.9 6054.6 6071.5 05:57:01 INFO - PROCESS | 1648 | 05:57:01 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:68771.50 Median:39612.30 stddev:154952.31 (391.2%) stddev-sans-first:155302.07 05:57:01 INFO - PROCESS | 1648 | Values: 442.6 488.4 514.5 532.5 551.9 31156.0 41143.0 41178.4 41243.8 41300.0 41827.8 42346.0 42389.0 42418.4 42475.6 9623.5 9791.2 9802.9 9844.3 9844.5 9656.2 9690.3 9739.9 9744.7 9747.0 9755.0 9782.6 9792.0 9792.8 9798.9 9683.7 9705.7 9731.0 9742.3 9754.0 9655.5 9720.9 9764.5 9764.6 9775.4 35549.5 37297.8 39829.6 40093.0 40410.0 2797.5 3030.6 3431.1 3637.4 3887.8 30776.7 30921.5 31571.8 31639.5 31643.5 3256.9 3274.0 3310.7 3408.9 3428.5 399762.0 474864.0 478679.0 481794.0 485015.0 33387.2 34351.1 52486.7 59472.4 59635.4 35192.3 35524.1 35742.5 35919.8 35993.9 14609.6 14650.9 14757.4 14769.0 14797.9 12301.1 12385.4 12403.0 12492.7 12529.1 7709.4 7812.7 7834.1 7889.8 7951.6 2951.8 3305.8 3334.6 3347.3 3352.2 928.3 937.9 949.9 1044.4 1049.7 29781.7 29915.0 29925.0 30012.1 30115.2 901.0 939.2 1040.7 1065.7 1066.2 12520.8 14385.0 15003.9 15453.4 15802.4 682580.0 690435.0 694389.0 695438.0 695683.0 57624.4 57998.4 58185.1 58342.0 58399.7 1333.2 1342.8 1368.3 1370.6 1374.9 28063.9 29246.4 29277.3 29651.4 29737.6 50248.5 50293.0 50423.0 50444.7 50518.0 497470.0 566697.0 567069.0 568196.0 568392.0 26930.2 27018.7 27317.3 27372.2 27395.5 46936.1 47115.5 47181.5 47535.3 48194.0 37941.2 38775.4 39485.8 39525.4 39920.4 38765.2 39168.4 39268.8 40297.6 40410.0 38150.4 38752.2 38792.0 39610.6 39614.0 32801.5 33802.8 34976.3 35601.0 35897.7 23245.3 23580.9 25439.9 25844.0 25865.9 05:57:01 INFO - PROCESS | 1648 | 05:57:01 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:34981.92 Median:358.93 stddev:102428.17 (28537.3%) stddev-sans-first:102682.48 05:57:01 INFO - PROCESS | 1648 | Values: 338.5 364.5 376.3 383.7 391.0 18111.8 19368.5 19679.0 20572.6 21144.8 20829.0 20964.9 21126.2 21390.3 21802.0 5174.3 5812.2 5832.4 5987.1 6079.5 5877.4 5901.8 5998.1 6005.8 6030.2 5706.2 5922.9 5929.3 5949.8 5961.7 5896.6 5960.6 5979.3 5989.6 6003.0 4348.8 4980.8 5196.9 5204.0 5204.7 21228.0 25677.7 25986.0 26008.9 26101.5 2162.4 2165.3 2224.1 2357.4 3076.8 15303.4 15801.7 15825.4 15903.4 16264.3 1792.7 1850.8 1910.9 1962.9 1984.5 82769.0 105434.5 107746.5 117587.0 120081.4 9571.7 10429.9 10542.8 10600.2 10779.8 13554.1 13598.0 13614.9 13634.7 13709.5 7427.8 7849.3 8374.1 8499.7 8569.5 6719.3 7131.5 7984.6 8042.1 8659.5 5631.2 5746.4 5810.3 6437.9 6656.5 1524.3 1864.9 1877.2 1888.7 1890.8 353.4 366.4 378.5 843.6 888.9 11547.8 11707.2 11925.9 11977.3 12122.3 321.8 329.6 413.3 418.2 436.8 5970.9 6038.2 6113.4 6191.5 6196.7 609643.0 610943.0 614319.0 616135.0 620139.0 128635.0 130214.0 132751.0 134061.3 140361.0 984.3 1006.5 1022.5 1024.5 1027.0 17132.9 18357.0 19335.5 19410.5 19506.7 53304.9 53365.4 54372.0 55115.4 55365.4 76329.6 77185.2 79712.6 79725.6 89232.6 6796.9 7758.3 7991.4 7998.5 8084.6 8935.6 11347.3 12191.2 12553.0 12579.6 10571.8 10655.3 10942.5 11005.6 11070.3 10572.1 10676.4 10971.5 11022.4 11023.7 8850.8 10537.7 10885.7 11250.8 11276.5 6976.5 7071.2 7561.4 7735.7 7970.6 9300.4 9563.9 9845.4 10328.7 10978.7 05:57:01 INFO - PROCESS | 1648 | 05:57:01 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5746.43 Median:4560.62 stddev:3272.67 (71.8%) stddev-sans-first:3266.21 05:57:01 INFO - PROCESS | 1648 | Values: 1488.8 1537.7 1735.5 1822.6 1858.8 8006.9 8329.4 8627.0 9089.9 9093.3 8441.2 8803.3 8816.1 8895.3 8904.3 2020.7 2366.0 2386.1 2416.8 2423.2 2061.6 2337.5 2374.0 2398.1 2421.9 2347.9 2369.6 2382.9 2384.1 2394.3 2367.5 2382.7 2388.4 2391.6 2400.5 2382.1 2388.1 2392.4 2394.2 2395.7 8953.3 8964.9 9000.0 9009.0 9023.9 4522.9 4741.2 5304.3 5379.8 5402.1 3108.8 3142.4 3578.8 3604.9 3697.9 1050.2 1064.8 1066.4 1086.5 1089.0 11775.1 11789.9 11824.3 11891.4 11907.6 4026.9 4622.3 4648.4 4861.5 4865.6 9004.5 10144.1 10165.3 10199.0 10227.0 5120.6 5457.3 5461.6 5461.7 5470.0 4438.8 4639.2 4788.2 4948.1 4978.8 3981.2 4021.3 4080.4 4092.8 4152.1 1309.1 1309.6 1311.7 1322.4 1328.9 895.2 913.6 917.5 935.9 939.8 5735.5 5984.3 6040.9 6046.0 6082.7 418.5 430.8 432.6 434.2 438.7 3966.6 3978.4 4055.4 4073.0 4091.7 10624.0 10832.6 10949.9 10962.3 10971.9 10849.8 10866.9 10870.1 10877.4 10893.0 7760.3 8179.0 8191.1 8389.0 8408.8 4598.3 5626.5 5762.9 5843.1 5903.8 9987.9 10036.6 10059.2 10102.0 10302.3 10136.3 10302.1 10361.6 10440.4 10468.4 6945.7 6959.9 7572.4 7609.2 7684.0 8453.5 8605.7 8607.0 8620.1 8634.0 7476.6 7573.5 7583.5 7699.5 7749.0 7356.4 7432.0 7458.7 7550.6 7630.6 6943.5 6992.6 7114.8 7117.5 7120.9 4672.5 4841.5 4936.1 5152.4 5162.1 5059.3 5108.2 5199.8 5224.0 5305.3 05:57:01 INFO - PROCESS | 1648 | 05:57:01 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4273.59 Median:4630.91 stddev:1307.39 (28.2%) stddev-sans-first:1276.63 05:57:01 INFO - PROCESS | 1648 | Values: 302.6 305.0 312.2 316.8 319.9 4677.5 5099.3 5247.3 5434.5 5530.7 5179.4 5307.3 5391.8 5400.0 5433.1 3274.3 3615.9 4145.9 4148.0 4192.2 3484.4 3964.6 4138.4 4177.8 4204.8 3731.5 4111.5 4130.3 4164.5 4181.6 3263.1 3818.5 4135.9 4149.9 4167.7 3211.9 3422.7 3629.4 3640.5 3659.3 3933.6 4567.5 5038.1 5089.7 5259.7 4090.4 4729.6 4733.0 4734.7 4771.3 5490.9 5719.4 5726.3 5759.1 5782.3 2999.6 3884.4 3934.4 3978.2 4000.0 5683.1 5989.7 6008.7 6029.5 6101.7 4019.0 4031.3 4052.7 4054.3 4065.5 3447.6 3891.7 4058.4 4069.6 4110.5 4123.3 4171.4 4556.2 4691.9 4706.8 4327.8 4552.7 5583.5 5797.9 5821.6 1253.2 1354.0 1358.4 1398.0 1405.3 695.5 739.8 800.8 837.7 862.7 3480.8 3840.8 4060.9 4468.3 4560.9 4270.6 4400.6 4955.8 4956.7 5052.4 4531.6 4548.3 4558.2 4570.6 4619.7 2271.7 2314.0 2346.3 2438.0 2615.0 4979.5 5044.9 5061.5 5095.5 5104.5 4360.6 4501.5 4665.9 4964.7 5042.9 4445.0 4642.1 4664.0 4936.4 5106.2 4063.8 4695.6 4730.5 4908.8 4937.0 3924.3 4063.0 4422.4 4780.5 4857.8 4873.6 4888.3 4941.5 5016.6 5152.5 3292.4 3318.9 3457.8 3503.7 3509.7 4527.8 4670.8 4796.6 4829.4 5008.2 3766.6 4555.1 4698.8 5460.6 5686.4 4614.4 4841.4 5385.7 5696.3 5737.2 4978.4 5274.7 5383.4 5446.8 5636.3 5482.2 5651.0 5662.4 5712.5 5723.6 5114.4 5363.8 5573.5 5611.6 5644.7 05:57:01 INFO - PROCESS | 1648 | 05:57:01 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:25248.18 Median:13158.08 stddev:40706.37 (309.4%) stddev-sans-first:40778.73 05:57:01 INFO - PROCESS | 1648 | Values: 669.8 722.9 798.6 799.8 824.2 15794.3 16541.5 16549.7 16581.4 16617.4 14893.1 15005.8 15036.5 15063.3 15089.8 670.3 689.9 823.3 842.4 851.7 854.8 860.3 870.3 871.8 873.5 818.5 823.2 826.4 826.8 828.5 749.0 757.3 790.5 798.8 799.7 560.5 566.0 607.2 608.4 608.5 14787.5 14998.9 15015.2 15063.4 15066.6 14493.7 14495.8 14556.4 14560.5 14593.9 11553.7 11901.9 11907.8 11915.2 11976.9 3802.6 3872.3 3878.9 3879.3 4004.7 106196.0 108606.0 108774.0 108791.0 114081.9 11314.5 11339.8 11407.9 11445.7 11727.8 22266.3 23127.7 23147.2 23176.9 23195.5 8028.7 8116.4 8169.3 8171.6 8178.4 3817.2 3899.3 3956.4 4300.8 4508.5 3120.6 3163.9 3619.7 3727.6 3732.1 651.2 652.2 663.6 675.4 678.0 571.4 605.0 663.8 667.9 687.9 8106.3 8956.3 9350.7 9367.3 9753.8 634.9 656.7 659.4 670.5 680.8 4445.6 4683.6 4714.1 4730.6 4768.3 135216.0 170365.0 170601.0 174848.0 180326.0 77984.0 109171.0 112517.0 114388.0 119963.4 111534.4 118073.0 118655.0 118934.0 121389.0 8988.5 8990.4 9057.1 9118.5 9197.9 53938.3 62685.8 63744.0 65655.7 68417.0 86284.6 91383.1 99247.0 105877.9 107884.5 12923.3 13041.2 13152.7 13196.6 13686.2 14736.1 14848.9 15209.9 15325.6 15595.0 10351.0 13895.2 14140.9 14570.1 14625.1 12462.4 13462.9 13825.7 13924.8 14019.0 11699.4 13303.5 13441.4 13675.7 14005.7 11061.6 11405.9 11515.5 11575.5 11860.2 10847.3 11385.8 11697.2 11711.8 11763.1 05:57:01 INFO - PROCESS | 1648 | -------- Summary: end -------- 05:57:01 INFO - PROCESS | 1648 | 05:57:02 INFO - TEST-INFO | 1648: exit 0 05:57:02 INFO - TEST-OK | dromaeo_css | took 1114400ms 05:57:02 INFO - TEST-START | kraken 05:57:02 INFO - operating with platform_type : mac_ 05:57:02 INFO - Initialising browser for kraken test... 05:57:02 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpVMs5nW/profile http://localhost:49271/getInfo.html) 05:57:05 INFO - PROCESS | 1659 | 2016-09-29 05:57:05.096 plugin-container[1661:9897] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.tsm.portname' 05:57:05 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:57:05 INFO - PROCESS | 1659 | 2016-09-29 05:57:05.096 plugin-container[1661:9897] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x891b, name = 'com.apple.CFPasteboardClient' 05:57:05 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:57:05 INFO - PROCESS | 1659 | 2016-09-29 05:57:05.096 plugin-container[1661:9897] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:57:05 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 05:57:05 INFO - PROCESS | 1659 | colorDepth:24 05:57:05 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 05:57:05 INFO - PROCESS | 1659 | __metrics 05:57:05 ERROR - PROCESS | 1659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3076: TypeError: this.tabs is undefined 05:57:05 INFO - TEST-INFO | 1659: exit 0 05:57:05 INFO - Browser initialized. 05:57:05 INFO - Running cycle 1/1 for kraken test... 05:57:05 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpVMs5nW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:57:08 INFO - PROCESS | 1662 | 2016-09-29 05:57:08.569 plugin-container[1663:10010] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x881f, name = 'com.apple.tsm.portname' 05:57:08 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:57:08 INFO - PROCESS | 1662 | 2016-09-29 05:57:08.570 plugin-container[1663:10010] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d03, name = 'com.apple.CFPasteboardClient' 05:57:08 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:57:08 INFO - PROCESS | 1662 | 2016-09-29 05:57:08.570 plugin-container[1663:10010] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:57:18 INFO - PROCESS | 1662 | RSS: Main: 239894528 05:57:18 INFO - PROCESS | 1662 | 05:57:20 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 05:57:21 INFO - PROCESS | 1662 | RSS: Main: 253313024 05:57:21 INFO - PROCESS | 1662 | 05:57:24 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 05:57:24 INFO - PROCESS | 1662 | RSS: Main: 260784128 05:57:24 INFO - PROCESS | 1662 | 05:57:28 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 05:57:28 INFO - PROCESS | 1662 | RSS: Main: 261300224 05:57:28 INFO - PROCESS | 1662 | 05:57:30 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 05:57:31 INFO - PROCESS | 1662 | RSS: Main: 263491584 05:57:31 INFO - PROCESS | 1662 | 05:57:33 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:57:33 INFO - PROCESS | 1662 | RSS: Main: 263897088 05:57:33 INFO - PROCESS | 1662 | 05:57:36 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 05:57:37 INFO - PROCESS | 1662 | RSS: Main: 264634368 05:57:37 INFO - PROCESS | 1662 | 05:57:40 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 05:57:41 INFO - PROCESS | 1662 | RSS: Main: 265482240 05:57:41 INFO - PROCESS | 1662 | 05:57:44 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 05:57:44 INFO - PROCESS | 1662 | RSS: Main: 265465856 05:57:44 INFO - PROCESS | 1662 | 05:57:46 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:57:46 INFO - PROCESS | 1662 | RSS: Main: 265003008 05:57:46 INFO - PROCESS | 1662 | 05:57:48 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:57:49 INFO - PROCESS | 1662 | RSS: Main: 265084928 05:57:49 INFO - PROCESS | 1662 | 05:57:51 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:57:52 INFO - PROCESS | 1662 | RSS: Main: 265072640 05:57:52 INFO - PROCESS | 1662 | 05:57:55 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:57:56 INFO - PROCESS | 1662 | RSS: Main: 265125888 05:57:56 INFO - PROCESS | 1662 | 05:57:59 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:57:59 INFO - PROCESS | 1662 | RSS: Main: 265486336 05:57:59 INFO - PROCESS | 1662 | 05:58:01 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 05:58:01 INFO - PROCESS | 1662 | RSS: Main: 267337728 05:58:01 INFO - PROCESS | 1662 | 05:58:01 INFO - PROCESS | 1662 | __start_tp_report 05:58:01 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 05:58:01 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 05:58:01 INFO - PROCESS | 1662 | |i|pagename|runs| 05:58:01 INFO - PROCESS | 1662 | |0;ai-astar;115;117;111;116;117;118;117;115;116;117 05:58:01 INFO - PROCESS | 1662 | |1;audio-beat-detection;143;113;112;149;112;112;111;114;141;98 05:58:01 INFO - PROCESS | 1662 | |2;audio-dft;192;201;196;207;242;269;196;198;199;230 05:58:01 INFO - PROCESS | 1662 | |3;audio-fft;83;61;59;96;62;62;49;65;102;51 05:58:01 INFO - PROCESS | 1662 | |4;audio-oscillator;76;71;87;74;68;70;86;69;75;87 05:58:01 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;91;85;80;116;137;85;88;85;88;87 05:58:01 INFO - PROCESS | 1662 | |6;imaging-darkroom;118;171;159;98;116;128;99;98;133;122 05:58:01 INFO - PROCESS | 1662 | |7;imaging-desaturate;126;95;94;96;95;94;97;97;94;92 05:58:01 INFO - PROCESS | 1662 | |8;json-parse-financial;59;63;82;80;59;58;57;58;58;60 05:58:01 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;69;71;73;89;71;68;57;88;102;108 05:58:01 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;107;74;105;110;112;106;86;89;88;85 05:58:01 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;161;143;168;161;164;141;141;157;201;159 05:58:01 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;171;175;168;187;194;170;163;165;235;179 05:58:01 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;68;68;71;73;98;88;69;69;70;78 05:58:01 INFO - PROCESS | 1662 | __end_tp_report 05:58:01 INFO - PROCESS | 1662 | __start_cc_report 05:58:01 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,910 05:58:01 INFO - PROCESS | 1662 | __end_cc_report 05:58:01 INFO - PROCESS | 1662 | __startTimestamp1475153881978__endTimestamp 05:58:01 INFO - PROCESS | 1662 | 05:58:01 INFO - PROCESS | 1662 | ------- Summary: start ------- 05:58:01 INFO - PROCESS | 1662 | Number of tests: 14 05:58:01 INFO - PROCESS | 1662 | 05:58:01 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:115.90 Median:117.00 stddev:1.97 (1.7%) stddev-sans-first:2.06 05:58:01 INFO - PROCESS | 1662 | Values: 115.0 117.0 111.0 116.0 117.0 118.0 117.0 115.0 116.0 117.0 05:58:01 INFO - PROCESS | 1662 | 05:58:01 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:120.50 Median:127.50 stddev:17.16 (13.5%) stddev-sans-first:16.16 05:58:01 INFO - PROCESS | 1662 | Values: 143.0 113.0 112.0 149.0 112.0 112.0 111.0 114.0 141.0 98.0 05:58:01 INFO - PROCESS | 1662 | 05:58:01 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:213.00 Median:204.00 stddev:25.57 (12.5%) stddev-sans-first:25.97 05:58:01 INFO - PROCESS | 1662 | Values: 192.0 201.0 196.0 207.0 242.0 269.0 196.0 198.0 199.0 230.0 05:58:01 INFO - PROCESS | 1662 | 05:58:01 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:69.00 Median:62.00 stddev:18.31 (29.5%) stddev-sans-first:18.70 05:58:01 INFO - PROCESS | 1662 | Values: 83.0 61.0 59.0 96.0 62.0 62.0 49.0 65.0 102.0 51.0 05:58:01 INFO - PROCESS | 1662 | 05:58:01 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:76.30 Median:75.50 stddev:7.60 (10.1%) stddev-sans-first:8.06 05:58:01 INFO - PROCESS | 1662 | Values: 76.0 71.0 87.0 74.0 68.0 70.0 86.0 69.0 75.0 87.0 05:58:01 INFO - PROCESS | 1662 | 05:58:01 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:94.20 Median:86.00 stddev:17.96 (20.9%) stddev-sans-first:19.01 05:58:01 INFO - PROCESS | 1662 | Values: 91.0 85.0 80.0 116.0 137.0 85.0 88.0 85.0 88.0 87.0 05:58:01 INFO - PROCESS | 1662 | 05:58:01 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:124.20 Median:165.00 stddev:24.97 (15.1%) stddev-sans-first:26.38 05:58:01 INFO - PROCESS | 1662 | Values: 118.0 171.0 159.0 98.0 116.0 128.0 99.0 98.0 133.0 122.0 05:58:01 INFO - PROCESS | 1662 | 05:58:01 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:98.00 Median:95.00 stddev:9.96 (10.5%) stddev-sans-first:1.62 05:58:01 INFO - PROCESS | 1662 | Values: 126.0 95.0 94.0 96.0 95.0 94.0 97.0 97.0 94.0 92.0 05:58:01 INFO - PROCESS | 1662 | 05:58:01 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:63.40 Median:59.50 stddev:9.43 (15.8%) stddev-sans-first:9.87 05:58:01 INFO - PROCESS | 1662 | Values: 59.0 63.0 82.0 80.0 59.0 58.0 57.0 58.0 58.0 60.0 05:58:01 INFO - PROCESS | 1662 | 05:58:01 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:79.60 Median:71.00 stddev:16.39 (23.1%) stddev-sans-first:16.92 05:58:01 INFO - PROCESS | 1662 | Values: 69.0 71.0 73.0 89.0 71.0 68.0 57.0 88.0 102.0 108.0 05:58:01 INFO - PROCESS | 1662 | 05:58:01 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:96.20 Median:79.50 stddev:13.21 (16.6%) stddev-sans-first:13.43 05:58:01 INFO - PROCESS | 1662 | Values: 107.0 74.0 105.0 110.0 112.0 106.0 86.0 89.0 88.0 85.0 05:58:01 INFO - PROCESS | 1662 | 05:58:01 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:159.60 Median:161.00 stddev:17.58 (10.9%) stddev-sans-first:18.64 05:58:01 INFO - PROCESS | 1662 | Values: 161.0 143.0 168.0 161.0 164.0 141.0 141.0 157.0 201.0 159.0 05:58:01 INFO - PROCESS | 1662 | 05:58:01 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.70 Median:177.00 stddev:21.42 (12.1%) stddev-sans-first:22.43 05:58:01 INFO - PROCESS | 1662 | Values: 171.0 175.0 168.0 187.0 194.0 170.0 163.0 165.0 235.0 179.0 05:58:01 INFO - PROCESS | 1662 | 05:58:01 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.20 Median:72.00 stddev:10.12 (14.1%) stddev-sans-first:10.39 05:58:01 INFO - PROCESS | 1662 | Values: 68.0 68.0 71.0 73.0 98.0 88.0 69.0 69.0 70.0 78.0 05:58:01 INFO - PROCESS | 1662 | -------- Summary: end -------- 05:58:01 INFO - PROCESS | 1662 | 05:58:02 INFO - TEST-INFO | 1662: exit 0 05:58:02 INFO - TEST-OK | kraken | took 60265ms 05:58:02 INFO - SUITE-END | took 1174s 05:58:03 INFO - Completed test suite (00:19:34) 05:58:03 INFO - javascript benchmark 05:58:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7103.515984158134, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2924.429736201441, 3355.211525291558, 3378.645242249563, 3395.270016946356, 3419.6321471411416, 5634.7182640867995, 5637.718114094299, 5648.830535083944, 5684.118961560955, 5728.570357223204, 5628.577856660746, 5666.320041595004, 5737.110747834093, 5774.82675519735, 5828.213191219194, 5160.56135228506, 5164.173732202848, 5283.920741188886, 5316.20256961455, 5324.813631522898, 5210.895782084356, 5353.678779273237, 5354.732263386834, 5364.704941228231, 5422.83731488054, 5148.536631703128, 5190.24741412493, 5218.982298451803, 5284.4187139414635, 5287.259783630295, 5226.817061402832, 5239.030779305841, 5254.133068043769, 5269.998700246979, 5310.62825602208, 5184.118699821001, 5196.792128314863, 5207.3490813648295, 5272.367315922113, 5275.630705850592, 5525.972370138164, 5606.523445507137, 5631.943680563224, 5682.8634273145535, 5764.33710123337, 4951.7771700273215, 6073.605215660968, 6181.134641150243, 6217.844553886144, 6231.626102433823, 6242.188515493002, 6258.968705156491, 6269.96865015672, 6300.086487459293, 6305.968470157666, 5751.683657398883, 5754.568407369464, 5788.957987562279, 5826.766929322822, 5827.388124246935, 6223.973044447615, 6286.811395658137, 6286.842828929313, 6287.811365659038, 6289.7484100635465, 6161.7227224775, 6164.260288765377, 6164.7842325518395, 6169.8457538561, 6178.907316390168, 6119.41115851624, 6144.494598823317, 6156.876862462816, 6171.13604095427, 6219.689015549205, 6222.12890195373, 6227.875442491125, 6232.127502149703, 6239.407256310643, 6246.718897649616, 6178.598391104564, 6231.937680623227, 6248.968755156286, 6252.374762523711, 6262.405071518198, 4819.807207711652, 4855.198892182821, 4871.756412179448, 4886.755662216875, 4943.703377797344, 4394.2969124939855, 4404.647628189737, 4438.245498265303, 4452.220861349251, 4514.548545145525, 5000.74996250186, 5684.943150568524, 5873.970630146822, 5914.349421563625, 5938.198343223696, 6138.786925362681, 6173.59871608344, 6238.1266622672865, 6244.562880598405, 6249.25008998923, 5826.766929322865, 5937.584369094208, 5945.435183657545, 5963.4931030862, 5972.283326000907, 4794.089123066607, 5414.0, 5431.511163995259, 5465.508104270555, 5500.009998200282, 4744.928826067612, 5540.168974653673, 6125.448709616156, 6219.222597175353, 6341.90487142684, 6158.938410616016, 6176.413240742122, 6229.470495007794, 6250.874982500233, 6263.0605409187165, 6351.2060992375955, 6378.6810659467765, 6379.904301435389, 6388.8083357499345, 6414.294427612866, 5244.501772331622, 6045.21412216409, 6097.451229389376, 6160.537959652955, 6243.781467648792, 5058.599073316951, 5682.886342273048, 5697.031504644303, 6265.467435268053, 6273.37266273369, 5681.715914204356, 6146.815595531963, 6163.845903852261, 6176.629402235701, 6189.50483961273, 5973.193618861398, 5988.251468566429, 6041.607295525777, 6116.082587611894, 6181.443670069715, 5805.809808989232, 5948.732307046107, 5953.642781433128, 5964.642121472725, 5970.582059255984, 6066.636001839904, 6115.848865095124, 6145.539084568586, 6171.9691401542705, 6190.288116866618, 5985.25184351956, 6030.216071910623, 6034.9094763577705, 6054.576179667381, 6071.453569178795], "value": 5672.873077848516, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [442.60165850734336, 488.4114641856563, 514.5163546266509, 532.5100907165408, 551.9061759500887, 31156.0, 41142.97142571437, 41178.38232426512, 41243.76268711944, 41300.0, 41827.79086104573, 42346.0, 42389.0, 42418.36372454416, 42475.57524424755, 9623.518824058787, 9791.216702663769, 9802.872669643011, 9844.261680374, 9844.458554779485, 9656.179224765903, 9690.32167748258, 9739.853902191473, 9744.658936937172, 9747.0, 9755.0, 9782.608695652165, 9792.0, 9792.755181120458, 9798.853017204749, 9683.709488715349, 9705.708828735149, 9731.0, 9742.269329800258, 9753.951230243874, 9655.517224138835, 9720.910004349029, 9764.462954537568, 9764.560594773253, 9775.364601300964, 35549.46675799865, 37297.813510932545, 39829.60170398288, 40093.0, 40410.0, 2797.4544963732083, 3030.6363236411553, 3431.0564594736647, 3637.418013117888, 3887.805609719531, 30776.692233077607, 30921.536176957365, 31571.842140789147, 31639.525407118912, 31643.525347119812, 3256.8697252109887, 3273.9836300818342, 3310.658718183383, 3408.863645454179, 3428.45144776837, 399762.0011899951, 474864.0, 478679.0, 481794.0, 485015.0, 33387.16532086717, 34351.14122146966, 52486.73756631269, 59472.40527594755, 59635.403645962986, 35192.29615407677, 35524.111897202776, 35742.463863042074, 35919.82040089783, 35993.92018239457, 14609.561713148623, 14650.853491464948, 14757.40970361195, 14769.0, 14797.852021479863, 12301.138920275494, 12385.380730963416, 12403.007759379228, 12492.687682808002, 12529.060320476001, 7709.421793365408, 7812.726554570563, 7834.138244793068, 7889.842203155905, 7951.602419878983, 2951.8376489293296, 3305.7933854143066, 3334.6498617645284, 3347.3472672828566, 3352.1619595101224, 928.3176865004217, 937.8593211018402, 949.8907625623142, 1044.38903241603, 1049.7270709615555, 29781.702182978326, 29915.0, 29924.952626657963, 30012.099637010924, 30115.247118822204, 900.9774755631162, 939.1594522901964, 1040.7346126737784, 1065.6909496246158, 1066.221658189526, 12520.81218781719, 14385.0, 15003.924980375028, 15453.38186472551, 15802.446914357943, 682580.0, 690435.0, 694389.0, 695438.0, 695683.0, 57624.42375576274, 57998.42001580015, 58185.12722309169, 58342.0, 58399.70800146057, 1333.2134040915905, 1342.7985802129758, 1368.3158420789407, 1370.6230786533583, 1374.8625137486372, 28063.859680701466, 29246.415071698022, 29277.268068298465, 29651.40697186001, 29737.553936690536, 50248.497515024384, 50292.982421054854, 50423.0, 50444.74777626088, 50518.0, 497470.0, 566697.0, 567069.0, 568196.0, 568392.0, 26930.192094237205, 27018.729812701622, 27317.317067073483, 27372.178834634993, 27395.452090958468, 46936.06127877492, 47115.528844711116, 47181.528184719085, 47535.286970694775, 48194.0, 37941.241175176896, 38775.41836872393, 39485.80257098811, 39525.40711889266, 39920.38753995494, 38765.22469550537, 39168.41247381234, 39268.80365598154, 40297.59702402938, 40410.0, 38150.427743583314, 38752.2249555013, 38792.0, 39610.60389396069, 39614.0, 32801.50797738084, 33802.830985845896, 34976.30047399089, 35601.0, 35897.74357897461, 23245.3026409208, 23580.88209558941, 25439.87280063662, 25844.0, 25865.870670646527], "value": 18571.251337910948, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.4973314627777, 364.4933542376096, 376.25877022266144, 383.69496250480876, 390.95113110861143, 18111.818881811196, 19368.51578710533, 19679.0, 20572.588548229025, 21144.788552114474, 20829.0, 20964.8951755241, 21126.15495380183, 21390.25134120306, 21802.0, 5174.301469301652, 5812.244408226946, 5832.387599302076, 5987.101934709806, 6079.544034197431, 5877.441643043904, 5901.763929442817, 5998.070299103624, 6005.819825405223, 6030.155778191057, 5706.1726049722765, 5922.940770592303, 5929.347771745104, 5949.8215053548465, 5961.731722072517, 5896.616719913192, 5960.642361458305, 5979.252593425822, 5989.580729348925, 6003.0, 4348.847790327323, 4980.7509624518625, 5196.92204616931, 5204.0, 5204.739763011834, 21228.044737986827, 25677.743222567722, 25986.0, 26008.86995565029, 26101.477970440676, 2162.42095393885, 2165.34945370761, 2224.0547767198877, 2357.3635118518077, 3076.8000079994727, 15303.387864485407, 15801.683966320725, 15825.446109386115, 15903.363865445368, 16264.349426022945, 1792.6504331655333, 1850.8149185081518, 1910.9044547772555, 1962.8822270663807, 1984.4641946674462, 82769.0, 105434.47282763614, 107746.46126769316, 117587.0, 120081.39959300148, 9571.664991725256, 10429.947850260853, 10542.789144217226, 10600.151987840954, 10779.78440431187, 13554.118982266318, 13598.0, 13614.863851361557, 13634.727305454035, 13709.520166794315, 7427.7771666850085, 7849.293563579306, 8374.120717324715, 8499.74500764978, 8569.528675922884, 6719.328067193339, 7131.536450130725, 7984.600769961478, 8042.115367309591, 8659.52372619512, 5631.155326700946, 5746.370830223551, 5810.331811841608, 6437.905556055388, 6656.500762442836, 1524.3216768538116, 1864.9160787764583, 1877.183425210038, 1888.6978083506574, 1890.7825600055894, 353.3604176440651, 366.3863029425701, 378.5400738103163, 843.6330196364601, 888.8755574219614, 11547.769044619228, 11707.239029463057, 11925.880741192477, 11977.341246231541, 12122.27266364021, 321.79808415567834, 329.55839175504457, 413.3304047443161, 418.1553262409915, 436.75105190042007, 5970.940290597126, 6038.175116032678, 6113.449789518876, 6191.535634827316, 6196.659183744847, 609643.0, 610943.0, 614319.0, 616135.0, 620139.0, 128635.0, 130214.0, 132751.0, 134061.32969335286, 140361.0, 984.3257368702461, 1006.4766321512773, 1022.539857064324, 1024.5082360467018, 1026.9948650256702, 17132.914335428242, 18357.0, 19335.51661208481, 19410.514737131685, 19506.707399388735, 53304.93390132253, 53365.39903802892, 54372.0, 55115.44884551103, 55365.44634553603, 76329.61835190789, 77185.2281477178, 79712.60143699244, 79725.60137199509, 89232.5538372304, 6796.93203067963, 7758.277618592841, 7991.360691144579, 7998.520088794691, 8084.595770211348, 8935.597898094471, 11347.262427942158, 12191.207571507824, 12553.0, 12579.622611321674, 10571.788564228826, 10655.25413221067, 10942.45287735626, 11005.559777608805, 11070.28043177191, 10572.10137138321, 10676.359418434919, 10971.451427428437, 11022.393768342818, 11023.724406889893, 8850.778730531789, 10537.73547174344, 10885.727856803644, 11250.7749845001, 11276.548938042386, 6976.511644185061, 7071.186813516511, 7561.363217440265, 7735.729249476241, 7970.601469926596, 9300.441973481342, 9563.947965723626, 9845.409275443497, 10328.741781455523, 10978.725531861768], "value": 8909.202030877344, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1488.8064551608288, 1537.746271865142, 1735.5053809664248, 1822.5625849796054, 1858.8327050565456, 8006.91993080069, 8329.416940814146, 8626.956865215672, 9089.909100908997, 9093.272538196945, 8441.240288374045, 8803.295736341093, 8816.074312197223, 8895.28837692983, 8904.332175086878, 2020.6968954656845, 2366.041753089997, 2386.0575072846214, 2416.7583241675866, 2423.164008417102, 2061.608294424062, 2337.450699085722, 2374.0, 2398.13667079852, 2421.8667973261463, 2347.85912845229, 2369.644553316999, 2382.9046838126455, 2384.129792625688, 2394.317619478449, 2367.490989437271, 2382.749811269809, 2388.3790214544256, 2391.6053851114543, 2400.459896523286, 2382.1305223593263, 2388.1163969331283, 2392.3660230039054, 2394.1979436888664, 2395.7125144982715, 8953.283737301, 8964.910350896473, 8999.955000225022, 9008.954955225248, 9023.864642030374, 4522.937109779199, 4741.241401375798, 5304.336957880265, 5379.838604841861, 5402.054640437907, 3108.797928134664, 3142.4186525492855, 3578.8210589470423, 3604.891853244407, 3697.926041479155, 1050.1913526584487, 1064.834950582661, 1066.4081434803682, 1086.538221255963, 1088.9782204355947, 11775.05799536035, 11789.94105029478, 11824.349660768741, 11891.405429728478, 11907.583234586747, 4026.879193624196, 4622.306654001859, 4648.395708557866, 4861.489543597942, 4865.635077369211, 9004.50475223869, 10144.087032167141, 10165.339252948535, 10199.0, 10227.0, 5120.615953803405, 5457.345118585795, 5461.617686761889, 5461.6996065216, 5470.0, 4438.755868427267, 4639.164950308977, 4788.233882578771, 4948.05986862495, 4978.775955081957, 3981.163955569305, 4021.2560676274397, 4080.367543030776, 4092.7748973806724, 4152.086540960984, 1309.12942892977, 1309.5874799438147, 1311.6524121107914, 1322.385090932721, 1328.9269090200132, 895.1987970766128, 913.5980168725739, 917.5137177296044, 935.9333695094803, 939.8252184769038, 5735.541156707453, 5984.2818861736, 6040.939590604125, 6046.0024096024545, 6082.7262773174425, 418.54587772266825, 430.7716910037685, 432.58687953004807, 434.1707338982528, 438.6753802186359, 3966.5636779954184, 3978.403239514038, 4055.3511438169753, 4073.0, 4091.672666186698, 10624.0, 10832.620858269922, 10949.945250273857, 10962.342259464458, 10971.890281097087, 10849.78300433987, 10866.891331086588, 10870.130389568816, 10877.401742904058, 10893.0, 7760.262775036363, 8179.0, 8191.098979112293, 8388.999999999878, 8408.831823363498, 4598.310253461874, 5626.549876009827, 5762.855928601818, 5843.1235314703135, 5903.763849445974, 9987.900120998696, 10036.598536058767, 10059.19526437898, 10102.040306170902, 10302.278840481395, 10136.341137826017, 10302.124319432633, 10361.63734269297, 10440.42577658236, 10468.424236667057, 6945.687444064928, 6959.89560156588, 7572.3942084632, 7609.162992070978, 7684.039495063117, 8453.535055571756, 8605.65577376898, 8606.967163940368, 8620.13798620133, 8634.0, 7476.626168691652, 7573.5444407332525, 7583.5070720403, 7699.499532530368, 7749.0, 7356.374708149867, 7432.0, 7458.664360103699, 7550.60313841941, 7630.580318082559, 6943.548669336477, 6992.61540615271, 7114.822129446805, 7117.537360071578, 7120.893186602101, 4672.5327467253, 4841.540053695035, 4936.062148191832, 5152.407473140637, 5162.09663308927, 5059.342285502861, 5108.208227724708, 5199.844004679866, 5224.0, 5305.257263982969], "value": 4527.288699207534, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.58243623799154, 304.98475076246183, 312.185196636778, 316.83524567225027, 319.9232184275773, 4677.53224677532, 5099.2917372680195, 5247.2653828464045, 5434.483724046209, 5530.695811730348, 5179.378474583055, 5307.283516725251, 5391.78432862685, 5399.9730001350035, 5433.1035379162395, 3274.3123943971796, 3615.915225432373, 4145.937810932831, 4148.0, 4192.203481338551, 3484.442489201728, 3964.6431821136093, 4138.4413104231, 4177.811998460062, 4204.810783514734, 3731.4589384539227, 4111.48606424197, 4130.297849365615, 4164.458620379346, 4181.64456021237, 3263.1189578813705, 3818.4654148419245, 4135.937960930589, 4149.937750933738, 4167.729097608676, 3211.935761284785, 3422.6748458896363, 3629.383004889175, 3640.5449318835144, 3659.2864391443677, 3933.5869733678123, 4567.474740404863, 5038.093143234217, 5089.745512724349, 5259.658122222043, 4090.406891000788, 4729.62163026957, 4732.952670473285, 4734.692245004064, 4771.308160316769, 5490.940897163564, 5719.399463056402, 5726.31284245889, 5759.112598995703, 5782.306123265193, 2999.5950546676354, 3884.4367566702954, 3934.350832112698, 3978.189923584762, 4000.0399904023116, 5683.119116536944, 5989.652328226158, 6008.699565021731, 6029.487493563052, 6101.694915254264, 4019.0, 4031.274370613318, 4052.7365721228025, 4054.3310353791294, 4065.4714887065084, 3447.5862896452586, 3891.747036442622, 4058.3912413138264, 4069.552349241581, 4110.506739191256, 4123.340265557496, 4171.374293855946, 4556.154757323289, 4691.906161876744, 4706.846822528434, 4327.805248763813, 4552.658550608718, 5583.497485226349, 5797.942020579824, 5821.592488525763, 1253.1916913590865, 1354.0, 1358.436248956672, 1397.9650508737363, 1405.2903283841595, 695.4992405468055, 739.8335374540685, 800.8027998142813, 837.6607473972929, 862.6764963138822, 3480.7549130810485, 3840.7695538267794, 4060.9339948338816, 4468.32975053738, 4560.928181877256, 4270.61564459194, 4400.581944715247, 4955.82654607087, 4956.7112550737065, 5052.423264135244, 4531.592156705912, 4548.295014272793, 4558.202314594932, 4570.623275896582, 4619.745913974763, 2271.670607761865, 2313.993412865409, 2346.343023953296, 2437.987810060974, 2615.018878478536, 4979.514339962041, 5044.924326135111, 5061.544460998528, 5095.541401273903, 5104.540591346797, 4360.563943605614, 4501.549845015472, 4665.930011049837, 4964.652474326763, 5042.899142017139, 4445.0, 4642.073539481213, 4663.953360466419, 4936.3829521309835, 5106.183010718267, 4063.756174629532, 4695.647826413101, 4730.47964723887, 4908.779104940215, 4936.950630493649, 3924.331454610892, 4063.0136849415067, 4422.447194100738, 4780.545848144421, 4857.8299759508245, 4873.586659868598, 4888.340074089952, 4941.529235382309, 5016.573591244785, 5152.458991805806, 3292.423825830518, 3318.8506517207265, 3457.844397002091, 3503.652552789586, 3509.666581674737, 4527.818887244473, 4670.8365207217585, 4796.616270698406, 4829.444613869309, 5008.223725322581, 3766.642168993941, 4555.1117532080925, 4698.825293676581, 5460.645058071213, 5686.374498805211, 4614.446266448048, 4841.419029716316, 5385.703786291791, 5696.316442026818, 5737.225474561048, 4978.4025916890205, 5274.657147285414, 5383.40782513931, 5446.809361672323, 5636.308218117139, 5482.150266708666, 5651.0, 5662.377138514677, 5712.485876271156, 5723.627964182315, 5114.4374118847645, 5363.7931465421525, 5573.498385145356, 5611.607187496836, 5644.717764111696], "value": 3865.4549805882234, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.8191488298163, 722.9060222171117, 798.6086817459445, 799.8480288745135, 824.2211110500577, 15794.289256983435, 16541.51126398624, 16549.66900661989, 16581.41965031224, 16617.41839035634, 14893.10641361519, 15005.774913376308, 15036.548903532912, 15063.322150503227, 15089.84910150901, 670.3028849995999, 689.9137607799025, 823.3495538524559, 842.3513894301384, 851.6806197675871, 854.7777577829747, 860.3418384935529, 870.2559311788425, 871.7733389318792, 873.5151990645189, 818.4598165210962, 823.1521532821174, 826.4173757500947, 826.8057006603443, 828.5360198288947, 748.9850202995937, 757.2578872704753, 790.5335851847437, 798.7763426240662, 799.6521513141802, 560.52915550937, 565.9830205093854, 607.2348840461007, 608.3521050081649, 608.4615115622662, 14787.482438114723, 14998.850011499855, 15015.249237538188, 15063.397464101423, 15066.623334416618, 14493.71012579753, 14495.782563261455, 14556.417743290256, 14560.490382836655, 14593.854061459355, 11553.711157221052, 11901.94049029758, 11907.821382679267, 11915.165938384316, 11976.94438206449, 3802.5954380796798, 3872.341701910682, 3878.941815872764, 3879.3405121129194, 4004.6996475264473, 106196.0, 108606.0, 108774.0, 108791.0, 114081.85918140796, 11314.547418103184, 11339.829902551468, 11407.885921140849, 11445.656630301104, 11727.768584298696, 22266.332010039725, 23127.653085203736, 23147.189848355225, 23176.884115579312, 23195.53608927812, 8028.71899483527, 8116.391270654724, 8169.30560902318, 8171.591420428955, 8178.427510074357, 3817.1602247505502, 3899.2591407633026, 3956.3669812829808, 4300.817275249331, 4508.45898492183, 3120.625524937022, 3163.936721265608, 3619.7285203609836, 3727.6086010969, 3732.0856390184576, 651.1827656291324, 652.1587152573222, 663.5852592129919, 675.4191395399952, 678.0, 571.4428432278578, 604.9818505444844, 663.7875879718443, 667.8564108716649, 687.8968154776724, 8106.270435660819, 8956.328275379372, 9350.672726454677, 9367.344285899922, 9753.804923901482, 634.8667628283491, 656.6946369938001, 659.4095151897966, 670.4636290967206, 680.8229860236275, 4445.555444455593, 4683.578477937002, 4714.080754252887, 4730.550597693214, 4768.3085952536685, 135216.0, 170365.0, 170601.0, 174848.0, 180326.0, 77984.0, 109171.0, 112517.0, 114388.0, 119963.40018299852, 111534.42327883573, 118073.0, 118655.0, 118934.0, 121388.99999999824, 8988.505632190292, 8990.415622984747, 9057.139571740674, 9118.452892826452, 9197.908020919705, 53938.30308484482, 62685.79113402017, 63743.98816130315, 65655.67172164109, 68417.0, 86284.56857715671, 91383.08616914012, 99247.0, 105877.94122058988, 107884.4605776966, 12923.289219092663, 13041.152325098838, 13152.736945261233, 13196.60410187696, 13686.247256400993, 14736.115833050051, 14848.85151148518, 15209.923950380176, 15325.616859578155, 15595.0, 10351.0, 13895.235762033186, 14140.858591414366, 14570.05294655844, 14625.122492650475, 12462.43919023628, 13462.932685336511, 13825.654358641115, 13924.789377335332, 14019.018668693094, 11699.356535390636, 13303.49877949554, 13441.395137219044, 13675.726485470434, 14005.668866821417, 11061.612843550438, 11405.942970285096, 11515.481803319039, 11575.479103440197, 11860.22908510944, 10847.294925829796, 11385.829212561652, 11697.23967942081, 11711.76576468483, 11763.117766167401], "value": 7821.186902689115, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1565.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 111.0, 116.0, 117.0, 118.0, 117.0, 115.0, 116.0, 117.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 113.0, 112.0, 149.0, 112.0, 112.0, 111.0, 114.0, 141.0, 98.0], "value": 120.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 201.0, 196.0, 207.0, 242.0, 269.0, 196.0, 198.0, 199.0, 230.0], "value": 213.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 61.0, 59.0, 96.0, 62.0, 62.0, 49.0, 65.0, 102.0, 51.0], "value": 69.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 71.0, 87.0, 74.0, 68.0, 70.0, 86.0, 69.0, 75.0, 87.0], "value": 76.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 85.0, 80.0, 116.0, 137.0, 85.0, 88.0, 85.0, 88.0, 87.0], "value": 94.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 171.0, 159.0, 98.0, 116.0, 128.0, 99.0, 98.0, 133.0, 122.0], "value": 124.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 95.0, 94.0, 96.0, 95.0, 94.0, 97.0, 97.0, 94.0, 92.0], "value": 98.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 63.0, 82.0, 80.0, 59.0, 58.0, 57.0, 58.0, 58.0, 60.0], "value": 63.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 73.0, 89.0, 71.0, 68.0, 57.0, 88.0, 102.0, 108.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 74.0, 105.0, 110.0, 112.0, 106.0, 86.0, 89.0, 88.0, 85.0], "value": 96.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 143.0, 168.0, 161.0, 164.0, 141.0, 141.0, 157.0, 201.0, 159.0], "value": 159.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 175.0, 168.0, 187.0, 194.0, 170.0, 163.0, 165.0, 235.0, 179.0], "value": 180.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 71.0, 73.0, 98.0, 88.0, 69.0, 69.0, 70.0, 78.0], "value": 75.2, "unit": "score"}]}]} 05:58:03 INFO - Return code: 0 05:58:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:58:03 ERROR - # TBPL SUCCESS # 05:58:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:58:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:58:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:58:03 INFO - 2016-09-29 05:58:03.305 screenresolution[1667:10387] starting screenresolution argv=screenresolution get 05:58:03 INFO - 2016-09-29 05:58:03.308 screenresolution[1667:10387] Display 0: 1600x1200x32@60 05:58:03 INFO - 2016-09-29 05:58:03.314 screenresolution[1668:10390] starting screenresolution argv=screenresolution list 05:58:03 INFO - Available Modes on Display 0 05:58:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:58:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:58:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:58:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:58:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:58:03 INFO - Intel Iris: 05:58:03 INFO - Chipset Model: Intel Iris 05:58:03 INFO - Type: GPU 05:58:03 INFO - Bus: Built-In 05:58:03 INFO - VRAM (Dynamic, Max): 1536 MB 05:58:03 INFO - Vendor: Intel (0x8086) 05:58:03 INFO - Device ID: 0x0a2e 05:58:03 INFO - Revision ID: 0x0009 05:58:03 INFO - Displays: 05:58:03 INFO - Display: 05:58:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:58:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:58:03 INFO - Display Serial Number: 1600x1200 60 05:58:03 INFO - Main Display: Yes 05:58:03 INFO - Mirror: Off 05:58:03 INFO - Online: Yes 05:58:03 INFO - Rotation: Supported 05:58:03 INFO - Return code: 0 05:58:03 INFO - Running post-action listener: _resource_record_post_action 05:58:03 INFO - [mozharness: 2016-09-29 12:58:03.554046Z] Finished run-tests step (success) 05:58:03 INFO - Running post-run listener: _resource_record_post_run 05:58:03 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 39942144; Write bytes: 710108672; Read time: 434; Write time: 1926 05:58:03 INFO - TinderboxPrint: CPU usage
54.6% 05:58:03 INFO - TinderboxPrint: I/O read bytes / time
39,942,144 / 434 05:58:03 INFO - TinderboxPrint: I/O write bytes / time
710,108,672 / 1,926 05:58:03 INFO - TinderboxPrint: CPU idle
2,195.6 (45.4%) 05:58:03 INFO - TinderboxPrint: CPU system
983.1 (20.3%) 05:58:03 INFO - TinderboxPrint: CPU user
1,659.1 (34.3%) 05:58:03 INFO - TinderboxPrint: Swap in / out
447,492,096 / 0 05:58:03 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 408252928; Write bytes: 410417152; Read time: 29961; Write time: 741 05:58:03 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 35854336; Write bytes: 298237440; Read time: 356; Write time: 1179 05:58:04 INFO - Running post-run listener: _upload_blobber_files 05:58:04 INFO - Blob upload gear active. 05:58:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:58:04 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:58:04 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:58:04 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:58:04 INFO - (blobuploader) - INFO - Open directory for files ... 05:58:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:58:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:58:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:05 INFO - (blobuploader) - INFO - Done attempting. 05:58:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:58:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:58:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:06 INFO - (blobuploader) - INFO - Done attempting. 05:58:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:58:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:58:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:12 INFO - (blobuploader) - INFO - Done attempting. 05:58:12 INFO - (blobuploader) - INFO - Iteration through files over. 05:58:12 INFO - Return code: 0 05:58:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:58:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:58:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5dcea018e8602d8eed709ff48de1ac918d23a1d240de67cb5091bac2cc57cf28bcde3f2be2aed43053adf1e96f04ee3f850b3286ced5d89d638ce03afbc9fc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ac4f9d3a80b8a431c4a22be4939baac82c033683d4b4c170149df8e1a11e08c5f2b98ef4acb9ab913c80111e26a28406110bf308a4699944bc18645619b4ab9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db3e2a43643411003d5e27aaaef9482174fb713b1ac2fb20cf9106a734b95bc22866f67c7dac1ed46efe7d730a46ee37286a2d1a95a63bb7a5edd63e594543ac"} 05:58:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:58:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:58:12 INFO - Contents: 05:58:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5dcea018e8602d8eed709ff48de1ac918d23a1d240de67cb5091bac2cc57cf28bcde3f2be2aed43053adf1e96f04ee3f850b3286ced5d89d638ce03afbc9fc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ac4f9d3a80b8a431c4a22be4939baac82c033683d4b4c170149df8e1a11e08c5f2b98ef4acb9ab913c80111e26a28406110bf308a4699944bc18645619b4ab9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db3e2a43643411003d5e27aaaef9482174fb713b1ac2fb20cf9106a734b95bc22866f67c7dac1ed46efe7d730a46ee37286a2d1a95a63bb7a5edd63e594543ac"} 05:58:12 INFO - Running post-run listener: copy_logs_to_upload_dir 05:58:12 INFO - Copying logs to upload dir... 05:58:12 INFO - mkdir: /builds/slave/test/build/upload/logs 05:58:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.240646 ========= master_lag: 1.24 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-09-29 05:58:13.826012) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 05:58:13.830391) ========= 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.Ls0ekER23f/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.mEd695bABe/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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/f5dcea018e8602d8eed709ff48de1ac918d23a1d240de67cb5091bac2cc57cf28bcde3f2be2aed43053adf1e96f04ee3f850b3286ced5d89d638ce03afbc9fc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ac4f9d3a80b8a431c4a22be4939baac82c033683d4b4c170149df8e1a11e08c5f2b98ef4acb9ab913c80111e26a28406110bf308a4699944bc18645619b4ab9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db3e2a43643411003d5e27aaaef9482174fb713b1ac2fb20cf9106a734b95bc22866f67c7dac1ed46efe7d730a46ee37286a2d1a95a63bb7a5edd63e594543ac"} build_url:https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010948 build_url: 'https://queue.taskcluster.net/v1/task/Bsb08oFwRAuFXdCZwpjl-A/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/f5dcea018e8602d8eed709ff48de1ac918d23a1d240de67cb5091bac2cc57cf28bcde3f2be2aed43053adf1e96f04ee3f850b3286ced5d89d638ce03afbc9fc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ac4f9d3a80b8a431c4a22be4939baac82c033683d4b4c170149df8e1a11e08c5f2b98ef4acb9ab913c80111e26a28406110bf308a4699944bc18645619b4ab9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db3e2a43643411003d5e27aaaef9482174fb713b1ac2fb20cf9106a734b95bc22866f67c7dac1ed46efe7d730a46ee37286a2d1a95a63bb7a5edd63e594543ac"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 05:58:13.870325) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 05:58:13.870658) ========= 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.Ls0ekER23f/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.mEd695bABe/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.005053 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 05:58:13.918894) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 05:58:13.919226) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 05:58:13.919542) ========= ========= Total master_lag: 1.91 =========