builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0033 starttime: 1480429491.39 results: success (0) buildid: 20161129030222 builduid: bc89033538dd4d76a5cd6a607395a896 revision: f8107cf961444a8d7fdc2c0f446238af9893f875 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 06:24:51.390285) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 06:24:51.390803) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 06:24:51.391082) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 06:24:51.417273) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 06:24:51.417653) ========= 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.cL40Sj66az/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.X3E8bBveI7/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-29 06:24:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.9M=0.001s 2016-11-29 06:24:52 (14.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.590247 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 06:24:52.031397) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 06:24:52.031770) ========= 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.cL40Sj66az/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.X3E8bBveI7/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.026766 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 06:24:52.079732) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 06:24:52.080035) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f8107cf961444a8d7fdc2c0f446238af9893f875 --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 f8107cf961444a8d7fdc2c0f446238af9893f875 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cL40Sj66az/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.X3E8bBveI7/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-29 06:24:52,174 truncating revision to first 12 chars 2016-11-29 06:24:52,174 Setting DEBUG logging. 2016-11-29 06:24:52,174 attempt 1/10 2016-11-29 06:24:52,174 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f8107cf96144?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-29 06:24:53,201 unpacking tar archive at: mozilla-central-f8107cf96144/testing/mozharness/ program finished with exit code 0 elapsedTime=1.300687 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 06:24:53.468406) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 06:24:53.468718) ========= script_repo_revision: f8107cf961444a8d7fdc2c0f446238af9893f875 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 06:24:53.469098) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 06:24:53.469369) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 06:24:53.496519) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-29 06:24:53.496917) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cL40Sj66az/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.X3E8bBveI7/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 06:24:53 INFO - MultiFileLogger online at 20161129 06:24:53 in /builds/slave/test 06:24:53 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 06:24:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:24:53 INFO - {'append_to_log': False, 06:24:53 INFO - 'base_work_dir': '/builds/slave/test', 06:24:53 INFO - 'blob_upload_branch': 'Firefox', 06:24:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:24:53 INFO - 'branch': 'Firefox', 06:24:53 INFO - 'buildbot_json_path': 'buildprops.json', 06:24:53 INFO - 'config_files': ('talos/mac_config.py',), 06:24:53 INFO - 'default_actions': ('clobber', 06:24:53 INFO - 'read-buildbot-config', 06:24:53 INFO - 'download-and-extract', 06:24:53 INFO - 'populate-webroot', 06:24:53 INFO - 'create-virtualenv', 06:24:53 INFO - 'install', 06:24:53 INFO - 'run-tests'), 06:24:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:24:53 INFO - 'download_minidump_stackwalk': True, 06:24:53 INFO - 'download_symbols': 'ondemand', 06:24:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:24:53 INFO - 'tooltool.py': '/tools/tooltool.py', 06:24:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:24:53 INFO - '/tools/misc-python/virtualenv.py')}, 06:24:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:24:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:24:53 INFO - 'installer_path': 'installer.exe', 06:24:53 INFO - 'log_level': 'info', 06:24:53 INFO - 'log_name': 'talos', 06:24:53 INFO - 'log_to_console': True, 06:24:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:24:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:24:53 INFO - 'opt_config_files': (), 06:24:53 INFO - 'pip_index': False, 06:24:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:24:53 INFO - 'cmd': ('bash', 06:24:53 INFO - '-c', 06:24:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:24:53 INFO - 'enabled': True, 06:24:53 INFO - 'halt_on_failure': False, 06:24:53 INFO - 'name': 'check_screen_resolution'},), 06:24:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:24:53 INFO - 'cmd': ('bash', 06:24:53 INFO - '-c', 06:24:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:24:53 INFO - 'enabled': True, 06:24:53 INFO - 'halt_on_failure': False, 06:24:53 INFO - 'name': 'check_screen_resolution'},), 06:24:53 INFO - 'run_cmd_checks_enabled': True, 06:24:53 INFO - 'sps_profile': False, 06:24:53 INFO - 'sps_profile_interval': 0, 06:24:53 INFO - 'suite': 'dromaeojs-e10s', 06:24:53 INFO - 'system_bits': '32', 06:24:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:24:53 INFO - 'title': 't-yosemite-r7-0033', 06:24:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:24:53 INFO - 'use_talos_json': True, 06:24:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:24:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:24:53 INFO - 'work_dir': 'build'} 06:24:53 INFO - [mozharness: 2016-11-29 14:24:53.704884Z] Running clobber step. 06:24:53 INFO - Running pre-action listener: _resource_record_pre_action 06:24:53 INFO - Running main action method: clobber 06:24:53 INFO - rmtree: /builds/slave/test/build 06:24:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:24:56 INFO - Running post-action listener: _resource_record_post_action 06:24:56 INFO - [mozharness: 2016-11-29 14:24:56.198986Z] Finished clobber step (success) 06:24:56 INFO - [mozharness: 2016-11-29 14:24:56.199106Z] Running read-buildbot-config step. 06:24:56 INFO - Running pre-action listener: _resource_record_pre_action 06:24:56 INFO - Running main action method: read_buildbot_config 06:24:56 INFO - Using buildbot properties: 06:24:56 INFO - { 06:24:56 INFO - "project": "", 06:24:56 INFO - "product": "firefox", 06:24:56 INFO - "script_repo_revision": "production", 06:24:56 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:24:56 INFO - "repository": "", 06:24:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:24:56 INFO - "buildid": "20161129030222", 06:24:56 INFO - "stage_platform": "macosx64", 06:24:56 INFO - "basedir": "/builds/slave/test", 06:24:56 INFO - "nightly_build": "True", 06:24:56 INFO - "buildnumber": 0, 06:24:56 INFO - "slavename": "t-yosemite-r7-0033", 06:24:56 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 06:24:56 INFO - "platform": "yosemite_r7", 06:24:56 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:24:56 INFO - "branch": "mozilla-central", 06:24:56 INFO - "repo_path": "mozilla-central", 06:24:56 INFO - "revision": "f8107cf961444a8d7fdc2c0f446238af9893f875", 06:24:56 INFO - "pgo_build": "False", 06:24:56 INFO - "builduid": "bc89033538dd4d76a5cd6a607395a896", 06:24:56 INFO - "slavebuilddir": "test" 06:24:56 INFO - } 06:24:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 06:24:56 INFO - Running post-action listener: _resource_record_post_action 06:24:56 INFO - [mozharness: 2016-11-29 14:24:56.202527Z] Finished read-buildbot-config step (success) 06:24:56 INFO - [mozharness: 2016-11-29 14:24:56.202659Z] Running download-and-extract step. 06:24:56 INFO - Running pre-action listener: _resource_record_pre_action 06:24:56 INFO - Running main action method: download_and_extract 06:24:56 INFO - mkdir: /builds/slave/test/build/tests 06:24:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:24:56 INFO - trying https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 06:24:56 INFO - Downloading https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 06:24:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 06:24:57 INFO - Downloaded 1199 bytes. 06:24:57 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 06:24:57 INFO - Using the following test package requirements: 06:24:57 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 06:24:57 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:24:57 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 06:24:57 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:24:57 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 06:24:57 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:24:57 INFO - u'jsshell-mac.zip'], 06:24:57 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:24:57 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 06:24:57 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 06:24:57 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:24:57 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 06:24:57 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:24:57 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 06:24:57 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:24:57 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 06:24:57 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:24:57 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 06:24:57 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:24:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 06:24:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:24:57 INFO - Fetch https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 06:24:57 INFO - Http code: 200 06:24:57 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 06:24:57 INFO - x-amz-cf-id: Rs_ysDvipLVgYVpoSw5ypnBfvq0WoqtAua_-1SURmZUzcgysjhdH1w== 06:24:57 INFO - x-amz-version-id: mKaXAMhoB.d_UKUk18R6UJya6l2qO7YN 06:24:58 INFO - Expected file size: 16284943 06:24:58 INFO - Obtained file size: 16284943 06:25:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:25:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 06:25:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:25:04 INFO - Fetch https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 06:25:05 INFO - Http code: 200 06:25:05 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 06:25:05 INFO - x-amz-cf-id: SegNjesyLOp1RTXx2NNFF4bh5VUWbISCNjPqCxVXfiGLrPXvWXH_BQ== 06:25:05 INFO - x-amz-version-id: mKaXAMhoB.d_UKUk18R6UJya6l2qO7YN 06:25:05 INFO - Expected file size: 16284943 06:25:05 INFO - Obtained file size: 16284943 06:25:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 06:25:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:25:11 INFO - Fetch https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 06:25:12 INFO - Http code: 200 06:25:12 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 06:25:12 INFO - x-amz-cf-id: 4o9iKkXkuJsy2sXvJe2lR87LhAP_-fI1etNqZOaMXchwIoYBoBoHEA== 06:25:12 INFO - x-amz-version-id: sr80JKp75alrPSvC.2LHQAEoq7F3pUH4 06:25:12 INFO - Expected file size: 14409702 06:25:12 INFO - Obtained file size: 14409702 06:25:13 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')]} 06:25:13 INFO - trying https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:25:13 INFO - Downloading https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 06:25:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 06:25:17 INFO - Downloaded 107718638 bytes. 06:25:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:25:17 INFO - mkdir: /builds/slave/test/properties 06:25:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:25:17 INFO - Writing to file /builds/slave/test/properties/build_url 06:25:17 INFO - Contents: 06:25:17 INFO - build_url:https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:25:17 INFO - Running post-action listener: _resource_record_post_action 06:25:17 INFO - Running post-action listener: set_extra_try_arguments 06:25:17 INFO - [mozharness: 2016-11-29 14:25:17.671727Z] Finished download-and-extract step (success) 06:25:17 INFO - [mozharness: 2016-11-29 14:25:17.671842Z] Running populate-webroot step. 06:25:17 INFO - Running pre-action listener: _resource_record_pre_action 06:25:17 INFO - Running main action method: populate_webroot 06:25:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:25:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:25:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:25:17 INFO - '--tppagecycles', 06:25:17 INFO - '7'], 06:25:17 INFO - 'tests': ['tsvgm']}}, 06:25:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:25:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:25:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:25:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:25:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:25:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:25:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:25:17 INFO - 'talos_options': ['--disable-e10s'], 06:25:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:25:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:25:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:25:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:25:17 INFO - 'talos_options': ['--disable-e10s'], 06:25:17 INFO - 'tests': ['damp', 'tps']}, 06:25:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:25:17 INFO - 'tests': ['damp', 'tps']}, 06:25:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:25:17 INFO - 'tests': ['dromaeo_dom']}, 06:25:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:25:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:25:17 INFO - 'tests': ['basic_compositor_video']}, 06:25:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:25:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:25:17 INFO - 'tests': ['a11yr', 06:25:17 INFO - 'ts_paint', 06:25:17 INFO - 'tpaint', 06:25:17 INFO - 'sessionrestore', 06:25:17 INFO - 'sessionrestore_no_auto_restore', 06:25:17 INFO - 'tabpaint']}, 06:25:17 INFO - 'other-e10s': {'tests': ['a11yr', 06:25:17 INFO - 'ts_paint', 06:25:17 INFO - 'tpaint', 06:25:17 INFO - 'sessionrestore', 06:25:17 INFO - 'sessionrestore_no_auto_restore', 06:25:17 INFO - 'tabpaint']}, 06:25:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:25:17 INFO - 'ts_paint', 06:25:17 INFO - 'tpaint', 06:25:17 INFO - 'sessionrestore', 06:25:17 INFO - 'sessionrestore_no_auto_restore', 06:25:17 INFO - 'tabpaint']}, 06:25:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:25:17 INFO - 'ts_paint', 06:25:17 INFO - 'tpaint', 06:25:17 INFO - 'sessionrestore', 06:25:17 INFO - 'sessionrestore_no_auto_restore', 06:25:17 INFO - 'tabpaint']}, 06:25:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:25:17 INFO - 'tests': ['a11yr', 06:25:17 INFO - 'ts_paint', 06:25:17 INFO - 'tpaint', 06:25:17 INFO - 'sessionrestore', 06:25:17 INFO - 'sessionrestore_no_auto_restore', 06:25:17 INFO - 'tabpaint']}, 06:25:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:25:17 INFO - 'tests': ['a11yr', 06:25:17 INFO - 'ts_paint', 06:25:17 INFO - 'tpaint', 06:25:17 INFO - 'sessionrestore', 06:25:17 INFO - 'sessionrestore_no_auto_restore', 06:25:17 INFO - 'tabpaint']}, 06:25:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:25:17 INFO - 'tests': ['tsvgx', 06:25:17 INFO - 'tsvgr_opacity', 06:25:17 INFO - 'tart', 06:25:17 INFO - 'tscrollx', 06:25:17 INFO - 'cart', 06:25:17 INFO - 'tsvg_static']}, 06:25:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:25:17 INFO - 'tsvgr_opacity', 06:25:17 INFO - 'tart', 06:25:17 INFO - 'tscrollx', 06:25:17 INFO - 'cart', 06:25:17 INFO - 'tsvg_static']}, 06:25:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:25:17 INFO - 'talos_options': ['--disable-e10s'], 06:25:17 INFO - 'tests': ['tp5o']}, 06:25:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:25:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:25:17 INFO - 'talos_options': ['--disable-e10s', 06:25:17 INFO - '--xperf_path', 06:25:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:25:17 INFO - 'tests': ['tp5n']}, 06:25:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:25:17 INFO - 'talos_options': ['--xperf_path', 06:25:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:25:17 INFO - 'tests': ['tp5n']}}, 06:25:17 INFO - 'talos.zip': {'path': '', 06:25:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:25:17 INFO - Running post-action listener: _resource_record_post_action 06:25:17 INFO - [mozharness: 2016-11-29 14:25:17.689023Z] Finished populate-webroot step (success) 06:25:17 INFO - [mozharness: 2016-11-29 14:25:17.689122Z] Running create-virtualenv step. 06:25:17 INFO - Running pre-action listener: _resource_record_pre_action 06:25:17 INFO - Running main action method: create_virtualenv 06:25:17 INFO - Creating virtualenv /builds/slave/test/build/venv 06:25:17 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 06:25:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:25:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:25:17 INFO - Using real prefix '/tools/python27' 06:25:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:25:18 INFO - Installing distribute.............................................................................................................................................................................................done. 06:25:21 INFO - Installing pip.................done. 06:25:21 INFO - Return code: 0 06:25:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:25:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:25:21 INFO - Reading from file tmpfile_stdout 06:25:21 INFO - Output received: 06:25:21 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) 06:25:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:25:21 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')]} 06:25:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:21 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')]} 06:25:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:21 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 0x106a1d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa44acbb0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106833d30>, '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 0x10669adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10682bb20>, '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.cL40Sj66az/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.X3E8bBveI7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 06:25:21 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 06:25:21 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 06:25:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cL40Sj66az/Render', 06:25:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:21 INFO - 'HOME': '/Users/cltbld', 06:25:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:21 INFO - 'LOGNAME': 'cltbld', 06:25:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:21 INFO - 'MOZ_NO_REMOTE': '1', 06:25:21 INFO - 'NO_EM_RESTART': '1', 06:25:21 INFO - 'PAGER': '/bin/cat', 06:25:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:21 INFO - 'PWD': '/builds/slave/test', 06:25:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:21 INFO - 'SHELL': '/bin/bash', 06:25:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X3E8bBveI7/Listeners', 06:25:21 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 06:25:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:21 INFO - 'USER': 'cltbld', 06:25:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:21 INFO - 'XPC_FLAGS': '0x0', 06:25:21 INFO - 'XPC_SERVICE_NAME': '0', 06:25:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:21 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 06:25:21 INFO - Cleaning up... 06:25:21 INFO - Return code: 0 06:25:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:25:21 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')]} 06:25:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:21 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')]} 06:25:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:21 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 0x106a1d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa44acbb0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106833d30>, '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 0x10669adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10682bb20>, '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.cL40Sj66az/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.X3E8bBveI7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 06:25:21 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 06:25:21 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 06:25:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cL40Sj66az/Render', 06:25:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:21 INFO - 'HOME': '/Users/cltbld', 06:25:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:21 INFO - 'LOGNAME': 'cltbld', 06:25:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:21 INFO - 'MOZ_NO_REMOTE': '1', 06:25:21 INFO - 'NO_EM_RESTART': '1', 06:25:21 INFO - 'PAGER': '/bin/cat', 06:25:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:21 INFO - 'PWD': '/builds/slave/test', 06:25:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:21 INFO - 'SHELL': '/bin/bash', 06:25:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X3E8bBveI7/Listeners', 06:25:21 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 06:25:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:21 INFO - 'USER': 'cltbld', 06:25:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:21 INFO - 'XPC_FLAGS': '0x0', 06:25:21 INFO - 'XPC_SERVICE_NAME': '0', 06:25:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:22 INFO - Downloading/unpacking psutil>=3.1.1 06:25:22 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 06:25:22 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 06:25:22 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 06:25:22 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 06:25:22 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 06:25:22 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 06:25:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:25:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:25:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:25:25 INFO - Installing collected packages: psutil 06:25:25 INFO - Running setup.py install for psutil 06:25:26 INFO - building 'psutil._psutil_osx' extension 06:25:26 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 06:25:26 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 06:25:26 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 06:25:26 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 06:25:26 INFO - building 'psutil._psutil_posix' extension 06:25:26 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 06:25:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:25:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:25:26 INFO - ^ 06:25:26 INFO - 1 warning generated. 06:25:26 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 06:25:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:25:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:25:27 INFO - Successfully installed psutil 06:25:27 INFO - Cleaning up... 06:25:27 INFO - Return code: 0 06:25:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:25: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')]} 06:25:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25: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')]} 06:25:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:27 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 0x106a1d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa44acbb0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106833d30>, '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 0x10669adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10682bb20>, '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.cL40Sj66az/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.X3E8bBveI7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 06:25:27 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 06:25:27 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 06:25:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cL40Sj66az/Render', 06:25:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:27 INFO - 'HOME': '/Users/cltbld', 06:25:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:27 INFO - 'LOGNAME': 'cltbld', 06:25:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:27 INFO - 'MOZ_NO_REMOTE': '1', 06:25:27 INFO - 'NO_EM_RESTART': '1', 06:25:27 INFO - 'PAGER': '/bin/cat', 06:25:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:27 INFO - 'PWD': '/builds/slave/test', 06:25:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:27 INFO - 'SHELL': '/bin/bash', 06:25:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X3E8bBveI7/Listeners', 06:25:27 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 06:25:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:27 INFO - 'USER': 'cltbld', 06:25:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:27 INFO - 'XPC_FLAGS': '0x0', 06:25:27 INFO - 'XPC_SERVICE_NAME': '0', 06:25:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:25:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:25:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:25:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:25:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:25:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:25:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:25:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:25:30 INFO - Installing collected packages: mozsystemmonitor 06:25:30 INFO - Running setup.py install for mozsystemmonitor 06:25:30 INFO - Successfully installed mozsystemmonitor 06:25:30 INFO - Cleaning up... 06:25:30 INFO - Return code: 0 06:25:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:30 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 0x106a1d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa44acbb0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106833d30>, '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 0x10669adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10682bb20>, '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.cL40Sj66az/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.X3E8bBveI7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 06:25:30 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 06:25:30 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 06:25:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cL40Sj66az/Render', 06:25:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:30 INFO - 'HOME': '/Users/cltbld', 06:25:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:30 INFO - 'LOGNAME': 'cltbld', 06:25:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:30 INFO - 'MOZ_NO_REMOTE': '1', 06:25:30 INFO - 'NO_EM_RESTART': '1', 06:25:30 INFO - 'PAGER': '/bin/cat', 06:25:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:30 INFO - 'PWD': '/builds/slave/test', 06:25:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:30 INFO - 'SHELL': '/bin/bash', 06:25:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X3E8bBveI7/Listeners', 06:25:30 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 06:25:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:30 INFO - 'USER': 'cltbld', 06:25:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:30 INFO - 'XPC_FLAGS': '0x0', 06:25:30 INFO - 'XPC_SERVICE_NAME': '0', 06:25:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:30 INFO - Downloading/unpacking jsonschema==2.5.1 06:25:30 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 06:25:30 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 06:25:30 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 06:25:30 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 06:25:30 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 06:25:30 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 06:25:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:25:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:25: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 06:25: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 06:25: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 06:25: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 06:25: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 06:25: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 06:25:33 INFO - Downloading functools32-3.2.3-2.tar.gz 06:25:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:25:33 INFO - warning: no files found matching '*.txt' 06:25:33 INFO - no previously-included directories found matching 'build' 06:25:33 INFO - no previously-included directories found matching 'dist' 06:25:33 INFO - no previously-included directories found matching '.git*' 06:25:33 INFO - Installing collected packages: jsonschema, functools32 06:25:33 INFO - Running setup.py install for functools32 06:25:33 INFO - warning: no files found matching '*.txt' 06:25:33 INFO - no previously-included directories found matching 'build' 06:25:33 INFO - no previously-included directories found matching 'dist' 06:25:33 INFO - no previously-included directories found matching '.git*' 06:25:33 INFO - Successfully installed jsonschema functools32 06:25:33 INFO - Cleaning up... 06:25:33 INFO - Return code: 0 06:25:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:25: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')]} 06:25:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25: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')]} 06:25:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25: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', '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 0x106a1d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa44acbb0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106833d30>, '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 0x10669adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10682bb20>, '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.cL40Sj66az/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.X3E8bBveI7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 06:25: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:25: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 functools32==3.2.3-2 06:25:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cL40Sj66az/Render', 06:25:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:33 INFO - 'HOME': '/Users/cltbld', 06:25:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:33 INFO - 'LOGNAME': 'cltbld', 06:25:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:33 INFO - 'MOZ_NO_REMOTE': '1', 06:25:33 INFO - 'NO_EM_RESTART': '1', 06:25:33 INFO - 'PAGER': '/bin/cat', 06:25:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:33 INFO - 'PWD': '/builds/slave/test', 06:25:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:33 INFO - 'SHELL': '/bin/bash', 06:25:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X3E8bBveI7/Listeners', 06:25:33 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 06:25:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:33 INFO - 'USER': 'cltbld', 06:25:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:33 INFO - 'XPC_FLAGS': '0x0', 06:25:33 INFO - 'XPC_SERVICE_NAME': '0', 06:25:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:25:34 INFO - Cleaning up... 06:25:34 INFO - Return code: 0 06:25:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:25:34 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')]} 06:25:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:34 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')]} 06:25:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:34 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 0x106a1d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa44acbb0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106833d30>, '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 0x10669adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10682bb20>, '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.cL40Sj66az/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.X3E8bBveI7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 06:25:34 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 06:25:34 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 06:25:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cL40Sj66az/Render', 06:25:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:34 INFO - 'HOME': '/Users/cltbld', 06:25:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:34 INFO - 'LOGNAME': 'cltbld', 06:25:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:34 INFO - 'MOZ_NO_REMOTE': '1', 06:25:34 INFO - 'NO_EM_RESTART': '1', 06:25:34 INFO - 'PAGER': '/bin/cat', 06:25:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:34 INFO - 'PWD': '/builds/slave/test', 06:25:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:34 INFO - 'SHELL': '/bin/bash', 06:25:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X3E8bBveI7/Listeners', 06:25:34 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 06:25:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:34 INFO - 'USER': 'cltbld', 06:25:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:34 INFO - 'XPC_FLAGS': '0x0', 06:25:34 INFO - 'XPC_SERVICE_NAME': '0', 06:25:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:34 INFO - Downloading/unpacking blobuploader==1.2.4 06:25:34 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 06:25:34 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 06:25:34 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 06:25:34 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 06:25:34 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 06:25:34 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 06:25:36 INFO - Downloading blobuploader-1.2.4.tar.gz 06:25:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:25:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:25: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 06:25: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 06:25: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 06:25: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 06:25: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 06:25: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 06:25:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:25:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:25: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 06:25: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 06:25: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 06:25: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 06:25: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 06:25: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 06:25:38 INFO - Downloading docopt-0.6.1.tar.gz 06:25:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:25:38 INFO - Installing collected packages: blobuploader, requests, docopt 06:25:38 INFO - Running setup.py install for blobuploader 06:25:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:25:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:25:38 INFO - Running setup.py install for requests 06:25:38 INFO - Running setup.py install for docopt 06:25:38 INFO - Successfully installed blobuploader requests docopt 06:25:38 INFO - Cleaning up... 06:25:39 INFO - Return code: 0 06:25:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:25:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:39 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 0x106a1d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa44acbb0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106833d30>, '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 0x10669adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10682bb20>, '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.cL40Sj66az/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.X3E8bBveI7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 06:25:39 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 06:25:39 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 06:25:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cL40Sj66az/Render', 06:25:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:39 INFO - 'HOME': '/Users/cltbld', 06:25:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:39 INFO - 'LOGNAME': 'cltbld', 06:25:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:39 INFO - 'MOZ_NO_REMOTE': '1', 06:25:39 INFO - 'NO_EM_RESTART': '1', 06:25:39 INFO - 'PAGER': '/bin/cat', 06:25:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:39 INFO - 'PWD': '/builds/slave/test', 06:25:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:39 INFO - 'SHELL': '/bin/bash', 06:25:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X3E8bBveI7/Listeners', 06:25:39 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 06:25:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:39 INFO - 'USER': 'cltbld', 06:25:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:39 INFO - 'XPC_FLAGS': '0x0', 06:25:39 INFO - 'XPC_SERVICE_NAME': '0', 06:25:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:25:39 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-AcY62W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:25:39 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-X69tAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:25:39 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-ry5wHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:25:39 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-vTXg3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:25:39 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-p2dNgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:25:39 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-GyyG6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:25:39 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-bnJXJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:25:40 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-nbzhsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:25:40 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-JaqYRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:25:40 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-lrKdgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:25:40 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-QvZSXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:25:40 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-veqf1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:25:40 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-IpH7Xv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:25:40 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-kIRflP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:25:40 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-xUz2j9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:25:40 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-oYCUoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:25:40 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-eRkS7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:25:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:25:41 INFO - Running setup.py install for manifestparser 06:25:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:25:41 INFO - Running setup.py install for mozcrash 06:25:41 INFO - Running setup.py install for mozdebug 06:25:41 INFO - Running setup.py install for mozdevice 06:25:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:25:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:25:41 INFO - Running setup.py install for mozfile 06:25:41 INFO - Running setup.py install for mozhttpd 06:25:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:25:41 INFO - Running setup.py install for mozinfo 06:25:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:25:42 INFO - Running setup.py install for mozInstall 06:25:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:25:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:25:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:25:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:25:42 INFO - Running setup.py install for mozleak 06:25:42 INFO - Running setup.py install for mozlog 06:25:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:25:42 INFO - Running setup.py install for moznetwork 06:25:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:25:42 INFO - Running setup.py install for mozprocess 06:25:42 INFO - Running setup.py install for mozprofile 06:25:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:25:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:25:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:25:43 INFO - Running setup.py install for mozrunner 06:25:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:25:43 INFO - Running setup.py install for mozscreenshot 06:25:43 INFO - Running setup.py install for moztest 06:25:43 INFO - Running setup.py install for mozversion 06:25:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:25:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:25:43 INFO - Cleaning up... 06:25:43 INFO - Return code: 0 06:25:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:25:43 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')]} 06:25:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:43 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')]} 06:25:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:43 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 0x106a1d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa44acbb0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106833d30>, '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 0x10669adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10682bb20>, '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.cL40Sj66az/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.X3E8bBveI7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 06:25:43 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 06:25:43 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 06:25:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cL40Sj66az/Render', 06:25:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:43 INFO - 'HOME': '/Users/cltbld', 06:25:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:43 INFO - 'LOGNAME': 'cltbld', 06:25:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:43 INFO - 'MOZ_NO_REMOTE': '1', 06:25:43 INFO - 'NO_EM_RESTART': '1', 06:25:43 INFO - 'PAGER': '/bin/cat', 06:25:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:43 INFO - 'PWD': '/builds/slave/test', 06:25:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:43 INFO - 'SHELL': '/bin/bash', 06:25:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X3E8bBveI7/Listeners', 06:25:43 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 06:25:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:43 INFO - 'USER': 'cltbld', 06:25:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:43 INFO - 'XPC_FLAGS': '0x0', 06:25:43 INFO - 'XPC_SERVICE_NAME': '0', 06:25:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:25:43 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-cDSeBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:25:44 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)) 06:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:25:44 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-xdwZcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:25:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:25:44 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-9RQjEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:25:44 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)) 06:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:25:44 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-U7wS1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:25:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 06:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:25:44 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-OfwXZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:25:44 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)) 06:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:25:44 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-uEQJcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:25:44 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)) 06:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:25:44 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-YDqnYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:25:44 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)) 06:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:25:44 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-4cX3V7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:25:44 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)) 06:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:25:44 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-HLsjzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:25:44 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)) 06:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:25:44 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-C6RJFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:25:45 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)) 06:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:25:45 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-iAHvIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:25:45 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)) 06:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:25:45 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-p4NTq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:25:45 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)) 06:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:25:45 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-aa0XBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:25:45 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)) 06:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:25:45 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-U0PvMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:25:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:25:45 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-DkAHTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:25:45 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)) 06:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:25:45 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-7VaHu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:25:45 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)) 06:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:25:45 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-bR6dhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:25:45 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)) 06:25:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:25:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:25:45 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)) 06:25:45 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:25:45 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:25:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:25:45 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 06:25:45 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 06:25:45 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 06:25:45 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 06:25:45 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 06:25:45 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 06:25:48 INFO - Downloading blessings-1.6.tar.gz 06:25:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:25:48 INFO - Installing collected packages: blessings 06:25:48 INFO - Running setup.py install for blessings 06:25:48 INFO - Successfully installed blessings 06:25:48 INFO - Cleaning up... 06:25:48 INFO - Return code: 0 06:25:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:25:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:25:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:25:48 INFO - Reading from file tmpfile_stdout 06:25:48 INFO - Current package versions: 06:25:48 INFO - blessings == 1.6 06:25:48 INFO - blobuploader == 1.2.4 06:25:48 INFO - docopt == 0.6.1 06:25:48 INFO - functools32 == 3.2.3-2 06:25:48 INFO - jsonschema == 2.5.1 06:25:48 INFO - manifestparser == 1.1 06:25:48 INFO - mozInstall == 1.12 06:25:48 INFO - mozcrash == 1.0 06:25:48 INFO - mozdebug == 0.1 06:25:48 INFO - mozdevice == 0.49 06:25:48 INFO - mozfile == 1.2 06:25:48 INFO - mozhttpd == 0.7 06:25:48 INFO - mozinfo == 0.9 06:25:48 INFO - mozleak == 0.1 06:25:48 INFO - mozlog == 3.4 06:25:48 INFO - moznetwork == 0.27 06:25:48 INFO - mozprocess == 0.23 06:25:48 INFO - mozprofile == 0.28 06:25:48 INFO - mozrunner == 6.13 06:25:48 INFO - mozscreenshot == 0.1 06:25:48 INFO - mozsystemmonitor == 0.3 06:25:48 INFO - moztest == 0.7 06:25:48 INFO - mozversion == 1.4 06:25:48 INFO - psutil == 3.1.1 06:25:48 INFO - requests == 1.2.3 06:25:48 INFO - wsgiref == 0.1.2 06:25:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:25:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:48 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 0x106a1d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa44acbb0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106833d30>, '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 0x10669adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10682bb20>, '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.cL40Sj66az/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.X3E8bBveI7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 06:25:48 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 06:25:48 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 06:25:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cL40Sj66az/Render', 06:25:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:48 INFO - 'HOME': '/Users/cltbld', 06:25:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:48 INFO - 'LOGNAME': 'cltbld', 06:25:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:48 INFO - 'MOZ_NO_REMOTE': '1', 06:25:48 INFO - 'NO_EM_RESTART': '1', 06:25:48 INFO - 'PAGER': '/bin/cat', 06:25:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:48 INFO - 'PWD': '/builds/slave/test', 06:25:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:48 INFO - 'SHELL': '/bin/bash', 06:25:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X3E8bBveI7/Listeners', 06:25:48 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 06:25:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:48 INFO - 'USER': 'cltbld', 06:25:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:48 INFO - 'XPC_FLAGS': '0x0', 06:25:48 INFO - 'XPC_SERVICE_NAME': '0', 06:25:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:49 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)) 06:25:49 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)) 06:25:49 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)) 06:25:49 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)) 06:25:49 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)) 06:25:49 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)) 06:25:49 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)) 06:25:49 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)) 06:25:49 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)) 06:25:49 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)) 06:25:49 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)) 06:25:49 INFO - Cleaning up... 06:25:49 INFO - Return code: 0 06:25:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:25:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106a1d3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106993570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa44acbb0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106833d30>, '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 0x10669adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10682bb20>, '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.cL40Sj66az/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.X3E8bBveI7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 06:25:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:25:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:25:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cL40Sj66az/Render', 06:25:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:49 INFO - 'HOME': '/Users/cltbld', 06:25:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:49 INFO - 'LOGNAME': 'cltbld', 06:25:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:49 INFO - 'MOZ_NO_REMOTE': '1', 06:25:49 INFO - 'NO_EM_RESTART': '1', 06:25:49 INFO - 'PAGER': '/bin/cat', 06:25:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:49 INFO - 'PWD': '/builds/slave/test', 06:25:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:49 INFO - 'SHELL': '/bin/bash', 06:25:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X3E8bBveI7/Listeners', 06:25:49 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 06:25:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:49 INFO - 'USER': 'cltbld', 06:25:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:49 INFO - 'XPC_FLAGS': '0x0', 06:25:49 INFO - 'XPC_SERVICE_NAME': '0', 06:25:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:25:49 INFO - Cleaning up... 06:25:49 INFO - Return code: 0 06:25:49 INFO - Running post-action listener: _resource_record_post_action 06:25:49 INFO - Running post-action listener: _start_resource_monitoring 06:25:49 INFO - Starting resource monitoring. 06:25:49 INFO - [mozharness: 2016-11-29 14:25:49.543466Z] Finished create-virtualenv step (success) 06:25:49 INFO - [mozharness: 2016-11-29 14:25:49.544543Z] Running install step. 06:25:49 INFO - Running pre-action listener: _resource_record_pre_action 06:25:49 INFO - Running main action method: install 06:25:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:25:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:25:49 INFO - Reading from file tmpfile_stdout 06:25:49 INFO - Detecting whether we're running mozinstall >=1.0... 06:25:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:25:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:25:49 INFO - Reading from file tmpfile_stdout 06:25:49 INFO - Output received: 06:25:49 INFO - Usage: mozinstall [options] installer 06:25:49 INFO - Options: 06:25:49 INFO - -h, --help show this help message and exit 06:25:49 INFO - -d DEST, --destination=DEST 06:25:49 INFO - Directory to install application into. [default: 06:25:49 INFO - "/builds/slave/test"] 06:25:49 INFO - --app=APP Application being installed. [default: firefox] 06:25:49 INFO - mkdir: /builds/slave/test/build/application 06:25:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:25:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:26:22 INFO - Reading from file tmpfile_stdout 06:26:22 INFO - Output received: 06:26:22 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 06:26:22 INFO - Running post-action listener: _resource_record_post_action 06:26:22 INFO - [mozharness: 2016-11-29 14:26:22.881948Z] Finished install step (success) 06:26:22 INFO - [mozharness: 2016-11-29 14:26:22.882116Z] Running run-tests step. 06:26:22 INFO - Running pre-action listener: _resource_record_pre_action 06:26:22 INFO - Running main action method: run_tests 06:26:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:26:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:26:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:26:22 INFO - 2016-11-29 06:26:22.896 screenresolution[1632:8161] starting screenresolution argv=screenresolution get 06:26:22 INFO - 2016-11-29 06:26:22.899 screenresolution[1632:8161] Display 0: 1600x1200x32@60 06:26:22 INFO - 2016-11-29 06:26:22.905 screenresolution[1633:8163] starting screenresolution argv=screenresolution list 06:26:22 INFO - Available Modes on Display 0 06:26:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:26:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:26:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:26:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:26:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:26:23 INFO - Intel Iris: 06:26:23 INFO - Chipset Model: Intel Iris 06:26:23 INFO - Type: GPU 06:26:23 INFO - Bus: Built-In 06:26:23 INFO - VRAM (Dynamic, Max): 1536 MB 06:26:23 INFO - Vendor: Intel (0x8086) 06:26:23 INFO - Device ID: 0x0a2e 06:26:23 INFO - Revision ID: 0x0009 06:26:23 INFO - Displays: 06:26:23 INFO - Display: 06:26:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:26:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:26:23 INFO - Display Serial Number: 1600x1200 60 06:26:23 INFO - Main Display: Yes 06:26:23 INFO - Mirror: Off 06:26:23 INFO - Online: Yes 06:26:23 INFO - Rotation: Supported 06:26:23 INFO - Return code: 0 06:26:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:26:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:26:23 INFO - Python 2.7.3 06:26:23 INFO - Return code: 0 06:26:23 INFO - grabbing minidump binary from tooltool 06:26:23 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')]} 06:26:23 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 0x106833d30>, '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 0x10669adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10682bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:26:23 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 06:26:23 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 06:26:23 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 06:26:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:26:23 INFO - Return code: 0 06:26:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:26:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:26:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:26:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:26:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:26:23 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/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--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 06:26:23 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/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0033 --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 06:26:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cL40Sj66az/Render', 06:26:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:23 INFO - 'HOME': '/Users/cltbld', 06:26:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:23 INFO - 'LOGNAME': 'cltbld', 06:26:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:26:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:26:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:23 INFO - 'MOZ_NO_REMOTE': '1', 06:26:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:26:23 INFO - 'NO_EM_RESTART': '1', 06:26:23 INFO - 'PAGER': '/bin/cat', 06:26:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:23 INFO - 'PWD': '/builds/slave/test', 06:26:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:26:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:23 INFO - 'SHELL': '/bin/bash', 06:26:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X3E8bBveI7/Listeners', 06:26:23 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 06:26:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:23 INFO - 'USER': 'cltbld', 06:26:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:23 INFO - 'XPC_FLAGS': '0x0', 06:26:23 INFO - 'XPC_SERVICE_NAME': '0', 06:26:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:23 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/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--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 06:26:23 INFO - mozversion application_buildid: 20161129030222 06:26:23 INFO - mozversion application_changeset: f8107cf961444a8d7fdc2c0f446238af9893f875 06:26:23 INFO - mozversion application_display_name: Nightly 06:26:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:26:23 INFO - mozversion application_name: Firefox 06:26:23 INFO - mozversion application_remotingname: firefox 06:26:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:26:23 INFO - mozversion application_vendor: Mozilla 06:26:23 INFO - mozversion application_version: 53.0a1 06:26:23 INFO - mozversion platform_buildid: 20161129030222 06:26:23 INFO - mozversion platform_changeset: f8107cf961444a8d7fdc2c0f446238af9893f875 06:26:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:26:23 INFO - mozversion platform_version: 53.0a1 06:26:23 INFO - using testdate: 1480429583 06:26:23 INFO - actual date: 1480429583 06:26:23 INFO - starting webserver on 'localhost:49250' 06:26:23 INFO - SUITE-START | Running 2 tests 06:26:23 INFO - TEST-START | dromaeo_css 06:26:23 INFO - operating with platform_type : mac_ 06:26:23 INFO - Initialising browser for dromaeo_css test... 06:26:23 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpP6wY_L/profile http://localhost:49250/getInfo.html) 06:26:27 INFO - PROCESS | 1643 | 2016-11-29 06:26:27.094 plugin-container[1646:8342] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 06:26:27 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:26:27 INFO - PROCESS | 1643 | 2016-11-29 06:26:27.095 plugin-container[1646:8342] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930f, name = 'com.apple.CFPasteboardClient' 06:26:27 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:26:27 INFO - PROCESS | 1643 | 2016-11-29 06:26:27.095 plugin-container[1646:8342] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:26:27 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 06:26:27 INFO - PROCESS | 1643 | colorDepth:24 06:26:27 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 06:26:27 INFO - PROCESS | 1643 | __metrics 06:26:27 ERROR - PROCESS | 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 06:26:27 INFO - TEST-INFO | 1643: exit 0 06:26:27 INFO - Browser initialized. 06:26:27 INFO - Running cycle 1/1 for dromaeo_css test... 06:26:27 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpP6wY_L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:26:30 INFO - PROCESS | 1648 | 2016-11-29 06:26:30.862 plugin-container[1649:8472] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873b, name = 'com.apple.tsm.portname' 06:26:30 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:26:30 INFO - PROCESS | 1648 | 2016-11-29 06:26:30.863 plugin-container[1649:8472] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 06:26:30 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:26:30 INFO - PROCESS | 1648 | 2016-11-29 06:26:30.863 plugin-container[1649:8472] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:26:40 INFO - PROCESS | 1648 | RSS: Main: 242851840 06:26:40 INFO - PROCESS | 1648 | 06:27:30 INFO - PROCESS | 1648 | 1480429649999 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] 06:29:43 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49250/tests/dromaeo/cssquery-ext.html) 06:29:45 INFO - PROCESS | 1648 | RSS: Main: 254525440 06:29:45 INFO - PROCESS | 1648 | 06:32:48 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-ext.html (next: http://localhost:49250/tests/dromaeo/cssquery-jquery.html) 06:32:49 INFO - PROCESS | 1648 | RSS: Main: 265539584 06:32:49 INFO - PROCESS | 1648 | 06:35:51 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49250/tests/dromaeo/cssquery-mootools.html) 06:35:51 INFO - PROCESS | 1648 | RSS: Main: 269885440 06:35:51 INFO - PROCESS | 1648 | 06:38:53 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49250/tests/dromaeo/cssquery-prototype.html) 06:38:54 INFO - PROCESS | 1648 | RSS: Main: 269021184 06:38:54 INFO - PROCESS | 1648 | 06:41:57 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49250/tests/dromaeo/cssquery-yui.html) 06:41:57 INFO - PROCESS | 1648 | RSS: Main: 274051072 06:41:57 INFO - PROCESS | 1648 | 06:45:00 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-yui.html (next: http://localhost:49250/tests/dromaeo/cssquery-dojo.html) 06:45:01 INFO - PROCESS | 1648 | RSS: Main: 276557824 06:45:01 INFO - PROCESS | 1648 | 06:45:01 INFO - PROCESS | 1648 | __start_tp_report 06:45:01 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 06:45:01 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 06:45:01 INFO - PROCESS | 1648 | |i|pagename|runs| 06:45:01 INFO - PROCESS | 1648 | |0;dojo.html;3328.3509715605464;3381.5773028371455;3399.3031428557133;3409.301093275879;3421.743369247307;3996.6003399660094;5067.885065285641;5561.916571251424;5570.442955704428;5653.8586535336635;5566.332040155177;5577.637453565515;5609.1305847593585;5674.801381951633;5713.000224960636;4286.892827679318;5158.226266060102;5171.922421163686;5172.948270517284;5238.68567885928;5090.949090509084;5183.481651834824;5187.299714538527;5214.608904332172;5229.764660590264;4421.403110580079;5188.870278243036;5224.242484839696;5240.895182096375;5252.973735131319;5055.772490237948;5084.44071152173;5116.411612664517;5149.382074151126;5209.817656382009;5121.948780512222;5142;5152.021115987988;5156.278121063016;5176.249443830623;4337.37108119321;5551.2228288039705;5622.550195984352;5634.690092044936;5702.116171993348;6016.308124565643;6057.242844644419;6138.570300078996;6166.260048794128;6176.84557886052;6112.594670127729;6143.757528502109;6161.815145545641;6164.198654174974;6194;5720.256366672347;5730.541556675456;5739.512141467939;5748.712564371806;5753.626014309057;6181;6233.563650544418;6243.843903902348;6257.217847769029;6261.96869015652;5149.639525233198;5875.353711091777;5917.940820591825;5942.494887934487;6096.481799047042;6107.583301036431;6114.0236562776045;6133.785317513865;6133.785317513865;6162.753489860445;5928.170056192136;5991.3409524953095;6052.515798736091;6108.719052744933;6142.2839182294965;6069.696515174223;6185.628862268189;6192.380761923771;6206.348333424925;6220.471259942956;4048.906795165348;4774.069056533943;4788.353572267769;4893.4617192108835;4926;3331.60020797501;3401.9829900850336;4299.613034826881;4339.088791353788;4382.539833317455;4651.674382793172;5055.216441451581;5326.934613077323;5840.153177789197;5867.6772777497645;5075.11185542536;6221.875562488815;6222.097795819581;6229.0967809667345;6247;5641.12562552805;5809.593328467051;5816.331121920949;5834.883302333929;5836.299644042657;5289.894202115936;5302.814401495929;5321.042212401728;5328.547073498796;5358.2498450217;6172.845678858064;6190.380961903774;6193.1329613853195;6194.628322300677;6208.286047104641;6065.514758819196;6122.733612273432;6129.664444256186;6159.322474527888;6246.968765156145;6253.249610046823;6300.684965751786;6302;6305.5270854687;6319.715612797343;5950.988331983486;5995.46040856325;6137.723802428991;6160.537959652955;6176.382361763787;6130.489860652556;6143.047827586731;6144.617461071223;6147.600405973598;6157.476614487819;4635.884102897319;5759.481646651821;5986.970065149646;6006.219191505076;6080.391960803884;6040.607360521552;6041.214642096676;6043.425874541911;6047.244094488189;6050.697465126814;5673.858153546194;5694.288213973254;5877.0302900021015;5888.764449422147;5899.734511946886;5863.208466857091;5956.076808094752;5965.850853728692;5967.791127310524;5971.28344598651;5725.885482290414;5742.28221472316;5746.994276001767;5752.137179422953;5778.988676981596 06:45:01 INFO - PROCESS | 1648 | |1;ext.html;443.3991940920054;487.98292059777907;489.91916333804903;500.14974543276395;529.3964880036756;27487.725122748765;32991;37661.05847353818;38402.07989600519;38512.422313665316;37535.24929501408;37965.81017094911;37981;38031.23937521248;38115.80942095286;8380.120087390827;8616.956915215447;9071.41035832672;9290.349675522682;10962.616308429206;11036.503357348938;11290.266132701388;11291.548338066468;11298.152638552101;11323.32060076398;11261.718457038598;11294.096472282197;11295;11310.151738619596;11310.434478276095;11181.161412894025;11239.325640461599;11239.494222759913;11253.943730281297;11294.322340659546;11120.55517779296;11124.443777811159;11136.721581960435;11167.944160279229;11184.720381990433;38247.85256442311;38422.23155536874;38576.22847543033;38636.13638636142;38727.419088713694;3198.104530731399;3248.10677063807;3392.86428542858;3453.067671728619;3628.6734193922675;29224.269393265127;29250.561241581392;30063.849680751675;30151.396972060655;30181.245468863235;3136.639286482061;3239.400710868497;3294.011796461076;3297.3899828531557;3320.2197483591335;281144;291379;368150.15924920206;368686;369360;57402;57470.137947930816;57856;57939;58224.4177558219;60271;60802;60923.69538152281;61000.289968103476;61148.69425652932;14018.859811401755;14410.13539187631;14471.855281447051;14488.058276212012;14534.491292804702;9943.850842237372;10148.695539133838;11618.593349232906;11797.764044719057;11857.703557411134;16716.58208544796;16727.749083763752;16767.832321676626;16803.663926721398;16855.410060647817;6594.571352862049;6598.637074960827;6629.569078009914;6642.900356494656;6714.597124172566;871.4292138649195;872.9825403491894;909.6907051602487;996.2179688944144;1049.6431213387484;27677.446451070868;27971.860140699166;29340;29453.821847126303;30153.849230754146;971.820213260549;1004.7036124343336;1011.5635797168039;1051.81593221186;1143.1312202725821;13285;15104.320305586274;15246.618834529225;15346.002509837048;15396.307166177548;601711;607408;607644;613077.280749563;614252;54988;55017.624559385535;55024.44975550193;55066.898662026535;55141;1305.432137020399;1346.2528296795372;1353.3368649361742;1360.0479664235074;1370.034125941229;28259.913215621385;28833.981621286308;29080.854595726887;29381.706182937898;29668.961586344376;48956;49191.50808491869;49230;49311.75344123256;49314.260286096454;417986;538902.0548972614;539792;541070;546568;32736.50895236621;33669.66330336665;36541;39181.41227881641;41046.58953410547;44707.552924470336;44821.775891120335;44836;45499.317510238034;45812.31281530713;37230;37460.0031997445;38218.42672359861;38262.6173738259;38503.807480962416;36332.636673633984;36474.452883207305;37074.25851483009;37414.33192609283;37730.24539509249;36594.81702591576;36671.81664091769;36743.63256367402;37001.44497832588;37694.8115259422;29390.265243369086;31870.6812931877;33552.664473354955;33781.83109084537;34352.82823585866;23918;24979.37551561153;25097.372565686004;25413.74586254114;25743 06:45:01 INFO - PROCESS | 1648 | |2;jquery.html;301.37915893259884;365.3040956976959;374.7919904453028;376.25877022266127;395.6636858670131;18383.54041148972;19093.23627054918;19935.517732623535;20116.39650810476;20335.898320508375;19140.138693758778;19456.513587160327;19596;19675.5081122972;19806;5744.741486633101;6046.486048685845;6047.092936059582;6048.092786082101;6051.878962420749;4471.8658440246845;4763.428388593374;5847;5872.765089396419;5950.672713000783;4870.756462176894;5688.573356998221;5864.941350586503;5935.317438494585;5946.43508866657;5824.679642619675;5863.648181109126;5947.910781338283;5955.183668980959;5962.940370596282;4979.253112033188;5257.921131183035;5721.256236689203;5739.16782066598;5748.741306641169;12871.292078935654;13102.80345794814;13127.86872131276;13128.277944713038;20312.867148949295;2111.968320475194;2460.323411061954;2548.24826676131;2569.678790151231;2812.3812761192503;9546.236301095896;9970.351927124713;13093.738125237442;13560.59318220455;13980.091294065853;1744.4069016534438;1748.3793253394988;1757.1126581076473;1775.20115947824;1801.6486785076916;104990.42514362291;107055;108478.45760771225;109704.4514777429;109868;9374.531273436438;11455.656330310105;11535.711607209887;11561.88438115625;11621.24461909973;14423.639409014859;14509.492167774077;14532.0554163979;14540.636484087983;14546.272686365639;6736.629485378351;7038.908969109796;7772.989511363487;8004.479708818908;8118.3099436548555;7238.27617238272;7555.480767327153;7687.385009199251;7836.059672839296;7859.567723775247;3652.872149474809;3741.7380783344906;4365.301551751704;4519.480259770103;4600.930986035212;1867.9346222882134;1908.8091190881078;1937.4381429385603;1958;1960.5784756277183;809.4819315637997;843.6709683223547;903.7966457547;913.4884464699657;962.7352478068588;20684.689729654066;21219.257325993516;21264.36206913795;21277.04253308605;21464.463388415414;882.9161229683169;886.7206829848577;892.9910700892908;916.9954150229206;934.3973137326386;10586.258961872749;11089.889101109047;11146.721331966766;11202.439878006067;11252.93097155769;485097;569914;577087;585074.0746296241;593565;108246.45876770566;133121;134384;135240;139448;995.1889210293588;996.2030375699411;1024.7950409918196;1037.201354956679;1046.9528871200814;18755.34356297523;19537.60924781525;19545.902270488557;19546.31587894417;19699.901500492404;43041.78479107584;45162.32256516089;51593.484065160366;52635;54752.17871732007;69512.95730563944;82458;83191;84898.57550712206;86757;5606.018946684396;7609.2010338913615;8188.303994160563;8220.47921134592;8285.585720714062;10946.835797463204;11965.222260553037;12678.302693351954;13036.152650077714;13162.14446061003;10477.842832357368;10945;11258;11362.886371136514;11459.255148415326;10510;10536.789264214825;10792.892071079188;11079.83380249313;11219.49512271966;10939.50772215268;11330.773384532098;11367.943160284145;11477.598284060017;11621;6047.425494578008;6631.237407698177;8014.919850801417;8227.71203007892;8370.414071014971;8418.915810842058;11115.44422778874;11141.777164456504;11336.773264534828;11366.943165284121 06:45:01 INFO - PROCESS | 1648 | |3;mootools.html;1337.1976813911651;1364.9385777640005;1475.7196132734782;1792.3457937852688;1798.3436045843264;6702.832429189267;7493.363064139541;7655.732049378273;7713.035870516185;8059.073206581245;8003.119656837744;8264.669413223479;8278.213569710882;8376.748697539084;8389.412741108124;1668.8414600612982;1954.941351759446;2306.5848147333472;2307.204014614955;2310.5378924215142;2289.4848659051663;2312.063614236237;2336.1823361823313;2354.0819080558595;2355.2463211772238;1875.812418758127;2286.2912497125944;2298.4713515891353;2299.206773663092;2317.4206448387904;2270.2167752125492;2281.977180228193;2288.2448791898632;2301.344116926668;2310.1683393978155;2306.5963456395066;2320.4894923116885;2321.3268152235833;2327.2087490253225;2332.125452955142;8237.711680091228;8325.916740832636;8344.749657510223;8346.207110324509;8408.621612027473;4960.156773348539;5097.898042039175;5125.461826508237;5127.410347810012;5131.384233891919;3178.523221516768;3289.7861638993627;3557.573091229069;3559.181388280684;3566.857325706969;1031.7059638003134;1058.4125810175349;1074.1567869222702;1092.9890701093045;1093.3931667924298;10543.051125398673;10773.4613269337;10823.188260880466;10830.9710577495;10835.295705779099;4443.200223959654;4548.363229147922;4629.768511574408;4655.837045703384;4686.297055441642;9263.675771347971;9356.34505584616;9361;9362.578683959238;9404.965453800076;4266.743995360288;5134.486551344836;5136.948630513722;5141.562967147835;5148.687084793353;4212.347086201644;4490.483594386622;4701.529847015271;4707.976460117746;4719.5044520325555;3733.663970242691;3774.245150969817;3856.652901238902;3866.9226615468096;3902.024493876531;1218.4029825385496;1245.3275231374955;1250.581055346451;1257.076049103908;1261.9873801262054;812.7236739508477;841.5707988925692;901.8782464367358;907.8138981508774;935.8923723771717;5483.095289277225;5542.8337149884765;5671.631343962712;5683.133973075213;5780.219670344533;894.3247847874812;901.1169054326797;910.6767097680361;927.2442958988403;932.8693982842408;3534.8865107491056;3672.889813305605;3899.3760998240145;3918.4122381642983;4036.2734707752297;9483.004284550161;9805.558749856273;9973.650922217832;9990.950045249727;9998.650047248311;9394;9919.652812151684;9926.702198934043;9930.106290433896;9965.701028969142;7389.741359052515;7750.5737184454265;7758.883616745753;7759.2240775921955;7787.610619469004;5479.479449452296;5562.22128901946;5740.368559458373;5814.3604203538425;5825.650460972355;8962.910370896469;9253.86119208199;9274.907250927405;9353.719388418358;9388.765280868032;9292.97777244489;9316.720498385059;9353.625854965725;9450.763730906783;9528.428294302365;6082.422169893853;6293.709789493235;6442.387973142544;6504.772332968323;6960.617166055916;6116.969415152896;7658.4639075264195;7737.187595302412;7744.65149068282;7754.185810489817;6988.580685158907;7101.218865924848;7102.857942841218;7122.109736282965;7212.026376439323;6490.837729056811;6906.343897329745;6914.585124892523;6967.581945083109;6999.30006999296;5901.527877769854;6578.04618330349;6636.734530618721;6644.767433139817;6666.433353165035;4782.402199725035;4820.831270905642;4823.758812059453;4844.442889067662;4948.257761335828;4674.672772905864;4738.4787673356595;4856.004519073652;4931.950680493149;5018.673786203885 06:45:01 INFO - PROCESS | 1648 | |4;prototype.html;281.7773958572726;285.92851787053223;287.8801462311608;299.48937062308767;307.16298087711004;4118.9702574356415;4918.360613120294;5246.9768395162955;5294.444083371249;5395.730213489324;4742.288656701497;5120.8975820483565;5206.817761378353;5292.364916210051;5324.334458192726;3600.2799440111953;4040.6565441937396;4053.3919912013284;4087.2234275487563;4096.730013695764;3718.9070273243115;4073.205724883649;4099.713020088595;4103.317639767681;4106.815193316308;3929;3934.7245692801516;3939.5666476687684;3949.308870947573;3972.4835771349685;3409.8636054557787;3957.525096988351;3968.7618742875375;3970.960290397088;3977.3835055566146;3019.3206528531123;3281.392942305681;3419.247765491588;3447.6552344765573;3448.9310213795834;4921.630877684152;5082.212257100118;5146.742662866842;5193.416008117522;5195.324607801;4112.424260603518;4356.237658409797;4509.842155524574;4528.932066019012;4549.27211646135;4248.1503699259965;5166.638335316529;5461.672299662033;5522.944770552283;5601.215829783833;3303.4053870303337;3335.5830521184853;3606.350856845767;3774.9622503774885;3780.8865734028022;4964.15609346412;5723.483276931616;5776.451237132465;5804.59367844251;5861.065848270061;3307.1236122427576;3784.4890939722786;3791.6208379161862;3801.144742432986;3828.808559572066;3878.7478813877574;3885.892520631606;3907.27715372657;3919.6864250859862;3929.8821035368983;3876.2635099331037;4043.8989025274013;4524.547545245449;4551.203539380595;4579.702319349299;5401.891962160735;5579.358373786985;5584.274044374205;5614.69119198448;5625.634333768292;1040.1262939131166;1140.971475713097;1197.2816310213802;1209.5101483899034;1223.9082068844873;842.2798507276314;866.27233124176;866.8526350520426;869.7260362985639;871.6687658689657;4775.257031183593;5038.269450929594;5050.9494905051215;5093.873962090718;5100.744962751922;4596.655250856199;5214.765335559833;5395.379531353866;5477.644612816447;5552.444755524496;4537.954620453753;4541.863744087683;5010.69935803853;5017.598592112622;5056.747162641853;2382.784779762299;2444.4866578018814;2764.060219525371;2839.4463079699262;2839.6166517520282;5311.883290449932;5524.723763811874;5563.599579506932;5641.1384243199645;5686.744096515667;5008.873003574166;5155.716435596003;5406.053940560386;5446.918296225481;5564.638298510584;4590.668706075201;5018.397792264878;5592.2170896075395;5607.130894711327;5607.7476513556985;4334.41485399467;5193.350831146106;5365.302510673743;5755.338136114233;5835.957414904749;5735.569832262514;5782.005208203163;5786.623869448473;5789.45000224978;5795;5458.072127738374;5995.430434108753;6027.638341699512;6032.638041717511;6059.273107164437;2865.4269146171264;3816.2749077675153;3850.7882066486613;3853.96146038536;3871.709621778434;4687.250039993721;5464.836054918359;5824.592278540462;5835.2122463466885;5856.560757943256;4646.814127435;4842.5157484251295;5246.501582349671;5440.918386224289;5588.608797384144;5280.1815718563685;5341.344183303188;5624.803131890364;5650.29371328584;5654.321481422256;5005;5028.874278143076;5269.126058395892;5403.6217464778665;5555.777768889199;5623.353314368736;5643.379228284967;5645.774169033192;5665.518430933252;5685.744141513559;5061.974690126526;5211.76547055391;5449.836504904859;5474.534664553398;5532.806351777668 06:45:01 INFO - PROCESS | 1648 | |5;yui.html;643.9034144878268;708.8794904866172;719.1945021575835;764.560377782775;806.2904643913357;14554.854451455481;14817.407303707865;15375.308111134984;15422.69154616907;15466.845331546696;13736.313184340794;13843.307834608255;13917.956153288493;13995.160290382584;14055.929720351383;651.3128649275013;686.0703746423574;837.4472847920374;844.923956843884;847.5084451018425;823.4524041512387;847.8431490174306;848.4824257203101;851.9818816514268;855.8288342331527;879.3756432932626;893.0801274687083;894.1416240409183;894.6421431427416;896.274018045382;887.5784002598746;888.7333799860016;891.6344298837478;892.6340200517757;892.808046270055;647.80242026182;653.464159389301;661.560062558398;663.9800805975829;664.3588936676131;14310.21293828839;14588.27058647074;14753.77869331961;14910.70178596422;15062.472813451477;14023.47216544188;14501.854981450262;14570.344334504973;14593.34329955144;14722.55832325032;8664.913350866475;10318.432486213254;10521.894781052244;10569.682909512727;10888.40113793741;3518.40187168182;3630.6187850275605;3702.7037836972977;3731.9813400933094;3817.408301713239;113279;113416.43291783571;114922;116214;117886.8211317893;10400.687979360631;10559.155267578575;10567;10591;10839.403832789272;20224.595508089755;20241;20254.088566014565;20310;20371.796282037285;7294.92689598715;7417.666205020787;7419.109706835215;7508.2867127622785;7586.24137586248;3642.8907132786057;4048.0284731664187;4159.396887451303;4259.957400425956;4274.957250427518;2554.1571281477068;3054.877804887824;3184.8726050957703;3463.1515278756865;3468.0982944434627;592.8725324055349;639.6290151711996;640.868621932512;654.9672516374258;658.7957733102753;506.8099462701487;531.115692372196;586.8415527807468;647.4205586000503;667.0094909060042;7836.490628109154;8207.589620518951;9023.864642030374;9059.68291109808;9307.488088155082;581.030538688435;630.3224034163293;654.7839213059679;658.1707049118146;667.1627107979551;3791.7345785795283;4029.49631296088;4396.758178300224;4444.488883778342;4460.420145381079;94282.528587358;114234.42882785533;116142;117902.41048794701;123511;72222.63888680628;95256;98091.01908980962;101403.49298253609;107611;68295;84781.15218847732;84811;85911;87218;9222.815543689223;9847.458389788344;9868.210543156674;9877.50612469388;9933.801323973625;42586.5741342595;48933.51066489289;49278.50721492739;55029;56525.43474565202;72571.63714181395;73533.26466735265;74092.25907740854;74641.6267918657;75310;10345.84481232767;12525.749485010067;12565.874341256836;13136.540221092217;13417;14037.227952462712;14224.644383890485;14455.85544144545;14606.853931460975;14752.55742327689;12886;12968.35158242103;13253.337333133497;13344.799828002782;13581.11722738019;12873.742525149632;13048.54330098442;13181.80227296572;13538.406687002322;13548.014497948794;12953;13346.133948824021;13498.987575931647;13637.249951252776;13895.930520347738;9053.77365565866;11055.783794486031;11136.38749868765;11267.661970140909;11437.714057148638;9067.365284430027;9292.234475449643;9680.322377433513;9689.273304502332;10721.41032243234 06:45:01 INFO - PROCESS | 1648 | __end_tp_report 06:45:01 INFO - PROCESS | 1648 | __start_cc_report 06:45:01 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,379 06:45:01 INFO - PROCESS | 1648 | __end_cc_report 06:45:01 INFO - PROCESS | 1648 | __startTimestamp1480430701020__endTimestamp 06:45:01 INFO - PROCESS | 1648 | 06:45:01 INFO - PROCESS | 1648 | ------- Summary: start ------- 06:45:01 INFO - PROCESS | 1648 | Number of tests: 6 06:45:01 INFO - PROCESS | 1648 | 06:45:01 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5595.80 Median:5834.88 stddev:704.31 (12.1%) stddev-sans-first:683.64 06:45:01 INFO - PROCESS | 1648 | Values: 3328.4 3381.6 3399.3 3409.3 3421.7 3996.6 5067.9 5561.9 5570.4 5653.9 5566.3 5577.6 5609.1 5674.8 5713.0 4286.9 5158.2 5171.9 5172.9 5238.7 5090.9 5183.5 5187.3 5214.6 5229.8 4421.4 5188.9 5224.2 5240.9 5253.0 5055.8 5084.4 5116.4 5149.4 5209.8 5121.9 5142.0 5152.0 5156.3 5176.2 4337.4 5551.2 5622.6 5634.7 5702.1 6016.3 6057.2 6138.6 6166.3 6176.8 6112.6 6143.8 6161.8 6164.2 6194.0 5720.3 5730.5 5739.5 5748.7 5753.6 6181.0 6233.6 6243.8 6257.2 6262.0 5149.6 5875.4 5917.9 5942.5 6096.5 6107.6 6114.0 6133.8 6133.8 6162.8 5928.2 5991.3 6052.5 6108.7 6142.3 6069.7 6185.6 6192.4 6206.3 6220.5 4048.9 4774.1 4788.4 4893.5 4926.0 3331.6 3402.0 4299.6 4339.1 4382.5 4651.7 5055.2 5326.9 5840.2 5867.7 5075.1 6221.9 6222.1 6229.1 6247.0 5641.1 5809.6 5816.3 5834.9 5836.3 5289.9 5302.8 5321.0 5328.5 5358.2 6172.8 6190.4 6193.1 6194.6 6208.3 6065.5 6122.7 6129.7 6159.3 6247.0 6253.2 6300.7 6302.0 6305.5 6319.7 5951.0 5995.5 6137.7 6160.5 6176.4 6130.5 6143.0 6144.6 6147.6 6157.5 4635.9 5759.5 5987.0 6006.2 6080.4 6040.6 6041.2 6043.4 6047.2 6050.7 5673.9 5694.3 5877.0 5888.8 5899.7 5863.2 5956.1 5965.9 5967.8 5971.3 5725.9 5742.3 5747.0 5752.1 5779.0 06:45:01 INFO - PROCESS | 1648 | 06:45:01 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:62865.15 Median:19980.66 stddev:133950.29 (670.4%) stddev-sans-first:134244.06 06:45:01 INFO - PROCESS | 1648 | Values: 443.4 488.0 489.9 500.1 529.4 27487.7 32991.0 37661.1 38402.1 38512.4 37535.2 37965.8 37981.0 38031.2 38115.8 8380.1 8617.0 9071.4 9290.3 10962.6 11036.5 11290.3 11291.5 11298.2 11323.3 11261.7 11294.1 11295.0 11310.2 11310.4 11181.2 11239.3 11239.5 11253.9 11294.3 11120.6 11124.4 11136.7 11167.9 11184.7 38247.9 38422.2 38576.2 38636.1 38727.4 3198.1 3248.1 3392.9 3453.1 3628.7 29224.3 29250.6 30063.8 30151.4 30181.2 3136.6 3239.4 3294.0 3297.4 3320.2 281144.0 291379.0 368150.2 368686.0 369360.0 57402.0 57470.1 57856.0 57939.0 58224.4 60271.0 60802.0 60923.7 61000.3 61148.7 14018.9 14410.1 14471.9 14488.1 14534.5 9943.9 10148.7 11618.6 11797.8 11857.7 16716.6 16727.7 16767.8 16803.7 16855.4 6594.6 6598.6 6629.6 6642.9 6714.6 871.4 873.0 909.7 996.2 1049.6 27677.4 27971.9 29340.0 29453.8 30153.8 971.8 1004.7 1011.6 1051.8 1143.1 13285.0 15104.3 15246.6 15346.0 15396.3 601711.0 607408.0 607644.0 613077.3 614252.0 54988.0 55017.6 55024.4 55066.9 55141.0 1305.4 1346.3 1353.3 1360.0 1370.0 28259.9 28834.0 29080.9 29381.7 29669.0 48956.0 49191.5 49230.0 49311.8 49314.3 417986.0 538902.1 539792.0 541070.0 546568.0 32736.5 33669.7 36541.0 39181.4 41046.6 44707.6 44821.8 44836.0 45499.3 45812.3 37230.0 37460.0 38218.4 38262.6 38503.8 36332.6 36474.5 37074.3 37414.3 37730.2 36594.8 36671.8 36743.6 37001.4 37694.8 29390.3 31870.7 33552.7 33781.8 34352.8 23918.0 24979.4 25097.4 25413.7 25743.0 06:45:01 INFO - PROCESS | 1648 | 06:45:01 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:33401.85 Median:2504.29 stddev:94353.39 (3767.7%) stddev-sans-first:94585.34 06:45:01 INFO - PROCESS | 1648 | Values: 301.4 365.3 374.8 376.3 395.7 18383.5 19093.2 19935.5 20116.4 20335.9 19140.1 19456.5 19596.0 19675.5 19806.0 5744.7 6046.5 6047.1 6048.1 6051.9 4471.9 4763.4 5847.0 5872.8 5950.7 4870.8 5688.6 5864.9 5935.3 5946.4 5824.7 5863.6 5947.9 5955.2 5962.9 4979.3 5257.9 5721.3 5739.2 5748.7 12871.3 13102.8 13127.9 13128.3 20312.9 2112.0 2460.3 2548.2 2569.7 2812.4 9546.2 9970.4 13093.7 13560.6 13980.1 1744.4 1748.4 1757.1 1775.2 1801.6 104990.4 107055.0 108478.5 109704.5 109868.0 9374.5 11455.7 11535.7 11561.9 11621.2 14423.6 14509.5 14532.1 14540.6 14546.3 6736.6 7038.9 7773.0 8004.5 8118.3 7238.3 7555.5 7687.4 7836.1 7859.6 3652.9 3741.7 4365.3 4519.5 4600.9 1867.9 1908.8 1937.4 1958.0 1960.6 809.5 843.7 903.8 913.5 962.7 20684.7 21219.3 21264.4 21277.0 21464.5 882.9 886.7 893.0 917.0 934.4 10586.3 11089.9 11146.7 11202.4 11252.9 485097.0 569914.0 577087.0 585074.1 593565.0 108246.5 133121.0 134384.0 135240.0 139448.0 995.2 996.2 1024.8 1037.2 1047.0 18755.3 19537.6 19545.9 19546.3 19699.9 43041.8 45162.3 51593.5 52635.0 54752.2 69513.0 82458.0 83191.0 84898.6 86757.0 5606.0 7609.2 8188.3 8220.5 8285.6 10946.8 11965.2 12678.3 13036.2 13162.1 10477.8 10945.0 11258.0 11362.9 11459.3 10510.0 10536.8 10792.9 11079.8 11219.5 10939.5 11330.8 11367.9 11477.6 11621.0 6047.4 6631.2 8014.9 8227.7 8370.4 8418.9 11115.4 11141.8 11336.8 11366.9 06:45:01 INFO - PROCESS | 1648 | 06:45:01 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5354.33 Median:5481.29 stddev:2922.88 (53.3%) stddev-sans-first:2915.49 06:45:01 INFO - PROCESS | 1648 | Values: 1337.2 1364.9 1475.7 1792.3 1798.3 6702.8 7493.4 7655.7 7713.0 8059.1 8003.1 8264.7 8278.2 8376.7 8389.4 1668.8 1954.9 2306.6 2307.2 2310.5 2289.5 2312.1 2336.2 2354.1 2355.2 1875.8 2286.3 2298.5 2299.2 2317.4 2270.2 2282.0 2288.2 2301.3 2310.2 2306.6 2320.5 2321.3 2327.2 2332.1 8237.7 8325.9 8344.7 8346.2 8408.6 4960.2 5097.9 5125.5 5127.4 5131.4 3178.5 3289.8 3557.6 3559.2 3566.9 1031.7 1058.4 1074.2 1093.0 1093.4 10543.1 10773.5 10823.2 10831.0 10835.3 4443.2 4548.4 4629.8 4655.8 4686.3 9263.7 9356.3 9361.0 9362.6 9405.0 4266.7 5134.5 5136.9 5141.6 5148.7 4212.3 4490.5 4701.5 4708.0 4719.5 3733.7 3774.2 3856.7 3866.9 3902.0 1218.4 1245.3 1250.6 1257.1 1262.0 812.7 841.6 901.9 907.8 935.9 5483.1 5542.8 5671.6 5683.1 5780.2 894.3 901.1 910.7 927.2 932.9 3534.9 3672.9 3899.4 3918.4 4036.3 9483.0 9805.6 9973.7 9991.0 9998.7 9394.0 9919.7 9926.7 9930.1 9965.7 7389.7 7750.6 7758.9 7759.2 7787.6 5479.5 5562.2 5740.4 5814.4 5825.7 8962.9 9253.9 9274.9 9353.7 9388.8 9293.0 9316.7 9353.6 9450.8 9528.4 6082.4 6293.7 6442.4 6504.8 6960.6 6117.0 7658.5 7737.2 7744.7 7754.2 6988.6 7101.2 7102.9 7122.1 7212.0 6490.8 6906.3 6914.6 6967.6 6999.3 5901.5 6578.0 6636.7 6644.8 6666.4 4782.4 4820.8 4823.8 4844.4 4948.3 4674.7 4738.5 4856.0 4932.0 5018.7 06:45:01 INFO - PROCESS | 1648 | 06:45:01 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4442.81 Median:5033.57 stddev:1394.05 (27.7%) stddev-sans-first:1362.52 06:45:01 INFO - PROCESS | 1648 | Values: 281.8 285.9 287.9 299.5 307.2 4119.0 4918.4 5247.0 5294.4 5395.7 4742.3 5120.9 5206.8 5292.4 5324.3 3600.3 4040.7 4053.4 4087.2 4096.7 3718.9 4073.2 4099.7 4103.3 4106.8 3929.0 3934.7 3939.6 3949.3 3972.5 3409.9 3957.5 3968.8 3971.0 3977.4 3019.3 3281.4 3419.2 3447.7 3448.9 4921.6 5082.2 5146.7 5193.4 5195.3 4112.4 4356.2 4509.8 4528.9 4549.3 4248.2 5166.6 5461.7 5522.9 5601.2 3303.4 3335.6 3606.4 3775.0 3780.9 4964.2 5723.5 5776.5 5804.6 5861.1 3307.1 3784.5 3791.6 3801.1 3828.8 3878.7 3885.9 3907.3 3919.7 3929.9 3876.3 4043.9 4524.5 4551.2 4579.7 5401.9 5579.4 5584.3 5614.7 5625.6 1040.1 1141.0 1197.3 1209.5 1223.9 842.3 866.3 866.9 869.7 871.7 4775.3 5038.3 5050.9 5093.9 5100.7 4596.7 5214.8 5395.4 5477.6 5552.4 4538.0 4541.9 5010.7 5017.6 5056.7 2382.8 2444.5 2764.1 2839.4 2839.6 5311.9 5524.7 5563.6 5641.1 5686.7 5008.9 5155.7 5406.1 5446.9 5564.6 4590.7 5018.4 5592.2 5607.1 5607.7 4334.4 5193.4 5365.3 5755.3 5836.0 5735.6 5782.0 5786.6 5789.5 5795.0 5458.1 5995.4 6027.6 6032.6 6059.3 2865.4 3816.3 3850.8 3854.0 3871.7 4687.3 5464.8 5824.6 5835.2 5856.6 4646.8 4842.5 5246.5 5440.9 5588.6 5280.2 5341.3 5624.8 5650.3 5654.3 5005.0 5028.9 5269.1 5403.6 5555.8 5623.4 5643.4 5645.8 5665.5 5685.7 5062.0 5211.8 5449.8 5474.5 5532.8 06:45:01 INFO - PROCESS | 1648 | 06:45:01 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:21226.48 Median:23423.27 stddev:32080.69 (137.0%) stddev-sans-first:32133.46 06:45:01 INFO - PROCESS | 1648 | Values: 643.9 708.9 719.2 764.6 806.3 14554.9 14817.4 15375.3 15422.7 15466.8 13736.3 13843.3 13918.0 13995.2 14055.9 651.3 686.1 837.4 844.9 847.5 823.5 847.8 848.5 852.0 855.8 879.4 893.1 894.1 894.6 896.3 887.6 888.7 891.6 892.6 892.8 647.8 653.5 661.6 664.0 664.4 14310.2 14588.3 14753.8 14910.7 15062.5 14023.5 14501.9 14570.3 14593.3 14722.6 8664.9 10318.4 10521.9 10569.7 10888.4 3518.4 3630.6 3702.7 3732.0 3817.4 113279.0 113416.4 114922.0 116214.0 117886.8 10400.7 10559.2 10567.0 10591.0 10839.4 20224.6 20241.0 20254.1 20310.0 20371.8 7294.9 7417.7 7419.1 7508.3 7586.2 3642.9 4048.0 4159.4 4260.0 4275.0 2554.2 3054.9 3184.9 3463.2 3468.1 592.9 639.6 640.9 655.0 658.8 506.8 531.1 586.8 647.4 667.0 7836.5 8207.6 9023.9 9059.7 9307.5 581.0 630.3 654.8 658.2 667.2 3791.7 4029.5 4396.8 4444.5 4460.4 94282.5 114234.4 116142.0 117902.4 123511.0 72222.6 95256.0 98091.0 101403.5 107611.0 68295.0 84781.2 84811.0 85911.0 87218.0 9222.8 9847.5 9868.2 9877.5 9933.8 42586.6 48933.5 49278.5 55029.0 56525.4 72571.6 73533.3 74092.3 74641.6 75310.0 10345.8 12525.7 12565.9 13136.5 13417.0 14037.2 14224.6 14455.9 14606.9 14752.6 12886.0 12968.4 13253.3 13344.8 13581.1 12873.7 13048.5 13181.8 13538.4 13548.0 12953.0 13346.1 13499.0 13637.2 13895.9 9053.8 11055.8 11136.4 11267.7 11437.7 9067.4 9292.2 9680.3 9689.3 10721.4 06:45:01 INFO - PROCESS | 1648 | -------- Summary: end -------- 06:45:01 INFO - PROCESS | 1648 | 06:45:01 INFO - TEST-INFO | 1648: exit 0 06:45:01 INFO - TEST-OK | dromaeo_css | took 1118331ms 06:45:01 INFO - TEST-START | kraken 06:45:01 INFO - operating with platform_type : mac_ 06:45:01 INFO - Initialising browser for kraken test... 06:45:01 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpQmYytv/profile http://localhost:49250/getInfo.html) 06:45:04 INFO - PROCESS | 1656 | 2016-11-29 06:45:04.806 plugin-container[1658:10667] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 06:45:04 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:45:04 INFO - PROCESS | 1656 | 2016-11-29 06:45:04.806 plugin-container[1658:10667] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481f, name = 'com.apple.CFPasteboardClient' 06:45:04 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:45:04 INFO - PROCESS | 1656 | 2016-11-29 06:45:04.807 plugin-container[1658:10667] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:45:04 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 06:45:04 INFO - PROCESS | 1656 | colorDepth:24 06:45:04 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 06:45:04 INFO - PROCESS | 1656 | __metrics 06:45:05 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 06:45:05 INFO - TEST-INFO | 1656: exit 0 06:45:05 INFO - Browser initialized. 06:45:05 INFO - Running cycle 1/1 for kraken test... 06:45:05 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpQmYytv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:45:08 INFO - PROCESS | 1659 | 2016-11-29 06:45:08.410 plugin-container[1660:10783] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 06:45:08 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:45:08 INFO - PROCESS | 1659 | 2016-11-29 06:45:08.410 plugin-container[1660:10783] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4517, name = 'com.apple.CFPasteboardClient' 06:45:08 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:45:08 INFO - PROCESS | 1659 | 2016-11-29 06:45:08.410 plugin-container[1660:10783] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:45:18 INFO - PROCESS | 1659 | RSS: Main: 242909184 06:45:18 INFO - PROCESS | 1659 | 06:45:20 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection) 06:45:21 INFO - PROCESS | 1659 | RSS: Main: 254976000 06:45:21 INFO - PROCESS | 1659 | 06:45:24 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-dft) 06:45:25 INFO - PROCESS | 1659 | RSS: Main: 262676480 06:45:25 INFO - PROCESS | 1659 | 06:45:28 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-fft) 06:45:29 INFO - PROCESS | 1659 | RSS: Main: 265117696 06:45:29 INFO - PROCESS | 1659 | 06:45:31 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator) 06:45:31 INFO - PROCESS | 1659 | RSS: Main: 266354688 06:45:31 INFO - PROCESS | 1659 | 06:45:33 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:45:34 INFO - PROCESS | 1659 | RSS: Main: 266797056 06:45:34 INFO - PROCESS | 1659 | 06:45:37 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom) 06:45:37 INFO - PROCESS | 1659 | RSS: Main: 274030592 06:45:37 INFO - PROCESS | 1659 | 06:45:41 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate) 06:45:41 INFO - PROCESS | 1659 | RSS: Main: 273006592 06:45:41 INFO - PROCESS | 1659 | 06:45:44 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial) 06:45:45 INFO - PROCESS | 1659 | RSS: Main: 273059840 06:45:45 INFO - PROCESS | 1659 | 06:45:46 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:45:47 INFO - PROCESS | 1659 | RSS: Main: 272154624 06:45:47 INFO - PROCESS | 1659 | 06:45:49 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:45:50 INFO - PROCESS | 1659 | RSS: Main: 272318464 06:45:50 INFO - PROCESS | 1659 | 06:45:52 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:45:52 INFO - PROCESS | 1659 | RSS: Main: 272318464 06:45:52 INFO - PROCESS | 1659 | 06:45:55 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:45:56 INFO - PROCESS | 1659 | RSS: Main: 272691200 06:45:56 INFO - PROCESS | 1659 | 06:45:59 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:46:00 INFO - PROCESS | 1659 | RSS: Main: 272646144 06:46:00 INFO - PROCESS | 1659 | 06:46:02 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49250/tests/kraken/driver.html?testName=ai-astar) 06:46:02 INFO - PROCESS | 1659 | RSS: Main: 274710528 06:46:02 INFO - PROCESS | 1659 | 06:46:02 INFO - PROCESS | 1659 | __start_tp_report 06:46:02 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 06:46:02 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 06:46:02 INFO - PROCESS | 1659 | |i|pagename|runs| 06:46:02 INFO - PROCESS | 1659 | |0;ai-astar;110;120;121;118;118;118;117;119;117;119 06:46:02 INFO - PROCESS | 1659 | |1;audio-beat-detection;177;152;147;155;119;144;115;148;176;152 06:46:02 INFO - PROCESS | 1659 | |2;audio-dft;200;211;207;214;194;204;198;197;202;197 06:46:02 INFO - PROCESS | 1659 | |3;audio-fft;76;65;52;78;53;58;61;86;102;82 06:46:02 INFO - PROCESS | 1659 | |4;audio-oscillator;83;69;88;97;70;82;66;87;109;113 06:46:02 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;90;92;123;134;113;84;93;90;91;88 06:46:02 INFO - PROCESS | 1659 | |6;imaging-darkroom;101;101;101;101;141;172;100;101;100;114 06:46:02 INFO - PROCESS | 1659 | |7;imaging-desaturate;98;100;91;92;94;91;91;95;91;92 06:46:02 INFO - PROCESS | 1659 | |8;json-parse-financial;55;59;64;58;58;59;61;59;57;57 06:46:02 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;69;75;87;122;113;70;70;72;73;72 06:46:02 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;93;89;91;88;90;87;88;89;104;113 06:46:02 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;151;152;169;158;155;165;174;164;194;155 06:46:02 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;177;178;178;174;178;173;175;181;220;195 06:46:02 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;70;72;72;71;71;71;82;96;98;78 06:46:02 INFO - PROCESS | 1659 | __end_tp_report 06:46:02 INFO - PROCESS | 1659 | __start_cc_report 06:46:02 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,970 06:46:02 INFO - PROCESS | 1659 | __end_cc_report 06:46:02 INFO - PROCESS | 1659 | __startTimestamp1480430762415__endTimestamp 06:46:02 INFO - PROCESS | 1659 | 06:46:02 INFO - PROCESS | 1659 | ------- Summary: start ------- 06:46:02 INFO - PROCESS | 1659 | Number of tests: 14 06:46:02 INFO - PROCESS | 1659 | 06:46:02 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:117.70 Median:118.50 stddev:2.98 (2.5%) stddev-sans-first:1.33 06:46:02 INFO - PROCESS | 1659 | Values: 110.0 120.0 121.0 118.0 118.0 118.0 117.0 119.0 117.0 119.0 06:46:02 INFO - PROCESS | 1659 | 06:46:02 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:148.50 Median:152.00 stddev:20.14 (13.2%) stddev-sans-first:18.53 06:46:02 INFO - PROCESS | 1659 | Values: 177.0 152.0 147.0 155.0 119.0 144.0 115.0 148.0 176.0 152.0 06:46:02 INFO - PROCESS | 1659 | 06:46:02 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:202.40 Median:203.00 stddev:6.55 (3.2%) stddev-sans-first:6.89 06:46:02 INFO - PROCESS | 1659 | Values: 200.0 211.0 207.0 214.0 194.0 204.0 198.0 197.0 202.0 197.0 06:46:02 INFO - PROCESS | 1659 | 06:46:02 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:71.30 Median:70.50 stddev:16.23 (23.0%) stddev-sans-first:17.12 06:46:02 INFO - PROCESS | 1659 | Values: 76.0 65.0 52.0 78.0 53.0 58.0 61.0 86.0 102.0 82.0 06:46:02 INFO - PROCESS | 1659 | 06:46:02 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:86.40 Median:82.50 stddev:16.17 (19.6%) stddev-sans-first:17.10 06:46:02 INFO - PROCESS | 1659 | Values: 83.0 69.0 88.0 97.0 70.0 82.0 66.0 87.0 109.0 113.0 06:46:02 INFO - PROCESS | 1659 | 06:46:02 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:99.80 Median:90.00 stddev:17.15 (19.1%) stddev-sans-first:17.82 06:46:02 INFO - PROCESS | 1659 | Values: 90.0 92.0 123.0 134.0 113.0 84.0 93.0 90.0 91.0 88.0 06:46:02 INFO - PROCESS | 1659 | 06:46:02 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:113.20 Median:101.00 stddev:24.32 (24.1%) stddev-sans-first:25.39 06:46:02 INFO - PROCESS | 1659 | Values: 101.0 101.0 101.0 101.0 141.0 172.0 100.0 101.0 100.0 114.0 06:46:02 INFO - PROCESS | 1659 | 06:46:02 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:93.50 Median:92.00 stddev:3.24 (3.5%) stddev-sans-first:3.00 06:46:02 INFO - PROCESS | 1659 | Values: 98.0 100.0 91.0 92.0 94.0 91.0 91.0 95.0 91.0 92.0 06:46:02 INFO - PROCESS | 1659 | 06:46:02 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:58.70 Median:59.00 stddev:2.45 (4.2%) stddev-sans-first:2.20 06:46:02 INFO - PROCESS | 1659 | Values: 55.0 59.0 64.0 58.0 58.0 59.0 61.0 59.0 57.0 57.0 06:46:02 INFO - PROCESS | 1659 | 06:46:02 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:82.30 Median:72.00 stddev:19.36 (26.9%) stddev-sans-first:19.92 06:46:02 INFO - PROCESS | 1659 | Values: 69.0 75.0 87.0 122.0 113.0 70.0 70.0 72.0 73.0 72.0 06:46:02 INFO - PROCESS | 1659 | 06:46:02 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:93.20 Median:89.00 stddev:8.51 (9.6%) stddev-sans-first:9.02 06:46:02 INFO - PROCESS | 1659 | Values: 93.0 89.0 91.0 88.0 90.0 87.0 88.0 89.0 104.0 113.0 06:46:02 INFO - PROCESS | 1659 | 06:46:02 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:163.70 Median:164.50 stddev:13.06 (7.9%) stddev-sans-first:13.02 06:46:02 INFO - PROCESS | 1659 | Values: 151.0 152.0 169.0 158.0 155.0 165.0 174.0 164.0 194.0 155.0 06:46:02 INFO - PROCESS | 1659 | 06:46:02 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.90 Median:178.00 stddev:14.43 (8.1%) stddev-sans-first:15.14 06:46:02 INFO - PROCESS | 1659 | Values: 177.0 178.0 178.0 174.0 178.0 173.0 175.0 181.0 220.0 195.0 06:46:02 INFO - PROCESS | 1659 | 06:46:02 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.10 Median:75.00 stddev:10.66 (14.2%) stddev-sans-first:10.90 06:46:02 INFO - PROCESS | 1659 | Values: 70.0 72.0 72.0 71.0 71.0 71.0 82.0 96.0 98.0 78.0 06:46:02 INFO - PROCESS | 1659 | -------- Summary: end -------- 06:46:02 INFO - PROCESS | 1659 | 06:46:03 INFO - TEST-INFO | 1659: exit 0 06:46:03 INFO - TEST-OK | kraken | took 61662ms 06:46:03 INFO - SUITE-END | took 1179s 06:46:03 INFO - Completed test suite (00:19:40) 06:46:03 INFO - javascript benchmark 06:46:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7084.856861103276, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3328.3509715605464, 3381.5773028371455, 3399.3031428557133, 3409.301093275879, 3421.743369247307, 3996.6003399660094, 5067.885065285641, 5561.916571251424, 5570.442955704428, 5653.8586535336635, 5566.332040155177, 5577.637453565515, 5609.1305847593585, 5674.801381951633, 5713.000224960636, 4286.892827679318, 5158.226266060102, 5171.922421163686, 5172.948270517284, 5238.68567885928, 5090.949090509084, 5183.481651834824, 5187.299714538527, 5214.608904332172, 5229.764660590264, 4421.403110580079, 5188.870278243036, 5224.242484839696, 5240.895182096375, 5252.973735131319, 5055.772490237948, 5084.44071152173, 5116.411612664517, 5149.382074151126, 5209.817656382009, 5121.948780512222, 5142.0, 5152.021115987988, 5156.278121063016, 5176.249443830623, 4337.37108119321, 5551.2228288039705, 5622.550195984352, 5634.690092044936, 5702.116171993348, 6016.308124565643, 6057.242844644419, 6138.570300078996, 6166.260048794128, 6176.84557886052, 6112.594670127729, 6143.757528502109, 6161.815145545641, 6164.198654174974, 6194.0, 5720.256366672347, 5730.541556675456, 5739.512141467939, 5748.712564371806, 5753.626014309057, 6181.0, 6233.563650544418, 6243.843903902348, 6257.217847769029, 6261.96869015652, 5149.639525233198, 5875.353711091777, 5917.940820591825, 5942.494887934487, 6096.481799047042, 6107.583301036431, 6114.0236562776045, 6133.785317513865, 6133.785317513865, 6162.753489860445, 5928.170056192136, 5991.3409524953095, 6052.515798736091, 6108.719052744933, 6142.2839182294965, 6069.696515174223, 6185.628862268189, 6192.380761923771, 6206.348333424925, 6220.471259942956, 4048.906795165348, 4774.069056533943, 4788.353572267769, 4893.4617192108835, 4926.0, 3331.60020797501, 3401.9829900850336, 4299.613034826881, 4339.088791353788, 4382.539833317455, 4651.674382793172, 5055.216441451581, 5326.934613077323, 5840.153177789197, 5867.6772777497645, 5075.11185542536, 6221.875562488815, 6222.097795819581, 6229.0967809667345, 6247.0, 5641.12562552805, 5809.593328467051, 5816.331121920949, 5834.883302333929, 5836.299644042657, 5289.894202115936, 5302.814401495929, 5321.042212401728, 5328.547073498796, 5358.2498450217, 6172.845678858064, 6190.380961903774, 6193.1329613853195, 6194.628322300677, 6208.286047104641, 6065.514758819196, 6122.733612273432, 6129.664444256186, 6159.322474527888, 6246.968765156145, 6253.249610046823, 6300.684965751786, 6302.0, 6305.5270854687, 6319.715612797343, 5950.988331983486, 5995.46040856325, 6137.723802428991, 6160.537959652955, 6176.382361763787, 6130.489860652556, 6143.047827586731, 6144.617461071223, 6147.600405973598, 6157.476614487819, 4635.884102897319, 5759.481646651821, 5986.970065149646, 6006.219191505076, 6080.391960803884, 6040.607360521552, 6041.214642096676, 6043.425874541911, 6047.244094488189, 6050.697465126814, 5673.858153546194, 5694.288213973254, 5877.0302900021015, 5888.764449422147, 5899.734511946886, 5863.208466857091, 5956.076808094752, 5965.850853728692, 5967.791127310524, 5971.28344598651, 5725.885482290414, 5742.28221472316, 5746.994276001767, 5752.137179422953, 5778.988676981596], "value": 5550.955700935958, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [443.3991940920054, 487.98292059777907, 489.91916333804903, 500.14974543276395, 529.3964880036756, 27487.725122748765, 32991.0, 37661.05847353818, 38402.07989600519, 38512.422313665316, 37535.24929501408, 37965.81017094911, 37981.0, 38031.23937521248, 38115.80942095286, 8380.120087390827, 8616.956915215447, 9071.41035832672, 9290.349675522682, 10962.616308429206, 11036.503357348938, 11290.266132701388, 11291.548338066468, 11298.152638552101, 11323.32060076398, 11261.718457038598, 11294.096472282197, 11295.0, 11310.151738619596, 11310.434478276095, 11181.161412894025, 11239.325640461599, 11239.494222759913, 11253.943730281297, 11294.322340659546, 11120.55517779296, 11124.443777811159, 11136.721581960435, 11167.944160279229, 11184.720381990433, 38247.85256442311, 38422.23155536874, 38576.22847543033, 38636.13638636142, 38727.419088713694, 3198.104530731399, 3248.10677063807, 3392.86428542858, 3453.067671728619, 3628.6734193922675, 29224.269393265127, 29250.561241581392, 30063.849680751675, 30151.396972060655, 30181.245468863235, 3136.639286482061, 3239.400710868497, 3294.011796461076, 3297.3899828531557, 3320.2197483591335, 281144.0, 291379.0, 368150.15924920206, 368686.0, 369360.0, 57402.0, 57470.137947930816, 57856.0, 57939.0, 58224.4177558219, 60271.0, 60802.0, 60923.69538152281, 61000.289968103476, 61148.69425652932, 14018.859811401755, 14410.13539187631, 14471.855281447051, 14488.058276212012, 14534.491292804702, 9943.850842237372, 10148.695539133838, 11618.593349232906, 11797.764044719057, 11857.703557411134, 16716.58208544796, 16727.749083763752, 16767.832321676626, 16803.663926721398, 16855.410060647817, 6594.571352862049, 6598.637074960827, 6629.569078009914, 6642.900356494656, 6714.597124172566, 871.4292138649195, 872.9825403491894, 909.6907051602487, 996.2179688944144, 1049.6431213387484, 27677.446451070868, 27971.860140699166, 29340.0, 29453.821847126303, 30153.849230754146, 971.820213260549, 1004.7036124343336, 1011.5635797168039, 1051.81593221186, 1143.1312202725821, 13285.0, 15104.320305586274, 15246.618834529225, 15346.002509837048, 15396.307166177548, 601711.0, 607408.0, 607644.0, 613077.280749563, 614252.0, 54988.0, 55017.624559385535, 55024.44975550193, 55066.898662026535, 55141.0, 1305.432137020399, 1346.2528296795372, 1353.3368649361742, 1360.0479664235074, 1370.034125941229, 28259.913215621385, 28833.981621286308, 29080.854595726887, 29381.706182937898, 29668.961586344376, 48956.0, 49191.50808491869, 49230.0, 49311.75344123256, 49314.260286096454, 417986.0, 538902.0548972614, 539792.0, 541070.0, 546568.0, 32736.50895236621, 33669.66330336665, 36541.0, 39181.41227881641, 41046.58953410547, 44707.552924470336, 44821.775891120335, 44836.0, 45499.317510238034, 45812.31281530713, 37230.0, 37460.0031997445, 38218.42672359861, 38262.6173738259, 38503.807480962416, 36332.636673633984, 36474.452883207305, 37074.25851483009, 37414.33192609283, 37730.24539509249, 36594.81702591576, 36671.81664091769, 36743.63256367402, 37001.44497832588, 37694.8115259422, 29390.265243369086, 31870.6812931877, 33552.664473354955, 33781.83109084537, 34352.82823585866, 23918.0, 24979.37551561153, 25097.372565686004, 25413.74586254114, 25743.0], "value": 19444.95911841796, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.37915893259884, 365.3040956976959, 374.7919904453028, 376.25877022266127, 395.6636858670131, 18383.54041148972, 19093.23627054918, 19935.517732623535, 20116.39650810476, 20335.898320508375, 19140.138693758778, 19456.513587160327, 19596.0, 19675.5081122972, 19806.0, 5744.741486633101, 6046.486048685845, 6047.092936059582, 6048.092786082101, 6051.878962420749, 4471.8658440246845, 4763.428388593374, 5847.0, 5872.765089396419, 5950.672713000783, 4870.756462176894, 5688.573356998221, 5864.941350586503, 5935.317438494585, 5946.43508866657, 5824.679642619675, 5863.648181109126, 5947.910781338283, 5955.183668980959, 5962.940370596282, 4979.253112033188, 5257.921131183035, 5721.256236689203, 5739.16782066598, 5748.741306641169, 12871.292078935654, 13102.80345794814, 13127.86872131276, 13128.277944713038, 20312.867148949295, 2111.968320475194, 2460.323411061954, 2548.24826676131, 2569.678790151231, 2812.3812761192503, 9546.236301095896, 9970.351927124713, 13093.738125237442, 13560.59318220455, 13980.091294065853, 1744.4069016534438, 1748.3793253394988, 1757.1126581076473, 1775.20115947824, 1801.6486785076916, 104990.42514362291, 107055.0, 108478.45760771225, 109704.4514777429, 109868.0, 9374.531273436438, 11455.656330310105, 11535.711607209887, 11561.88438115625, 11621.24461909973, 14423.639409014859, 14509.492167774077, 14532.0554163979, 14540.636484087983, 14546.272686365639, 6736.629485378351, 7038.908969109796, 7772.989511363487, 8004.479708818908, 8118.3099436548555, 7238.27617238272, 7555.480767327153, 7687.385009199251, 7836.059672839296, 7859.567723775247, 3652.872149474809, 3741.7380783344906, 4365.301551751704, 4519.480259770103, 4600.930986035212, 1867.9346222882134, 1908.8091190881078, 1937.4381429385603, 1958.0, 1960.5784756277183, 809.4819315637997, 843.6709683223547, 903.7966457547, 913.4884464699657, 962.7352478068588, 20684.689729654066, 21219.257325993516, 21264.36206913795, 21277.04253308605, 21464.463388415414, 882.9161229683169, 886.7206829848577, 892.9910700892908, 916.9954150229206, 934.3973137326386, 10586.258961872749, 11089.889101109047, 11146.721331966766, 11202.439878006067, 11252.93097155769, 485097.0, 569914.0, 577087.0, 585074.0746296241, 593565.0, 108246.45876770566, 133121.0, 134384.0, 135240.0, 139448.0, 995.1889210293588, 996.2030375699411, 1024.7950409918196, 1037.201354956679, 1046.9528871200814, 18755.34356297523, 19537.60924781525, 19545.902270488557, 19546.31587894417, 19699.901500492404, 43041.78479107584, 45162.32256516089, 51593.484065160366, 52635.0, 54752.17871732007, 69512.95730563944, 82458.0, 83191.0, 84898.57550712206, 86757.0, 5606.018946684396, 7609.2010338913615, 8188.303994160563, 8220.47921134592, 8285.585720714062, 10946.835797463204, 11965.222260553037, 12678.302693351954, 13036.152650077714, 13162.14446061003, 10477.842832357368, 10945.0, 11258.0, 11362.886371136514, 11459.255148415326, 10510.0, 10536.789264214825, 10792.892071079188, 11079.83380249313, 11219.49512271966, 10939.50772215268, 11330.773384532098, 11367.943160284145, 11477.598284060017, 11621.0, 6047.425494578008, 6631.237407698177, 8014.919850801417, 8227.71203007892, 8370.414071014971, 8418.915810842058, 11115.44422778874, 11141.777164456504, 11336.773264534828, 11366.943165284121], "value": 9235.05184768331, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1337.1976813911651, 1364.9385777640005, 1475.7196132734782, 1792.3457937852688, 1798.3436045843264, 6702.832429189267, 7493.363064139541, 7655.732049378273, 7713.035870516185, 8059.073206581245, 8003.119656837744, 8264.669413223479, 8278.213569710882, 8376.748697539084, 8389.412741108124, 1668.8414600612982, 1954.941351759446, 2306.5848147333472, 2307.204014614955, 2310.5378924215142, 2289.4848659051663, 2312.063614236237, 2336.1823361823313, 2354.0819080558595, 2355.2463211772238, 1875.812418758127, 2286.2912497125944, 2298.4713515891353, 2299.206773663092, 2317.4206448387904, 2270.2167752125492, 2281.977180228193, 2288.2448791898632, 2301.344116926668, 2310.1683393978155, 2306.5963456395066, 2320.4894923116885, 2321.3268152235833, 2327.2087490253225, 2332.125452955142, 8237.711680091228, 8325.916740832636, 8344.749657510223, 8346.207110324509, 8408.621612027473, 4960.156773348539, 5097.898042039175, 5125.461826508237, 5127.410347810012, 5131.384233891919, 3178.523221516768, 3289.7861638993627, 3557.573091229069, 3559.181388280684, 3566.857325706969, 1031.7059638003134, 1058.4125810175349, 1074.1567869222702, 1092.9890701093045, 1093.3931667924298, 10543.051125398673, 10773.4613269337, 10823.188260880466, 10830.9710577495, 10835.295705779099, 4443.200223959654, 4548.363229147922, 4629.768511574408, 4655.837045703384, 4686.297055441642, 9263.675771347971, 9356.34505584616, 9361.0, 9362.578683959238, 9404.965453800076, 4266.743995360288, 5134.486551344836, 5136.948630513722, 5141.562967147835, 5148.687084793353, 4212.347086201644, 4490.483594386622, 4701.529847015271, 4707.976460117746, 4719.5044520325555, 3733.663970242691, 3774.245150969817, 3856.652901238902, 3866.9226615468096, 3902.024493876531, 1218.4029825385496, 1245.3275231374955, 1250.581055346451, 1257.076049103908, 1261.9873801262054, 812.7236739508477, 841.5707988925692, 901.8782464367358, 907.8138981508774, 935.8923723771717, 5483.095289277225, 5542.8337149884765, 5671.631343962712, 5683.133973075213, 5780.219670344533, 894.3247847874812, 901.1169054326797, 910.6767097680361, 927.2442958988403, 932.8693982842408, 3534.8865107491056, 3672.889813305605, 3899.3760998240145, 3918.4122381642983, 4036.2734707752297, 9483.004284550161, 9805.558749856273, 9973.650922217832, 9990.950045249727, 9998.650047248311, 9394.0, 9919.652812151684, 9926.702198934043, 9930.106290433896, 9965.701028969142, 7389.741359052515, 7750.5737184454265, 7758.883616745753, 7759.2240775921955, 7787.610619469004, 5479.479449452296, 5562.22128901946, 5740.368559458373, 5814.3604203538425, 5825.650460972355, 8962.910370896469, 9253.86119208199, 9274.907250927405, 9353.719388418358, 9388.765280868032, 9292.97777244489, 9316.720498385059, 9353.625854965725, 9450.763730906783, 9528.428294302365, 6082.422169893853, 6293.709789493235, 6442.387973142544, 6504.772332968323, 6960.617166055916, 6116.969415152896, 7658.4639075264195, 7737.187595302412, 7744.65149068282, 7754.185810489817, 6988.580685158907, 7101.218865924848, 7102.857942841218, 7122.109736282965, 7212.026376439323, 6490.837729056811, 6906.343897329745, 6914.585124892523, 6967.581945083109, 6999.30006999296, 5901.527877769854, 6578.04618330349, 6636.734530618721, 6644.767433139817, 6666.433353165035, 4782.402199725035, 4820.831270905642, 4823.758812059453, 4844.442889067662, 4948.257761335828, 4674.672772905864, 4738.4787673356595, 4856.004519073652, 4931.950680493149, 5018.673786203885], "value": 4351.537281535521, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [281.7773958572726, 285.92851787053223, 287.8801462311608, 299.48937062308767, 307.16298087711004, 4118.9702574356415, 4918.360613120294, 5246.9768395162955, 5294.444083371249, 5395.730213489324, 4742.288656701497, 5120.8975820483565, 5206.817761378353, 5292.364916210051, 5324.334458192726, 3600.2799440111953, 4040.6565441937396, 4053.3919912013284, 4087.2234275487563, 4096.730013695764, 3718.9070273243115, 4073.205724883649, 4099.713020088595, 4103.317639767681, 4106.815193316308, 3929.0, 3934.7245692801516, 3939.5666476687684, 3949.308870947573, 3972.4835771349685, 3409.8636054557787, 3957.525096988351, 3968.7618742875375, 3970.960290397088, 3977.3835055566146, 3019.3206528531123, 3281.392942305681, 3419.247765491588, 3447.6552344765573, 3448.9310213795834, 4921.630877684152, 5082.212257100118, 5146.742662866842, 5193.416008117522, 5195.324607801, 4112.424260603518, 4356.237658409797, 4509.842155524574, 4528.932066019012, 4549.27211646135, 4248.1503699259965, 5166.638335316529, 5461.672299662033, 5522.944770552283, 5601.215829783833, 3303.4053870303337, 3335.5830521184853, 3606.350856845767, 3774.9622503774885, 3780.8865734028022, 4964.15609346412, 5723.483276931616, 5776.451237132465, 5804.59367844251, 5861.065848270061, 3307.1236122427576, 3784.4890939722786, 3791.6208379161862, 3801.144742432986, 3828.808559572066, 3878.7478813877574, 3885.892520631606, 3907.27715372657, 3919.6864250859862, 3929.8821035368983, 3876.2635099331037, 4043.8989025274013, 4524.547545245449, 4551.203539380595, 4579.702319349299, 5401.891962160735, 5579.358373786985, 5584.274044374205, 5614.69119198448, 5625.634333768292, 1040.1262939131166, 1140.971475713097, 1197.2816310213802, 1209.5101483899034, 1223.9082068844873, 842.2798507276314, 866.27233124176, 866.8526350520426, 869.7260362985639, 871.6687658689657, 4775.257031183593, 5038.269450929594, 5050.9494905051215, 5093.873962090718, 5100.744962751922, 4596.655250856199, 5214.765335559833, 5395.379531353866, 5477.644612816447, 5552.444755524496, 4537.954620453753, 4541.863744087683, 5010.69935803853, 5017.598592112622, 5056.747162641853, 2382.784779762299, 2444.4866578018814, 2764.060219525371, 2839.4463079699262, 2839.6166517520282, 5311.883290449932, 5524.723763811874, 5563.599579506932, 5641.1384243199645, 5686.744096515667, 5008.873003574166, 5155.716435596003, 5406.053940560386, 5446.918296225481, 5564.638298510584, 4590.668706075201, 5018.397792264878, 5592.2170896075395, 5607.130894711327, 5607.7476513556985, 4334.41485399467, 5193.350831146106, 5365.302510673743, 5755.338136114233, 5835.957414904749, 5735.569832262514, 5782.005208203163, 5786.623869448473, 5789.45000224978, 5795.0, 5458.072127738374, 5995.430434108753, 6027.638341699512, 6032.638041717511, 6059.273107164437, 2865.4269146171264, 3816.2749077675153, 3850.7882066486613, 3853.96146038536, 3871.709621778434, 4687.250039993721, 5464.836054918359, 5824.592278540462, 5835.2122463466885, 5856.560757943256, 4646.814127435, 4842.5157484251295, 5246.501582349671, 5440.918386224289, 5588.608797384144, 5280.1815718563685, 5341.344183303188, 5624.803131890364, 5650.29371328584, 5654.321481422256, 5005.0, 5028.874278143076, 5269.126058395892, 5403.6217464778665, 5555.777768889199, 5623.353314368736, 5643.379228284967, 5645.774169033192, 5665.518430933252, 5685.744141513559, 5061.974690126526, 5211.76547055391, 5449.836504904859, 5474.534664553398, 5532.806351777668], "value": 3989.8120185365256, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [643.9034144878268, 708.8794904866172, 719.1945021575835, 764.560377782775, 806.2904643913357, 14554.854451455481, 14817.407303707865, 15375.308111134984, 15422.69154616907, 15466.845331546696, 13736.313184340794, 13843.307834608255, 13917.956153288493, 13995.160290382584, 14055.929720351383, 651.3128649275013, 686.0703746423574, 837.4472847920374, 844.923956843884, 847.5084451018425, 823.4524041512387, 847.8431490174306, 848.4824257203101, 851.9818816514268, 855.8288342331527, 879.3756432932626, 893.0801274687083, 894.1416240409183, 894.6421431427416, 896.274018045382, 887.5784002598746, 888.7333799860016, 891.6344298837478, 892.6340200517757, 892.808046270055, 647.80242026182, 653.464159389301, 661.560062558398, 663.9800805975829, 664.3588936676131, 14310.21293828839, 14588.27058647074, 14753.77869331961, 14910.70178596422, 15062.472813451477, 14023.47216544188, 14501.854981450262, 14570.344334504973, 14593.34329955144, 14722.55832325032, 8664.913350866475, 10318.432486213254, 10521.894781052244, 10569.682909512727, 10888.40113793741, 3518.40187168182, 3630.6187850275605, 3702.7037836972977, 3731.9813400933094, 3817.408301713239, 113279.0, 113416.43291783571, 114922.0, 116214.0, 117886.8211317893, 10400.687979360631, 10559.155267578575, 10567.0, 10591.0, 10839.403832789272, 20224.595508089755, 20241.0, 20254.088566014565, 20310.0, 20371.796282037285, 7294.92689598715, 7417.666205020787, 7419.109706835215, 7508.2867127622785, 7586.24137586248, 3642.8907132786057, 4048.0284731664187, 4159.396887451303, 4259.957400425956, 4274.957250427518, 2554.1571281477068, 3054.877804887824, 3184.8726050957703, 3463.1515278756865, 3468.0982944434627, 592.8725324055349, 639.6290151711996, 640.868621932512, 654.9672516374258, 658.7957733102753, 506.8099462701487, 531.115692372196, 586.8415527807468, 647.4205586000503, 667.0094909060042, 7836.490628109154, 8207.589620518951, 9023.864642030374, 9059.68291109808, 9307.488088155082, 581.030538688435, 630.3224034163293, 654.7839213059679, 658.1707049118146, 667.1627107979551, 3791.7345785795283, 4029.49631296088, 4396.758178300224, 4444.488883778342, 4460.420145381079, 94282.528587358, 114234.42882785533, 116142.0, 117902.41048794701, 123511.0, 72222.63888680628, 95256.0, 98091.01908980962, 101403.49298253609, 107611.0, 68295.0, 84781.15218847732, 84811.0, 85911.0, 87218.0, 9222.815543689223, 9847.458389788344, 9868.210543156674, 9877.50612469388, 9933.801323973625, 42586.5741342595, 48933.51066489289, 49278.50721492739, 55029.0, 56525.43474565202, 72571.63714181395, 73533.26466735265, 74092.25907740854, 74641.6267918657, 75310.0, 10345.84481232767, 12525.749485010067, 12565.874341256836, 13136.540221092217, 13417.0, 14037.227952462712, 14224.644383890485, 14455.85544144545, 14606.853931460975, 14752.55742327689, 12886.0, 12968.35158242103, 13253.337333133497, 13344.799828002782, 13581.11722738019, 12873.742525149632, 13048.54330098442, 13181.80227296572, 13538.406687002322, 13548.014497948794, 12953.0, 13346.133948824021, 13498.987575931647, 13637.249951252776, 13895.930520347738, 9053.77365565866, 11055.783794486031, 11136.38749868765, 11267.661970140909, 11437.714057148638, 9067.365284430027, 9292.234475449643, 9680.322377433513, 9689.273304502332, 10721.41032243234], "value": 7306.835391101183, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1591.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 120.0, 121.0, 118.0, 118.0, 118.0, 117.0, 119.0, 117.0, 119.0], "value": 117.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 152.0, 147.0, 155.0, 119.0, 144.0, 115.0, 148.0, 176.0, 152.0], "value": 148.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 211.0, 207.0, 214.0, 194.0, 204.0, 198.0, 197.0, 202.0, 197.0], "value": 202.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 65.0, 52.0, 78.0, 53.0, 58.0, 61.0, 86.0, 102.0, 82.0], "value": 71.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 69.0, 88.0, 97.0, 70.0, 82.0, 66.0, 87.0, 109.0, 113.0], "value": 86.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 123.0, 134.0, 113.0, 84.0, 93.0, 90.0, 91.0, 88.0], "value": 99.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 101.0, 101.0, 101.0, 141.0, 172.0, 100.0, 101.0, 100.0, 114.0], "value": 113.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 100.0, 91.0, 92.0, 94.0, 91.0, 91.0, 95.0, 91.0, 92.0], "value": 93.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 59.0, 64.0, 58.0, 58.0, 59.0, 61.0, 59.0, 57.0, 57.0], "value": 58.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 75.0, 87.0, 122.0, 113.0, 70.0, 70.0, 72.0, 73.0, 72.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 89.0, 91.0, 88.0, 90.0, 87.0, 88.0, 89.0, 104.0, 113.0], "value": 93.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 152.0, 169.0, 158.0, 155.0, 165.0, 174.0, 164.0, 194.0, 155.0], "value": 163.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 178.0, 178.0, 174.0, 178.0, 173.0, 175.0, 181.0, 220.0, 195.0], "value": 182.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 72.0, 71.0, 71.0, 71.0, 82.0, 96.0, 98.0, 78.0], "value": 78.1, "unit": "score"}]}]} 06:46:03 INFO - Return code: 0 06:46:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:46:03 ERROR - # TBPL SUCCESS # 06:46:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:46:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:46:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:46:03 INFO - 2016-11-29 06:46:03.728 screenresolution[1666:11245] starting screenresolution argv=screenresolution get 06:46:03 INFO - 2016-11-29 06:46:03.730 screenresolution[1666:11245] Display 0: 1600x1200x32@60 06:46:03 INFO - 2016-11-29 06:46:03.736 screenresolution[1667:11247] starting screenresolution argv=screenresolution list 06:46:03 INFO - Available Modes on Display 0 06:46:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:46:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:46:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:46:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:46:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:46:03 INFO - Intel Iris: 06:46:03 INFO - Chipset Model: Intel Iris 06:46:03 INFO - Type: GPU 06:46:03 INFO - Bus: Built-In 06:46:03 INFO - VRAM (Dynamic, Max): 1536 MB 06:46:03 INFO - Vendor: Intel (0x8086) 06:46:03 INFO - Device ID: 0x0a2e 06:46:03 INFO - Revision ID: 0x0009 06:46:03 INFO - Displays: 06:46:03 INFO - Display: 06:46:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:46:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:46:03 INFO - Display Serial Number: 1600x1200 60 06:46:03 INFO - Main Display: Yes 06:46:03 INFO - Mirror: Off 06:46:03 INFO - Online: Yes 06:46:03 INFO - Rotation: Supported 06:46:03 INFO - Return code: 0 06:46:03 INFO - Running post-action listener: _resource_record_post_action 06:46:03 INFO - [mozharness: 2016-11-29 14:46:03.977893Z] Finished run-tests step (success) 06:46:03 INFO - Running post-run listener: _resource_record_post_run 06:46:04 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 34983936; Write bytes: 694159360; Read time: 321; Write time: 1931 06:46:04 INFO - TinderboxPrint: CPU usage
54.5% 06:46:04 INFO - TinderboxPrint: I/O read bytes / time
34,983,936 / 321 06:46:04 INFO - TinderboxPrint: I/O write bytes / time
694,159,360 / 1,931 06:46:04 INFO - TinderboxPrint: CPU idle
2,206.3 (45.5%) 06:46:04 INFO - TinderboxPrint: CPU system
985.9 (20.3%) 06:46:04 INFO - TinderboxPrint: CPU user
1,661.6 (34.2%) 06:46:04 INFO - TinderboxPrint: Swap in / out
415,014,912 / 0 06:46:04 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 378837504; Write bytes: 373765120; Read time: 28705; Write time: 678 06:46:04 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 32907264; Write bytes: 305947648; Read time: 299; Write time: 1217 06:46:04 INFO - Running post-run listener: _upload_blobber_files 06:46:04 INFO - Blob upload gear active. 06:46:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:46: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 06:46: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'] 06:46: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 06:46:04 INFO - (blobuploader) - INFO - Open directory for files ... 06:46:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:46:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:46:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:46:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:46:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:46:06 INFO - (blobuploader) - INFO - Done attempting. 06:46:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:46:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:46:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:46:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:46:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:46:07 INFO - (blobuploader) - INFO - Done attempting. 06:46:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:46:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:46:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:46:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:46:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:46:10 INFO - (blobuploader) - INFO - Done attempting. 06:46:10 INFO - (blobuploader) - INFO - Iteration through files over. 06:46:10 INFO - Return code: 0 06:46:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:46:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:46:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb2276df10f74ec17e6c6ee6b86509317bcae2cb6777308ce8cd8f0df89dfb27b629584a497fc3ae8fd15bc074e810113257bf6f04edf0afe19604b45a806193", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c24f3da5176444b623df75b83142d9e2feb54d1d5e5a9abc8b7ff8d03657e9e7c9a7c4930c550a68ca572fc03fb50a007e17aa691e167af3c84439bdc7809178", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed411f17a531676cc5c3f4ac7168925e835cf41d0d654f8ae42d53a100fc700ceaea9aa25da47dce196d8c5943402fb1e2c7f22f4b22b60949270ca8a1bdf4b4"} 06:46:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:46:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:46:10 INFO - Contents: 06:46:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb2276df10f74ec17e6c6ee6b86509317bcae2cb6777308ce8cd8f0df89dfb27b629584a497fc3ae8fd15bc074e810113257bf6f04edf0afe19604b45a806193", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c24f3da5176444b623df75b83142d9e2feb54d1d5e5a9abc8b7ff8d03657e9e7c9a7c4930c550a68ca572fc03fb50a007e17aa691e167af3c84439bdc7809178", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed411f17a531676cc5c3f4ac7168925e835cf41d0d654f8ae42d53a100fc700ceaea9aa25da47dce196d8c5943402fb1e2c7f22f4b22b60949270ca8a1bdf4b4"} 06:46:10 INFO - Running post-run listener: copy_logs_to_upload_dir 06:46:10 INFO - Copying logs to upload dir... 06:46:10 INFO - mkdir: /builds/slave/test/build/upload/logs 06:46:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.378477 ========= master_lag: 1.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-29 06:46:11.931567) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 06:46:11.935823) ========= 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.cL40Sj66az/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.X3E8bBveI7/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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/bb2276df10f74ec17e6c6ee6b86509317bcae2cb6777308ce8cd8f0df89dfb27b629584a497fc3ae8fd15bc074e810113257bf6f04edf0afe19604b45a806193", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c24f3da5176444b623df75b83142d9e2feb54d1d5e5a9abc8b7ff8d03657e9e7c9a7c4930c550a68ca572fc03fb50a007e17aa691e167af3c84439bdc7809178", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed411f17a531676cc5c3f4ac7168925e835cf41d0d654f8ae42d53a100fc700ceaea9aa25da47dce196d8c5943402fb1e2c7f22f4b22b60949270ca8a1bdf4b4"} build_url:https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011015 build_url: 'https://queue.taskcluster.net/v1/task/OgYVCpFgQjm6tEKbakqyTg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb2276df10f74ec17e6c6ee6b86509317bcae2cb6777308ce8cd8f0df89dfb27b629584a497fc3ae8fd15bc074e810113257bf6f04edf0afe19604b45a806193", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c24f3da5176444b623df75b83142d9e2feb54d1d5e5a9abc8b7ff8d03657e9e7c9a7c4930c550a68ca572fc03fb50a007e17aa691e167af3c84439bdc7809178", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed411f17a531676cc5c3f4ac7168925e835cf41d0d654f8ae42d53a100fc700ceaea9aa25da47dce196d8c5943402fb1e2c7f22f4b22b60949270ca8a1bdf4b4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 06:46:11.975021) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 06:46:11.975396) ========= 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.cL40Sj66az/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.X3E8bBveI7/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.004981 ========= master_lag: 0.53 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 06:46:12.506759) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 06:46:12.507081) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 06:46:12.507479) ========= ========= Total master_lag: 1.74 =========