builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0020 starttime: 1480677793.67 results: success (0) buildid: 20161202004006 builduid: 469c4f26a17a4cba93fbf63c529cdf1b revision: 618770ed4835a5ee8852afc1406754ea6391b2d4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-02 03:23:13.668827) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-02 03:23:13.669304) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-02 03:23:13.669611) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-02 03:23:13.699863) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-02 03:23:13.700302) ========= 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.PxYlp8oQDz/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.WFaChyWfBj/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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-12-02 03:23:13-- 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% 190M=0s 2016-12-02 03:23:14 (190 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.587854 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-02 03:23:14.312721) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-02 03:23:14.313050) ========= 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.PxYlp8oQDz/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.WFaChyWfBj/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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.027748 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-02 03:23:14.365285) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-02 03:23:14.365633) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 618770ed4835a5ee8852afc1406754ea6391b2d4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 618770ed4835a5ee8852afc1406754ea6391b2d4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PxYlp8oQDz/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.WFaChyWfBj/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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-12-02 03:23:14,457 truncating revision to first 12 chars 2016-12-02 03:23:14,457 Setting DEBUG logging. 2016-12-02 03:23:14,457 attempt 1/10 2016-12-02 03:23:14,457 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/618770ed4835?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-02 03:23:15,133 unpacking tar archive at: mozilla-aurora-618770ed4835/testing/mozharness/ program finished with exit code 0 elapsedTime=0.947654 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-02 03:23:15.336269) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-02 03:23:15.336576) ========= script_repo_revision: 618770ed4835a5ee8852afc1406754ea6391b2d4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-02 03:23:15.336976) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-02 03:23:15.337256) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-02 03:23:15.357695) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-02 03:23:15.358134) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PxYlp8oQDz/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.WFaChyWfBj/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 03:23:15 INFO - MultiFileLogger online at 20161202 03:23:15 in /builds/slave/test 03:23:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:23:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:23:15 INFO - {'append_to_log': False, 03:23:15 INFO - 'base_work_dir': '/builds/slave/test', 03:23:15 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:23:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:23:15 INFO - 'branch': 'Mozilla-Aurora', 03:23:15 INFO - 'buildbot_json_path': 'buildprops.json', 03:23:15 INFO - 'config_files': ('talos/mac_config.py',), 03:23:15 INFO - 'default_actions': ('clobber', 03:23:15 INFO - 'read-buildbot-config', 03:23:15 INFO - 'download-and-extract', 03:23:15 INFO - 'populate-webroot', 03:23:15 INFO - 'create-virtualenv', 03:23:15 INFO - 'install', 03:23:15 INFO - 'run-tests'), 03:23:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:23:15 INFO - 'download_minidump_stackwalk': True, 03:23:15 INFO - 'download_symbols': 'ondemand', 03:23:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:23:15 INFO - 'tooltool.py': '/tools/tooltool.py', 03:23:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:23:15 INFO - '/tools/misc-python/virtualenv.py')}, 03:23:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:23:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:23:15 INFO - 'installer_path': 'installer.exe', 03:23:15 INFO - 'log_level': 'info', 03:23:15 INFO - 'log_name': 'talos', 03:23:15 INFO - 'log_to_console': True, 03:23:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:23:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:23:15 INFO - 'opt_config_files': (), 03:23:15 INFO - 'pip_index': False, 03:23:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:23:15 INFO - 'cmd': ('bash', 03:23:15 INFO - '-c', 03:23:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:23:15 INFO - 'enabled': True, 03:23:15 INFO - 'halt_on_failure': False, 03:23:15 INFO - 'name': 'check_screen_resolution'},), 03:23:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:23:15 INFO - 'cmd': ('bash', 03:23:15 INFO - '-c', 03:23:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:23:15 INFO - 'enabled': True, 03:23:15 INFO - 'halt_on_failure': False, 03:23:15 INFO - 'name': 'check_screen_resolution'},), 03:23:15 INFO - 'run_cmd_checks_enabled': True, 03:23:15 INFO - 'sps_profile': False, 03:23:15 INFO - 'sps_profile_interval': 0, 03:23:15 INFO - 'suite': 'dromaeojs-e10s', 03:23:15 INFO - 'system_bits': '32', 03:23:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:23:15 INFO - 'title': 't-yosemite-r7-0020', 03:23:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:23:15 INFO - 'use_talos_json': True, 03:23:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:23:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:23:15 INFO - 'work_dir': 'build'} 03:23:15 INFO - [mozharness: 2016-12-02 11:23:15.563036Z] Running clobber step. 03:23:15 INFO - Running pre-action listener: _resource_record_pre_action 03:23:15 INFO - Running main action method: clobber 03:23:15 INFO - rmtree: /builds/slave/test/build 03:23:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:23:18 INFO - Running post-action listener: _resource_record_post_action 03:23:18 INFO - [mozharness: 2016-12-02 11:23:18.152839Z] Finished clobber step (success) 03:23:18 INFO - [mozharness: 2016-12-02 11:23:18.152971Z] Running read-buildbot-config step. 03:23:18 INFO - Running pre-action listener: _resource_record_pre_action 03:23:18 INFO - Running main action method: read_buildbot_config 03:23:18 INFO - Using buildbot properties: 03:23:18 INFO - { 03:23:18 INFO - "project": "", 03:23:18 INFO - "product": "firefox", 03:23:18 INFO - "script_repo_revision": "production", 03:23:18 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 03:23:18 INFO - "repository": "", 03:23:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 03:23:18 INFO - "buildid": "20161202004006", 03:23:18 INFO - "stage_platform": "macosx64", 03:23:18 INFO - "basedir": "/builds/slave/test", 03:23:18 INFO - "nightly_build": "True", 03:23:18 INFO - "buildnumber": 9, 03:23:18 INFO - "slavename": "t-yosemite-r7-0020", 03:23:18 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:23:18 INFO - "platform": "yosemite_r7", 03:23:18 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:23:18 INFO - "branch": "mozilla-aurora", 03:23:18 INFO - "repo_path": "releases/mozilla-aurora", 03:23:18 INFO - "revision": "618770ed4835a5ee8852afc1406754ea6391b2d4", 03:23:18 INFO - "pgo_build": "False", 03:23:18 INFO - "builduid": "469c4f26a17a4cba93fbf63c529cdf1b", 03:23:18 INFO - "slavebuilddir": "test" 03:23:18 INFO - } 03:23:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:23:18 INFO - Running post-action listener: _resource_record_post_action 03:23:18 INFO - [mozharness: 2016-12-02 11:23:18.156630Z] Finished read-buildbot-config step (success) 03:23:18 INFO - [mozharness: 2016-12-02 11:23:18.156757Z] Running download-and-extract step. 03:23:18 INFO - Running pre-action listener: _resource_record_pre_action 03:23:18 INFO - Running main action method: download_and_extract 03:23:18 INFO - mkdir: /builds/slave/test/build/tests 03:23:18 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')]} 03:23:18 INFO - trying https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:23:18 INFO - Downloading https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:23:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 03:23:18 INFO - Downloaded 1199 bytes. 03:23:18 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:23:18 INFO - Using the following test package requirements: 03:23:18 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:23:18 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:23:18 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:23:18 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:23:18 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:23:18 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:23:18 INFO - u'jsshell-mac.zip'], 03:23:18 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:23:18 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:23:18 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:23:18 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:23:18 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:23:18 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:23:18 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:23:18 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:23:18 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:23:18 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:23:18 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:23:18 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:23:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:23:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:23:18 INFO - Fetch https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:23:19 INFO - Http code: 200 03:23:19 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 03:23:19 INFO - x-amz-cf-id: z7NF6_km0Z9WWI7rvUYRlyCcd3bVYjj7hpdRv1Su7KEysKNBRkzK-A== 03:23:19 INFO - x-amz-version-id: h3Bzj8..aoDYk5x2i4B8aB9Y2un7vFvU 03:23:19 INFO - Expected file size: 16274146 03:23:19 INFO - Obtained file size: 16274146 03:23:26 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:23:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:23:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:23:26 INFO - Fetch https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:23:26 INFO - Http code: 200 03:23:26 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 03:23:26 INFO - x-amz-cf-id: DeDHqyO9AlS4F7VzTAA7UiANjxZrCaufSs2ZZDGlSD2UXgOcd4xNuA== 03:23:26 INFO - x-amz-version-id: h3Bzj8..aoDYk5x2i4B8aB9Y2un7vFvU 03:23:27 INFO - Expected file size: 16274146 03:23:27 INFO - Obtained file size: 16274146 03:23:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:23:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:23:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:23:33 INFO - Http code: 200 03:23:33 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 03:23:33 INFO - x-amz-cf-id: rwF1nPq9Am1dVTrUke3XYFb_7Z4cKXB2sMrosKOYVKsTdF_jcemw-w== 03:23:33 INFO - x-amz-version-id: VNfJNoW7Qy.HaC1BrBTJxLlzUIRvtutR 03:23:34 INFO - Expected file size: 14409038 03:23:34 INFO - Obtained file size: 14409038 03:23: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')]} 03:23:34 INFO - trying https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:23:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:23:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 03:23:37 INFO - Downloaded 109853029 bytes. 03:23:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:23:37 INFO - mkdir: /builds/slave/test/properties 03:23:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:23:37 INFO - Writing to file /builds/slave/test/properties/build_url 03:23:37 INFO - Contents: 03:23:37 INFO - build_url:https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:23:38 INFO - Running post-action listener: _resource_record_post_action 03:23:38 INFO - Running post-action listener: set_extra_try_arguments 03:23:38 INFO - [mozharness: 2016-12-02 11:23:38.001579Z] Finished download-and-extract step (success) 03:23:38 INFO - [mozharness: 2016-12-02 11:23:38.001711Z] Running populate-webroot step. 03:23:38 INFO - Running pre-action listener: _resource_record_pre_action 03:23:38 INFO - Running main action method: populate_webroot 03:23:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:23:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:23:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:23:38 INFO - '--tppagecycles', 03:23:38 INFO - '7'], 03:23:38 INFO - 'tests': ['tsvgm']}}, 03:23:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:23:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:23:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:23:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:23:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:23:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:23:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:38 INFO - 'talos_options': ['--disable-e10s'], 03:23:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:23:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:23:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:38 INFO - 'talos_options': ['--disable-e10s'], 03:23:38 INFO - 'tests': ['damp', 'tps']}, 03:23:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:38 INFO - 'tests': ['damp', 'tps']}, 03:23:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:23:38 INFO - 'tests': ['dromaeo_dom']}, 03:23:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:23:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:23:38 INFO - 'tests': ['basic_compositor_video']}, 03:23:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:23:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:23:38 INFO - 'tests': ['a11yr', 03:23:38 INFO - 'ts_paint', 03:23:38 INFO - 'tpaint', 03:23:38 INFO - 'sessionrestore', 03:23:38 INFO - 'sessionrestore_no_auto_restore', 03:23:38 INFO - 'tabpaint']}, 03:23:38 INFO - 'other-e10s': {'tests': ['a11yr', 03:23:38 INFO - 'ts_paint', 03:23:38 INFO - 'tpaint', 03:23:38 INFO - 'sessionrestore', 03:23:38 INFO - 'sessionrestore_no_auto_restore', 03:23:38 INFO - 'tabpaint']}, 03:23:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:23:38 INFO - 'ts_paint', 03:23:38 INFO - 'tpaint', 03:23:38 INFO - 'sessionrestore', 03:23:38 INFO - 'sessionrestore_no_auto_restore', 03:23:38 INFO - 'tabpaint']}, 03:23:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:23:38 INFO - 'ts_paint', 03:23:38 INFO - 'tpaint', 03:23:38 INFO - 'sessionrestore', 03:23:38 INFO - 'sessionrestore_no_auto_restore', 03:23:38 INFO - 'tabpaint']}, 03:23:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:23:38 INFO - 'tests': ['a11yr', 03:23:38 INFO - 'ts_paint', 03:23:38 INFO - 'tpaint', 03:23:38 INFO - 'sessionrestore', 03:23:38 INFO - 'sessionrestore_no_auto_restore', 03:23:38 INFO - 'tabpaint']}, 03:23:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:23:38 INFO - 'tests': ['a11yr', 03:23:38 INFO - 'ts_paint', 03:23:38 INFO - 'tpaint', 03:23:38 INFO - 'sessionrestore', 03:23:38 INFO - 'sessionrestore_no_auto_restore', 03:23:38 INFO - 'tabpaint']}, 03:23:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:23:38 INFO - 'tests': ['tsvgx', 03:23:38 INFO - 'tsvgr_opacity', 03:23:38 INFO - 'tart', 03:23:38 INFO - 'tscrollx', 03:23:38 INFO - 'cart']}, 03:23:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:23:38 INFO - 'tsvgr_opacity', 03:23:38 INFO - 'tart', 03:23:38 INFO - 'tscrollx', 03:23:38 INFO - 'cart']}, 03:23:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:38 INFO - 'talos_options': ['--disable-e10s'], 03:23:38 INFO - 'tests': ['tp5o']}, 03:23:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:38 INFO - 'tests': ['tp5o']}, 03:23:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:38 INFO - 'talos_options': ['--disable-e10s', 03:23:38 INFO - '--xperf_path', 03:23:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:23:38 INFO - 'tests': ['tp5n']}, 03:23:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:23:38 INFO - 'talos_options': ['--xperf_path', 03:23:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:23:38 INFO - 'tests': ['tp5n']}}, 03:23:38 INFO - 'talos.zip': {'path': '', 03:23:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:23:38 INFO - Running post-action listener: _resource_record_post_action 03:23:38 INFO - [mozharness: 2016-12-02 11:23:38.020545Z] Finished populate-webroot step (success) 03:23:38 INFO - [mozharness: 2016-12-02 11:23:38.020681Z] Running create-virtualenv step. 03:23:38 INFO - Running pre-action listener: _resource_record_pre_action 03:23:38 INFO - Running main action method: create_virtualenv 03:23:38 INFO - Creating virtualenv /builds/slave/test/build/venv 03:23:38 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 03:23:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:23:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:23:38 INFO - Using real prefix '/tools/python27' 03:23:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:23:38 INFO - Installing distribute.............................................................................................................................................................................................done. 03:23:41 INFO - Installing pip.................done. 03:23:41 INFO - Return code: 0 03:23:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:23:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:23:41 INFO - Reading from file tmpfile_stdout 03:23:41 INFO - Output received: 03:23:41 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) 03:23:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:41 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 0x109833490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097a9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe704706c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109647d30>, '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 0x1094addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10963fb20>, '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.PxYlp8oQDz/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.WFaChyWfBj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 03:23:41 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 03:23:41 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 03:23:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PxYlp8oQDz/Render', 03:23:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:42 INFO - 'HOME': '/Users/cltbld', 03:23:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:42 INFO - 'LOGNAME': 'cltbld', 03:23:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:42 INFO - 'MOZ_NO_REMOTE': '1', 03:23:42 INFO - 'NO_EM_RESTART': '1', 03:23:42 INFO - 'PAGER': '/bin/cat', 03:23:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:42 INFO - 'PWD': '/builds/slave/test', 03:23:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:42 INFO - 'SHELL': '/bin/bash', 03:23:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WFaChyWfBj/Listeners', 03:23:42 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 03:23:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:42 INFO - 'USER': 'cltbld', 03:23:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:42 INFO - 'XPC_FLAGS': '0x0', 03:23:42 INFO - 'XPC_SERVICE_NAME': '0', 03:23:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:42 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 03:23:42 INFO - Cleaning up... 03:23:42 INFO - Return code: 0 03:23:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:23:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:42 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 0x109833490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097a9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe704706c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109647d30>, '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 0x1094addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10963fb20>, '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.PxYlp8oQDz/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.WFaChyWfBj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 03:23:42 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 03:23:42 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 03:23:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PxYlp8oQDz/Render', 03:23:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:42 INFO - 'HOME': '/Users/cltbld', 03:23:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:42 INFO - 'LOGNAME': 'cltbld', 03:23:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:42 INFO - 'MOZ_NO_REMOTE': '1', 03:23:42 INFO - 'NO_EM_RESTART': '1', 03:23:42 INFO - 'PAGER': '/bin/cat', 03:23:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:42 INFO - 'PWD': '/builds/slave/test', 03:23:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:42 INFO - 'SHELL': '/bin/bash', 03:23:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WFaChyWfBj/Listeners', 03:23:42 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 03:23:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:42 INFO - 'USER': 'cltbld', 03:23:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:42 INFO - 'XPC_FLAGS': '0x0', 03:23:42 INFO - 'XPC_SERVICE_NAME': '0', 03:23:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:42 INFO - Downloading/unpacking psutil>=3.1.1 03:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:23:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:23:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:23:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:23:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:23:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:23:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:23:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:23:46 INFO - Installing collected packages: psutil 03:23:46 INFO - Running setup.py install for psutil 03:23:46 INFO - building 'psutil._psutil_osx' extension 03:23:46 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 03:23:47 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 03:23:47 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 03:23:47 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 03:23:47 INFO - building 'psutil._psutil_posix' extension 03:23:47 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 03:23:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:23:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:23:47 INFO - ^ 03:23:47 INFO - 1 warning generated. 03:23:47 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 03:23:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:23:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:23:47 INFO - Successfully installed psutil 03:23:47 INFO - Cleaning up... 03:23:47 INFO - Return code: 0 03:23:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:23:47 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')]} 03:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:47 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')]} 03:23:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:47 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 0x109833490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097a9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe704706c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109647d30>, '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 0x1094addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10963fb20>, '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.PxYlp8oQDz/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.WFaChyWfBj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 03:23:47 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 03:23:47 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 03:23:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PxYlp8oQDz/Render', 03:23:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:47 INFO - 'HOME': '/Users/cltbld', 03:23:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:47 INFO - 'LOGNAME': 'cltbld', 03:23:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:47 INFO - 'MOZ_NO_REMOTE': '1', 03:23:47 INFO - 'NO_EM_RESTART': '1', 03:23:47 INFO - 'PAGER': '/bin/cat', 03:23:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:47 INFO - 'PWD': '/builds/slave/test', 03:23:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:47 INFO - 'SHELL': '/bin/bash', 03:23:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WFaChyWfBj/Listeners', 03:23:47 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 03:23:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:47 INFO - 'USER': 'cltbld', 03:23:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:47 INFO - 'XPC_FLAGS': '0x0', 03:23:47 INFO - 'XPC_SERVICE_NAME': '0', 03:23:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:23:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:23:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:23:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:23:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:23:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:23:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:23:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:23:50 INFO - Installing collected packages: mozsystemmonitor 03:23:50 INFO - Running setup.py install for mozsystemmonitor 03:23:50 INFO - Successfully installed mozsystemmonitor 03:23:50 INFO - Cleaning up... 03:23:50 INFO - Return code: 0 03:23:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:23:50 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')]} 03:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:50 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')]} 03:23:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:50 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 0x109833490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097a9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe704706c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109647d30>, '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 0x1094addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10963fb20>, '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.PxYlp8oQDz/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.WFaChyWfBj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 03:23:50 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 03:23:50 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 03:23:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PxYlp8oQDz/Render', 03:23:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:50 INFO - 'HOME': '/Users/cltbld', 03:23:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:50 INFO - 'LOGNAME': 'cltbld', 03:23:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:50 INFO - 'MOZ_NO_REMOTE': '1', 03:23:50 INFO - 'NO_EM_RESTART': '1', 03:23:50 INFO - 'PAGER': '/bin/cat', 03:23:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:50 INFO - 'PWD': '/builds/slave/test', 03:23:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:50 INFO - 'SHELL': '/bin/bash', 03:23:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WFaChyWfBj/Listeners', 03:23:50 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 03:23:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:50 INFO - 'USER': 'cltbld', 03:23:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:50 INFO - 'XPC_FLAGS': '0x0', 03:23:50 INFO - 'XPC_SERVICE_NAME': '0', 03:23:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:51 INFO - Downloading/unpacking jsonschema==2.5.1 03:23:51 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 03:23:51 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 03:23:51 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 03:23:51 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 03:23:51 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 03:23:51 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 03:23:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:23:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:23:53 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 03:23:53 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 03:23:53 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 03:23:53 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 03:23:53 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 03:23:53 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 03:23:54 INFO - Downloading functools32-3.2.3-2.tar.gz 03:23:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:23:54 INFO - warning: no files found matching '*.txt' 03:23:54 INFO - no previously-included directories found matching 'build' 03:23:54 INFO - no previously-included directories found matching 'dist' 03:23:54 INFO - no previously-included directories found matching '.git*' 03:23:54 INFO - Installing collected packages: jsonschema, functools32 03:23:54 INFO - Running setup.py install for functools32 03:23:54 INFO - warning: no files found matching '*.txt' 03:23:54 INFO - no previously-included directories found matching 'build' 03:23:54 INFO - no previously-included directories found matching 'dist' 03:23:54 INFO - no previously-included directories found matching '.git*' 03:23:54 INFO - Successfully installed jsonschema functools32 03:23:54 INFO - Cleaning up... 03:23:54 INFO - Return code: 0 03:23:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109833490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097a9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe704706c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109647d30>, '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 0x1094addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10963fb20>, '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.PxYlp8oQDz/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.WFaChyWfBj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 03:23:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:23:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:23:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PxYlp8oQDz/Render', 03:23:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:54 INFO - 'HOME': '/Users/cltbld', 03:23:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:54 INFO - 'LOGNAME': 'cltbld', 03:23:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:54 INFO - 'MOZ_NO_REMOTE': '1', 03:23:54 INFO - 'NO_EM_RESTART': '1', 03:23:54 INFO - 'PAGER': '/bin/cat', 03:23:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:54 INFO - 'PWD': '/builds/slave/test', 03:23:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:54 INFO - 'SHELL': '/bin/bash', 03:23:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WFaChyWfBj/Listeners', 03:23:54 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 03:23:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:54 INFO - 'USER': 'cltbld', 03:23:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:54 INFO - 'XPC_FLAGS': '0x0', 03:23:54 INFO - 'XPC_SERVICE_NAME': '0', 03:23:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:23:54 INFO - Cleaning up... 03:23:54 INFO - Return code: 0 03:23:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109833490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097a9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe704706c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109647d30>, '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 0x1094addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10963fb20>, '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.PxYlp8oQDz/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.WFaChyWfBj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 03:23:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:23:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:23:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PxYlp8oQDz/Render', 03:23:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:54 INFO - 'HOME': '/Users/cltbld', 03:23:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:54 INFO - 'LOGNAME': 'cltbld', 03:23:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:54 INFO - 'MOZ_NO_REMOTE': '1', 03:23:54 INFO - 'NO_EM_RESTART': '1', 03:23:54 INFO - 'PAGER': '/bin/cat', 03:23:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:54 INFO - 'PWD': '/builds/slave/test', 03:23:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:54 INFO - 'SHELL': '/bin/bash', 03:23:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WFaChyWfBj/Listeners', 03:23:54 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 03:23:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:54 INFO - 'USER': 'cltbld', 03:23:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:54 INFO - 'XPC_FLAGS': '0x0', 03:23:54 INFO - 'XPC_SERVICE_NAME': '0', 03:23:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:54 INFO - Downloading/unpacking blobuploader==1.2.4 03:23:54 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 03:23:54 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 03:23:54 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 03:23:54 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 03:23:54 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 03:23:54 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 03:23:57 INFO - Downloading blobuploader-1.2.4.tar.gz 03:23:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:23:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:23:57 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 03:23:57 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 03:23:57 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 03:23:57 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 03:23:57 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 03:23:57 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 03:23:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:23:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:23:58 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 03:23:58 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 03:23:58 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 03:23:58 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 03:23:58 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 03:23:58 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 03:23:58 INFO - Downloading docopt-0.6.1.tar.gz 03:23:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:23:58 INFO - Installing collected packages: blobuploader, requests, docopt 03:23:58 INFO - Running setup.py install for blobuploader 03:23:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:23:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:23:58 INFO - Running setup.py install for requests 03:23:59 INFO - Running setup.py install for docopt 03:23:59 INFO - Successfully installed blobuploader requests docopt 03:23:59 INFO - Cleaning up... 03:23:59 INFO - Return code: 0 03:23:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:23:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:59 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 0x109833490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097a9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe704706c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109647d30>, '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 0x1094addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10963fb20>, '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.PxYlp8oQDz/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.WFaChyWfBj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 03:23:59 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 03:23:59 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 03:23:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PxYlp8oQDz/Render', 03:23:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:59 INFO - 'HOME': '/Users/cltbld', 03:23:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:59 INFO - 'LOGNAME': 'cltbld', 03:23:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:59 INFO - 'MOZ_NO_REMOTE': '1', 03:23:59 INFO - 'NO_EM_RESTART': '1', 03:23:59 INFO - 'PAGER': '/bin/cat', 03:23:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:59 INFO - 'PWD': '/builds/slave/test', 03:23:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:59 INFO - 'SHELL': '/bin/bash', 03:23:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WFaChyWfBj/Listeners', 03:23:59 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 03:23:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:59 INFO - 'USER': 'cltbld', 03:23:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:59 INFO - 'XPC_FLAGS': '0x0', 03:23:59 INFO - 'XPC_SERVICE_NAME': '0', 03:23:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:24:00 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-eagrFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:24:00 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-9lCUN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:24:00 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-ADTdV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:24:00 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-enK3oC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:24:00 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-DtLjaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:24:00 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-Hp6USa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:24:00 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-NgoBzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:24:00 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-RG5WWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:24:00 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-13Iowz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:24:00 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-Wd3MbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:24:01 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-JPex1O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:24:01 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-W6yynw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:24:01 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-YRU8No-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:24:01 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-DcFIAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:24:01 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-51HQ09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:24:01 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-sAY2dh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:24:01 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-bLWWLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:24:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:24:01 INFO - Running setup.py install for manifestparser 03:24:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:24:01 INFO - Running setup.py install for mozcrash 03:24:02 INFO - Running setup.py install for mozdebug 03:24:02 INFO - Running setup.py install for mozdevice 03:24:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:24:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:24:02 INFO - Running setup.py install for mozfile 03:24:02 INFO - Running setup.py install for mozhttpd 03:24:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:24:02 INFO - Running setup.py install for mozinfo 03:24:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:24:02 INFO - Running setup.py install for mozInstall 03:24:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:24:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:24:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:24:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:24:02 INFO - Running setup.py install for mozleak 03:24:03 INFO - Running setup.py install for mozlog 03:24:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:24:03 INFO - Running setup.py install for moznetwork 03:24:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:24:03 INFO - Running setup.py install for mozprocess 03:24:03 INFO - Running setup.py install for mozprofile 03:24:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:24:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:24:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:24:03 INFO - Running setup.py install for mozrunner 03:24:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:24:03 INFO - Running setup.py install for mozscreenshot 03:24:04 INFO - Running setup.py install for moztest 03:24:04 INFO - Running setup.py install for mozversion 03:24:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:24:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:24:04 INFO - Cleaning up... 03:24:04 INFO - Return code: 0 03:24:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:24:04 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')]} 03:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:04 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')]} 03:24:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:04 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 0x109833490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097a9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe704706c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109647d30>, '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 0x1094addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10963fb20>, '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.PxYlp8oQDz/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.WFaChyWfBj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 03:24:04 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 03:24:04 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 03:24:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PxYlp8oQDz/Render', 03:24:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:24:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:24:04 INFO - 'HOME': '/Users/cltbld', 03:24:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:24:04 INFO - 'LOGNAME': 'cltbld', 03:24:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:04 INFO - 'MOZ_NO_REMOTE': '1', 03:24:04 INFO - 'NO_EM_RESTART': '1', 03:24:04 INFO - 'PAGER': '/bin/cat', 03:24:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:24:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:04 INFO - 'PWD': '/builds/slave/test', 03:24:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:24:04 INFO - 'SHELL': '/bin/bash', 03:24:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WFaChyWfBj/Listeners', 03:24:04 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 03:24:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:24:04 INFO - 'USER': 'cltbld', 03:24:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:24:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:24:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:04 INFO - 'XPC_FLAGS': '0x0', 03:24:04 INFO - 'XPC_SERVICE_NAME': '0', 03:24:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:24:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:24:04 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-Sq4Hrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:24:04 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)) 03:24:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:24:04 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-w9t9Lw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:24:04 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)) 03:24:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:24:04 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-YoXWVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:24:04 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)) 03:24:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:24:04 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-kaTCoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:24:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:24:05 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-bWIOWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:24:05 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)) 03:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:24:05 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-IG4ZWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:24:05 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)) 03:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:24:05 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-dpV8ZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:24:05 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)) 03:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:24:05 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-lPfa9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:24:05 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)) 03:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:24:05 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-ZRSd4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:24:05 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)) 03:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:24:05 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-dhetRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:24:05 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)) 03:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:24:05 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-2Bbyta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:24:05 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)) 03:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:24:05 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-WZIo4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:24:05 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)) 03:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:24:05 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-UltDd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:24:06 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)) 03:24:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:24:06 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-KdaXZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:24:06 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)) 03:24:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:24:06 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-aYYWpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:24:06 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)) 03:24:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:24:06 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-CyVQy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:24:06 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)) 03:24:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:24:06 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-LkQvEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:24:06 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)) 03:24:06 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)) 03:24:06 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)) 03:24:06 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)) 03:24:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:24:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:24:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:24:06 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 03:24:06 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 03:24:06 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 03:24:06 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 03:24:06 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 03:24:06 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 03:24:08 INFO - Downloading blessings-1.6.tar.gz 03:24:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:24:09 INFO - Installing collected packages: blessings 03:24:09 INFO - Running setup.py install for blessings 03:24:09 INFO - Successfully installed blessings 03:24:09 INFO - Cleaning up... 03:24:09 INFO - Return code: 0 03:24:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:24:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:24:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:24:09 INFO - Reading from file tmpfile_stdout 03:24:09 INFO - Current package versions: 03:24:09 INFO - blessings == 1.6 03:24:09 INFO - blobuploader == 1.2.4 03:24:09 INFO - docopt == 0.6.1 03:24:09 INFO - functools32 == 3.2.3-2 03:24:09 INFO - jsonschema == 2.5.1 03:24:09 INFO - manifestparser == 1.1 03:24:09 INFO - mozInstall == 1.12 03:24:09 INFO - mozcrash == 1.0 03:24:09 INFO - mozdebug == 0.1 03:24:09 INFO - mozdevice == 0.48 03:24:09 INFO - mozfile == 1.2 03:24:09 INFO - mozhttpd == 0.7 03:24:09 INFO - mozinfo == 0.9 03:24:09 INFO - mozleak == 0.1 03:24:09 INFO - mozlog == 3.4 03:24:09 INFO - moznetwork == 0.27 03:24:09 INFO - mozprocess == 0.23 03:24:09 INFO - mozprofile == 0.28 03:24:09 INFO - mozrunner == 6.13 03:24:09 INFO - mozscreenshot == 0.1 03:24:09 INFO - mozsystemmonitor == 0.3 03:24:09 INFO - moztest == 0.7 03:24:09 INFO - mozversion == 1.4 03:24:09 INFO - psutil == 3.1.1 03:24:09 INFO - requests == 1.2.3 03:24:09 INFO - wsgiref == 0.1.2 03:24:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:24:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:24:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:24:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:09 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 0x109833490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097a9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe704706c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109647d30>, '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 0x1094addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10963fb20>, '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.PxYlp8oQDz/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.WFaChyWfBj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 03:24:09 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 03:24:09 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 03:24:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PxYlp8oQDz/Render', 03:24:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:24:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:24:09 INFO - 'HOME': '/Users/cltbld', 03:24:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:24:09 INFO - 'LOGNAME': 'cltbld', 03:24:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:09 INFO - 'MOZ_NO_REMOTE': '1', 03:24:09 INFO - 'NO_EM_RESTART': '1', 03:24:09 INFO - 'PAGER': '/bin/cat', 03:24:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:24:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:09 INFO - 'PWD': '/builds/slave/test', 03:24:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:24:09 INFO - 'SHELL': '/bin/bash', 03:24:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WFaChyWfBj/Listeners', 03:24:09 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 03:24:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:24:09 INFO - 'USER': 'cltbld', 03:24:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:24:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:24:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:09 INFO - 'XPC_FLAGS': '0x0', 03:24:09 INFO - 'XPC_SERVICE_NAME': '0', 03:24:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:24:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:09 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)) 03:24:09 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)) 03:24:09 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)) 03:24:09 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)) 03:24:09 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)) 03:24:09 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)) 03:24:09 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)) 03:24:09 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)) 03:24:09 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)) 03:24:09 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)) 03:24:09 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)) 03:24:09 INFO - Cleaning up... 03:24:09 INFO - Return code: 0 03:24:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:24:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:24:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:24:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109833490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097a9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe704706c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109647d30>, '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 0x1094addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10963fb20>, '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.PxYlp8oQDz/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.WFaChyWfBj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 03:24:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:24:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:24:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PxYlp8oQDz/Render', 03:24:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:24:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:24:09 INFO - 'HOME': '/Users/cltbld', 03:24:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:24:09 INFO - 'LOGNAME': 'cltbld', 03:24:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:09 INFO - 'MOZ_NO_REMOTE': '1', 03:24:09 INFO - 'NO_EM_RESTART': '1', 03:24:09 INFO - 'PAGER': '/bin/cat', 03:24:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:24:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:09 INFO - 'PWD': '/builds/slave/test', 03:24:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:24:09 INFO - 'SHELL': '/bin/bash', 03:24:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WFaChyWfBj/Listeners', 03:24:09 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 03:24:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:24:09 INFO - 'USER': 'cltbld', 03:24:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:24:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:24:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:09 INFO - 'XPC_FLAGS': '0x0', 03:24:09 INFO - 'XPC_SERVICE_NAME': '0', 03:24:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:24:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:24:10 INFO - Cleaning up... 03:24:10 INFO - Return code: 0 03:24:10 INFO - Running post-action listener: _resource_record_post_action 03:24:10 INFO - Running post-action listener: _start_resource_monitoring 03:24:10 INFO - Starting resource monitoring. 03:24:10 INFO - [mozharness: 2016-12-02 11:24:10.223290Z] Finished create-virtualenv step (success) 03:24:10 INFO - [mozharness: 2016-12-02 11:24:10.224301Z] Running install step. 03:24:10 INFO - Running pre-action listener: _resource_record_pre_action 03:24:10 INFO - Running main action method: install 03:24:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:24:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:24:10 INFO - Reading from file tmpfile_stdout 03:24:10 INFO - Detecting whether we're running mozinstall >=1.0... 03:24:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:24:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:24:10 INFO - Reading from file tmpfile_stdout 03:24:10 INFO - Output received: 03:24:10 INFO - Usage: mozinstall [options] installer 03:24:10 INFO - Options: 03:24:10 INFO - -h, --help show this help message and exit 03:24:10 INFO - -d DEST, --destination=DEST 03:24:10 INFO - Directory to install application into. [default: 03:24:10 INFO - "/builds/slave/test"] 03:24:10 INFO - --app=APP Application being installed. [default: firefox] 03:24:10 INFO - mkdir: /builds/slave/test/build/application 03:24:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:24:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:24:41 INFO - Reading from file tmpfile_stdout 03:24:41 INFO - Output received: 03:24:41 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:24:41 INFO - Running post-action listener: _resource_record_post_action 03:24:41 INFO - [mozharness: 2016-12-02 11:24:41.235622Z] Finished install step (success) 03:24:41 INFO - [mozharness: 2016-12-02 11:24:41.235788Z] Running run-tests step. 03:24:41 INFO - Running pre-action listener: _resource_record_pre_action 03:24:41 INFO - Running main action method: run_tests 03:24:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:24:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:24:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:24:41 INFO - 2016-12-02 03:24:41.250 screenresolution[1631:8352] starting screenresolution argv=screenresolution get 03:24:41 INFO - 2016-12-02 03:24:41.253 screenresolution[1631:8352] Display 0: 1600x1200x32@60 03:24:41 INFO - 2016-12-02 03:24:41.259 screenresolution[1632:8356] starting screenresolution argv=screenresolution list 03:24:41 INFO - Available Modes on Display 0 03:24:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:24:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:24:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:24:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:24:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:24:41 INFO - Intel Iris: 03:24:41 INFO - Chipset Model: Intel Iris 03:24:41 INFO - Type: GPU 03:24:41 INFO - Bus: Built-In 03:24:41 INFO - VRAM (Dynamic, Max): 1536 MB 03:24:41 INFO - Vendor: Intel (0x8086) 03:24:41 INFO - Device ID: 0x0a2e 03:24:41 INFO - Revision ID: 0x0009 03:24:41 INFO - Displays: 03:24:41 INFO - Display: 03:24:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:24:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:24:41 INFO - Display Serial Number: 1600x1200 60 03:24:41 INFO - Main Display: Yes 03:24:41 INFO - Mirror: Off 03:24:41 INFO - Online: Yes 03:24:41 INFO - Rotation: Supported 03:24:41 INFO - Return code: 0 03:24:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:24:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:24:41 INFO - Python 2.7.3 03:24:41 INFO - Return code: 0 03:24:41 INFO - grabbing minidump binary from tooltool 03:24:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:24:41 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 0x109647d30>, '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 0x1094addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10963fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:24:41 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 03:24:41 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 03:24:41 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 03:24:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:24:41 INFO - Return code: 0 03:24:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:24:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:24:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:24:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:24:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:24:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0020', '--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 03:24:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0020 --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 03:24:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PxYlp8oQDz/Render', 03:24:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:24:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:24:41 INFO - 'HOME': '/Users/cltbld', 03:24:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:24:41 INFO - 'LOGNAME': 'cltbld', 03:24:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:24:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:24:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:41 INFO - 'MOZ_NO_REMOTE': '1', 03:24:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:24:41 INFO - 'NO_EM_RESTART': '1', 03:24:41 INFO - 'PAGER': '/bin/cat', 03:24:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:24:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:41 INFO - 'PWD': '/builds/slave/test', 03:24:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:24:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:24:41 INFO - 'SHELL': '/bin/bash', 03:24:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WFaChyWfBj/Listeners', 03:24:41 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 03:24:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:24:41 INFO - 'USER': 'cltbld', 03:24:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:24:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:24:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:41 INFO - 'XPC_FLAGS': '0x0', 03:24:41 INFO - 'XPC_SERVICE_NAME': '0', 03:24:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:24:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0020', '--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 03:24:41 INFO - mozversion application_buildid: 20161202004006 03:24:41 INFO - mozversion application_changeset: 618770ed4835a5ee8852afc1406754ea6391b2d4 03:24:41 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:24:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:24:41 INFO - mozversion application_name: Firefox 03:24:41 INFO - mozversion application_remotingname: firefox-dev 03:24:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:24:41 INFO - mozversion application_vendor: Mozilla 03:24:41 INFO - mozversion application_version: 52.0a2 03:24:41 INFO - mozversion platform_buildid: 20161202004006 03:24:41 INFO - mozversion platform_changeset: 618770ed4835a5ee8852afc1406754ea6391b2d4 03:24:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:24:41 INFO - mozversion platform_version: 52.0a2 03:24:41 INFO - using testdate: 1480677881 03:24:41 INFO - actual date: 1480677881 03:24:41 INFO - starting webserver on 'localhost:49238' 03:24:41 INFO - SUITE-START | Running 2 tests 03:24:41 INFO - TEST-START | dromaeo_css 03:24:41 INFO - operating with platform_type : mac_ 03:24:41 INFO - Initialising browser for dromaeo_css test... 03:24:41 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpgSrovU/profile http://localhost:49238/getInfo.html) 03:24:45 INFO - PROCESS | 1642 | 2016-12-02 03:24:45.183 plugin-container[1646:8543] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c4b, name = 'com.apple.tsm.portname' 03:24:45 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:24:45 INFO - PROCESS | 1642 | 2016-12-02 03:24:45.184 plugin-container[1646:8543] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 03:24:45 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:24:45 INFO - PROCESS | 1642 | 2016-12-02 03:24:45.184 plugin-container[1646:8543] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:24:45 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 03:24:45 INFO - PROCESS | 1642 | colorDepth:24 03:24:45 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 03:24:45 INFO - PROCESS | 1642 | __metrics 03:24:45 ERROR - PROCESS | 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 03:24:46 INFO - TEST-INFO | 1642: exit 0 03:24:46 INFO - Browser initialized. 03:24:46 INFO - Running cycle 1/1 for dromaeo_css test... 03:24:46 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpgSrovU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:24:49 INFO - PROCESS | 1647 | 2016-12-02 03:24:49.137 plugin-container[1648:8675] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 03:24:49 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:24:49 INFO - PROCESS | 1647 | 2016-12-02 03:24:49.137 plugin-container[1648:8675] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 03:24:49 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:24:49 INFO - PROCESS | 1647 | 2016-12-02 03:24:49.137 plugin-container[1648:8675] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:24:58 INFO - PROCESS | 1647 | RSS: Main: 229543936 03:24:58 INFO - PROCESS | 1647 | 03:25:48 INFO - PROCESS | 1647 | 1480677948291 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] 03:28:02 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49238/tests/dromaeo/cssquery-ext.html) 03:28:04 INFO - PROCESS | 1647 | RSS: Main: 234348544 03:28:04 INFO - PROCESS | 1647 | 03:31:06 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-ext.html (next: http://localhost:49238/tests/dromaeo/cssquery-jquery.html) 03:31:07 INFO - PROCESS | 1647 | RSS: Main: 243499008 03:31:07 INFO - PROCESS | 1647 | 03:34:09 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49238/tests/dromaeo/cssquery-mootools.html) 03:34:10 INFO - PROCESS | 1647 | RSS: Main: 246337536 03:34:10 INFO - PROCESS | 1647 | 03:37:11 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49238/tests/dromaeo/cssquery-prototype.html) 03:37:12 INFO - PROCESS | 1647 | RSS: Main: 245587968 03:37:12 INFO - PROCESS | 1647 | 03:40:15 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49238/tests/dromaeo/cssquery-yui.html) 03:40:16 INFO - PROCESS | 1647 | RSS: Main: 248987648 03:40:16 INFO - PROCESS | 1647 | 03:43:18 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-yui.html (next: http://localhost:49238/tests/dromaeo/cssquery-dojo.html) 03:43:19 INFO - PROCESS | 1647 | RSS: Main: 250556416 03:43:19 INFO - PROCESS | 1647 | 03:43:19 INFO - PROCESS | 1647 | __start_tp_report 03:43:19 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 03:43:19 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 03:43:19 INFO - PROCESS | 1647 | |i|pagename|runs| 03:43:19 INFO - PROCESS | 1647 | |0;dojo.html;2765.253381586971;3284.9178770530734;3344.6822551857563;3351.1287065363;3374.1227280906955;4249.707795126921;5627.831165065049;5655.5475561955045;5693.629914055588;5826.359100498942;5757.942420575793;5783.103618939061;5796.942030579704;5800;5814;5210.635255532124;5232.032074066291;5242.895142097156;5277.023750606131;5312.990531798965;5279;5305.946940530584;5311.097113490697;5313.9734301328435;5341.706206158659;5285.180796976456;5291.153415453528;5294.10000299948;5326.6537675051195;5329.600279978998;5138.3577052868395;5177.922331165036;5224.52979231871;5262.315898933152;5312.946870531284;5212.687238765686;5213.817516386908;5215.6349055566125;5234.057869583474;5351.063563876306;5145.819896303611;5872.207252021008;5899.646021238739;5911.201987731687;5924.851878703023;6052.939470605282;6117.796956032686;6169.969150154266;6266.122742816009;6273.62358258506;6160.599561028519;6168.074788781719;6185.505159587222;6207.379262073801;6224.221972253468;5807;5843.5033022193165;5858.502027327725;5884.882302353929;5905.025670764362;6034.034554471351;6249.812505624838;6294.65379404128;6306.49548036156;6323.924932761442;4456.286994080931;4519.847438903124;4890;4951.331570238044;6070.210872586624;5263.7894484220205;6186.783462578788;6247.094171345129;6256.781012664626;6260.655663938527;4911.435184953704;6179.196704428396;6217.285012223652;6239.594426362272;6239.625622462576;5331.70675612845;6131.8354600871235;6146.555559443527;6170.074488826712;6177.505799536027;4813.9037219256115;4889.853304400874;4949.678270912451;4953.7523123844385;4994.300797888226;3714.814259287025;4335.241332766753;4407.4050003249795;4414.536473670282;4423.314386270134;5195.792168313301;5305.761240744098;6006.849828754229;6015.819525414245;6039.396060394013;6216.067589861557;6218.689065546704;6237.563370564108;6238.407351301619;6244.781432649836;5807.5644326675665;5867.853303667357;5870.55970802183;5915.08316210988;5917.230760001173;5405.405405405402;5408.107662235766;5417.485338892799;5417.539509141767;5433.130699088127;6218.782342617987;6221.626702397871;6242.875142497124;6286.8428289291305;6289.716962736779;6205.751769929333;6230.7507699691505;6232.501399887908;6253.093301471352;6281.560290779785;5755.5971082025535;6357.904631430625;6382.840428989312;6384.201974753156;6390.5526613136635;6139;6139.815805525842;6140.938590614215;6159.84600384994;6250.1874756281395;6121.666376154927;6140.228185505611;6198.977168767103;6207.16203312547;6274.372562743689;5902.6458412495385;6061.121137435135;6066.272047354346;6081.391860813883;6107.328193898578;4963.776630051584;6061.36355682647;6081.087836824512;6086.421789929949;6117.296510901126;5822.912656310074;5880.176775251553;6002.6398416093425;6015.789447369321;6024.759009639565;6115.694215289307;6127.234095738033;6127.571070025234;6130.172426722514;6134.7852825152695;5876.3536011037895;5954.672493012926;5956.5532585055425;5959.880802383841;5993.490553303018 03:43:19 INFO - PROCESS | 1647 | |1;ext.html;469.4319872953726;505.2698850161516;525.5952916254485;546.2270886695323;546.5518275014489;27233.863830680843;34526.48210276848;37206;39304.80347598262;39322.60677393225;31854.68145318546;40417.39373909394;40454.797726011326;40492.79753601235;40513.79743101288;8043.436959412844;8985.607230879225;9214.723558293263;9415.62337506499;9453.054694530561;9367.81264374712;9473;9503.477308748015;9503.524823758818;9522.57148428319;9477.14705676489;9498.28762842786;9499.715008549754;9517.952410237938;9542.379745316566;9409.200217981446;9441.386309889835;9452.385594936375;9479.194268487186;9486.430814151174;9350.392224505453;9373.203277721403;9417.858732119024;9426.622935082589;9434;38236;38597.42103868444;38648.227035459415;38659.8067009666;38661;3298.719608833252;3391.6269210386836;3527.6472352764777;3588.372034893909;3882.9223415531815;29039.12882613525;29281.56077658837;29861.43717719464;29883.954061607958;30187.547186792217;3132.183333666468;3149.165471150147;3200.8399580020905;3224.951625725616;3260.2990357073104;409911;490643;493662;497264;498068;55534.444655553736;55679.721601391735;55745.442545574835;55877.44122558722;56220;57989;57994;58436;58514.41485585175;58598.7070064647;14948.626284342761;15129.092254464767;15138.243087845784;15187.544373668808;15208.46770363032;11817.113716471298;11825.526978920747;11928.224665396721;11929;11959.641210763692;13791.724165516633;14668.826493880464;14785.852141478663;14811.777823332659;15001.87485938537;6877.1747390313485;6990.965045174741;7048.718051277891;7074.363307302265;7088.574685518885;784.7684932944795;818.8444195602816;931.8043210925781;938.2681508423441;958.2908647600725;25806.612900806504;26033.479330413666;26089.08688195904;29688;29974.85012574967;958.3962103874514;1002.4586723169552;1002.8245057114975;1043.3739756146251;1096.9743290023853;14684;14742.484013059491;14833.332500037524;14940.551783446514;14947.327370268364;555281;575139;595098;609287;671833;42587.935301617086;52143;53484;53608.46391536113;53707.731461342446;1183.9052875769917;1325.6685828542863;1344.2069179184132;1350.1898860683707;1355.5662188099714;24778.752212478365;26754.331141721614;27525;27600.033998809944;28327.71672283251;46559;47282.52717472781;47340.7632961833;47387.28919066286;47429;536638;604044.9797750983;610286;610568;616453;24727.50544989126;25068.749312506643;25085.87457062776;25092.745362731424;25148;42396.57603423926;42472.7258182255;42565.93585160396;43392.56607433885;43846.34230486609;34284.31431371309;35933.46099808453;36152;36369.45445818262;36903.81548092242;35590.28819423545;35823;36367.63632363643;36780.81609591935;37066;26302;29957.55063674003;34259.65740342565;34369.484457733655;34657.65342346645;31721.36557268888;32472.35055298928;32888.835555822065;32921;32955.83522082374;23373.181938632068;24416.90123944462;24637.00187482525;24691.753082468946;24752.632782744546 03:43:19 INFO - PROCESS | 1647 | |2;jquery.html;291.99124026279213;322.52910750304557;360.75468681296724;376.5707093912938;379.62986088563656;18317.816821831777;19039.52401189969;20196.091175897083;20327.390178294674;20330.390088297358;19699.113539890703;19776.90111549444;19976.600467990633;20055.899720501377;20369.898150509227;5221.242919776649;5732.828015159542;6033.5173186145;6045.486133678643;6056.818295451144;5151;5162.664426812264;5163.948360516384;5934.139549765281;5951.553633477485;5812.244408226925;5890.528757699395;5892.675902825343;5913.970430147843;5919.37846526115;6314.494840412756;7151.678174482135;7538.623068846536;7539.132999705022;7609.0869095708595;7006.7197312107455;7309.378702810268;7352.154502232205;7427.368673662745;7433.219511951222;21932.684038957712;28268.858655706794;28449.857750711322;28610.85694571514;28745.98778085535;2324.4970038493443;2532.2529853693204;2653.0714250012534;2676.2907829425217;2729.5359788835945;15707.371705131782;16602;17063.91468042652;17159;17284;1494.454524098707;1900.591372854829;1935.5451468904796;1962.4995626115392;2008.9698654520193;102615;132908;136284.31857840746;140263;149660.25169874082;18968.905155474135;21176;21718.090743666293;22065;22573;34893.127671808405;35480;35487;35557.644423555954;35661.64338356635;9961.900380996241;10066.748331291776;10156.085952264331;10213.642522511826;10403.58385664565;8311.459755116019;8335.583220838933;8335.749927502064;8442.66229350819;8453.66185352579;8781.902262217223;9422.434653920649;9549.570269337897;9822.312438129406;9982.850257246148;3334.549835772188;3843.674576880947;3851.3645248533676;3891.3579259422445;3892.9221415572097;788.6056971514242;882.4087861133056;915.5056269614333;954.1412728544227;970.9416735758058;17710.557236069202;21288.36134915955;21432.678509822366;21490.749737264876;21527.354179374644;884.7390019944132;899.9370044096981;942.2179590939504;944.8441007233866;987.5013118375318;9193.816123677489;11342.376169310603;11343.20597558163;11355.26190797612;11398.632164140356;607470;607992;608377;609724;636989;104401;144511;148900;151555;154354;937.5827756648226;972.6838777397375;1000.1298869983161;1001.7095042437611;1005.4771518810178;18965.525861853013;19603.8039619602;19669.328107111054;19714;19730.60538789245;55361;57301.42698572961;57359.713201433726;58294.7085264571;59210;68817.3118268811;83861;85264;85504.14495854962;87194.564027182;7784.610769461391;8078.757637270891;8125.309348705427;8231.218034286732;8272.710455134042;12289.1397602167;12759.553415630407;12810.74378512406;12992.480300787862;13042.282674452996;10808.837867432152;10890;11164;11498.77002459963;11584.304941703525;10792.406417647106;10821;10943.264183954012;11120.888791111985;11340;11058.336499810037;11209.71975700614;11242.437878106226;11408.372539510407;11590.768184636428;6888.380045795902;7350.4854660175415;8010.5154257898;8108.432409731262;8109.47288426274;10280.434576098087;10899.237053406185;11003.834942475709;11096.722581935515;11324.886751132382 03:43:19 INFO - PROCESS | 1647 | |3;mootools.html;1389.2220356600305;1450.9625617683357;1472.6907349456615;1757.5957529768161;1764.6735353959511;7490.96254518728;7822.921770782291;8038.758837234885;8521.19048690375;8555.957220213899;7926.762197134095;8292.212239837221;8325.875111873327;8401.91598084019;8416.537090460022;2278.897449614767;2323.0591610397732;2324.0122947747222;2325.4535184231777;2332.4285550040263;2320.93037208884;2350.2949115265437;2368.9813380246487;2373.6320870265135;2381.6189409694452;2258.4918393361527;2305.9769402306015;2311.630139177732;2311.66480860276;2339.4034521197073;1729.541671457065;1849.3619701203063;1913.7416448779516;2282.7603101674335;2320.953580928372;2325.430269583946;2358.2217868103485;2358.7287461941924;2359.433735903388;2362.208660098852;8395.916040839575;8410.285125764318;8449.57752112392;8474.23731864129;8478.194571515696;4519.954800451987;5079.542841144278;5227.79088836446;5234.371875374979;5241.42344342122;3241.2707140893112;3289.9835500822583;3372.865085396581;3435.4675025371107;3626.0934766308424;1039.3555995282973;1056.3820165203365;1083.8049151152868;1104.0119093411352;1111.972200694981;11028.558857645685;11065.612703555336;11124.38815865127;11172.162087843362;11184.720381990433;3918.040080180317;4599.425071866017;4682.672212945129;4688.757479267997;4727.267273572602;9519.857202141973;9561.235101191887;9568.186704130227;9579.329446938647;9632.90367096334;5203.401608814959;5209.244659524347;5216.087184742731;5217.087009773274;5230.764615592308;4246.8725938221905;4276.593723596254;4696.01383709418;4739.3838800955655;4783.497732738082;3795.6014618464665;3822.1782316802023;3897.1231472918366;3927.23418933311;3927.9607203927594;1259.6724851538665;1263.867293934142;1267.9556215532411;1279.1238001968682;1279.9552015679405;824.1799409587499;888.3071204460531;902.7336935604012;913.6797327861177;926.2775035472342;5683.772649094072;5731.484166425042;5789.739461724233;5880.588358814927;5995.550333724988;424.4418589554726;442.219482613188;446.7810772721408;450.22561194744986;452.9026259354255;3823.904402389907;3979.820908059144;4063.9433747225935;4071.450354202145;4091.6112969267874;9816.067473589997;10310.484475776182;10319.380837149794;10342.638007669695;10353.948230258951;9597.664081757104;9916.504174791376;10113.898861011296;10272.486375681186;10295.07344339013;7800.882986755203;7913.32736717374;8002.679892804339;8070.071941726659;8071.071826739882;5703.486686198262;5841.532677385884;5858.794942177004;5893.823185304448;5900.734466948912;7817.609119543887;9060.682876099305;9340.766480837761;9399.295052870926;9546.52267386614;9400.388974716621;9564.426134431957;9572.282078843975;9581.664641737505;9615.615375384907;5284.445133260952;6560.081588577507;6879.105716256855;7168.2831716827895;7339.853202935805;7846.215378462108;8041.597920104088;8081.232282933112;8087.919120808717;8095.757127286191;7083.858322833411;7226.566406015656;7238.167610724623;7245.601491917784;7326.157491888288;7011.579305241702;7029;7102.964485177541;7173.210946795744;7229.09636295463;5859.179714839841;5996.37038110992;6593.769218077345;6738.157730283715;6746.86506269862;4896.828610998598;4918.360613120271;4918.950810491992;4944.431390390151;4973.776180071978;4848;4875.463698993179;4886.804527818847;4968.677035992649;5035.446100928968 03:43:19 INFO - PROCESS | 1647 | |4;prototype.html;233.9087755775247;267.21172541004034;310.31110933727126;311.8487533546231;312.5483676088054;5244.9613797376;5372.2210279509445;5418.810341638038;5503.394626591077;5532.9999999999945;4285.864245974819;4414.117176564692;4560.683453417017;5093.872653183672;5377.946220537793;3260.9021729348156;4112.732672376301;4166.916661666764;4176.060386413048;4176.45706058212;3447.3967055765143;3725.864290637404;3728.8254199927073;3776.924461510768;4177.853775117872;3415.573053368329;4021.5174179098403;4027.8187481563255;4045.0494133878515;4061.1877624475073;3989.640932316091;3994.380870964976;4052.615001574846;4059.573744756817;4107.609777071173;2860.856957152134;3245.367153405087;3325.833708314575;3490.89527314181;3491.7555771096036;4121.505419349697;4635.165670179366;4968.801247950078;5244.213367994793;5319;4529.569690879394;4632.559906808848;4640.953590464086;4642.326862604903;4650.86047418578;5475.753591088411;5510.889782204374;5647.604667673265;5652.615109298248;5679.7160141993145;3324.6841550052704;3562.946555801665;3798.8793305974805;3855.306044911915;3860.305145073886;5849.239598852165;5956.583039187259;5968.46283834457;5973.492253158487;6005.4595086442;3518.9443167050295;3539.64603539644;3748.9250214995545;3879.825407856597;3904.90237744053;3912.56961734209;3951.664108550748;3971.4439978403047;3975.1453437511072;3984.541777695602;3765.4351847223134;4615.746133962597;4624.375709279271;4624.72819974503;4660.53394660538;4973.90052198954;5010.323606313101;5713.371529131792;5743.052396354555;5761.135829625506;1054.8681414823147;1225.577175874322;1245.9409501923474;1257.6101408563375;1293.7579923273574;726.3644311227675;750.9549427034286;862.3230763850347;873.5457562067689;882.2544949517647;4014.498187726534;4599.182678376939;4659.8136074557315;5075.767031979488;5140.485951404829;5031.974840125849;5070.797168113234;5206.661566998133;5569.766977097435;5637.013522633605;4076.979615101965;4179;4677.859664210079;5097.311862898461;5140.40885298188;2757.586362045669;2836.971630283712;2882.509973304543;2929.7802664800224;2939.9416210164322;5170.6122040847085;5471.699056551848;5506.889862202814;5683.77264909399;5784.322546461563;4506.684532082788;5098.745062746848;5497.725113744297;5668.206451096849;6097.512199024068;5294.41761406245;5411.891762164813;5745.597808153473;6163.537734669918;6167.352427995085;4430.363933459586;5952.15483200192;5975.312839023568;5984.815542327106;5988.694352674164;4641.512641172763;5790.726040271134;5828.260717462786;5840.357560668408;5864.67744274069;5582.916256256241;5668.8095499945475;6107.511399087974;6117.134274046459;6150.630962142286;3168.920776980594;3801.334766415811;3908.6482216600643;3942.4086387042175;3958.267720471722;5636.9718151410625;5855.7364918579615;5868.3251426084835;5898.675572843535;5915.40845915405;5074.82238121664;5685.857853553694;5763.11844077961;5803.47768700819;5803.564732644984;4800.591949684316;4980.900381992412;5727.021948586795;5791.117886686928;5797.420257974169;4917.852464426073;5391.433899440659;5466.91799622998;5626.64960409507;5747.396523365153;4825.782839772149;5241.31862857826;5288.862894477552;5390.245365648734;5802.332731735904;4905.77923993414;5635.9436405635415;5647.661140331593;5661.886762264814;5685.175649530712 03:43:19 INFO - PROCESS | 1647 | |5;yui.html;591.2742258189744;660.9537332386735;671.251554516714;781.2109769133174;783.64735868859;14072.929635351822;15010.699786004274;15052;15067.6986460271;15068.171250581214;13460.66348341292;13580.456781728744;13592.796108058363;13594.320283985786;13660.385282662279;650.9837254068663;811.3711873298197;824.6618886256636;825.8018075661828;828.3580225325361;662.2648859765657;798.7603718884341;824.7154731617612;841.1630427724423;845.3575282785097;855.7774978505571;865.338016417438;876.5441970175505;877.7937184761576;878.0341624213378;867.8394497018072;867.9435836670659;869.0527325215483;870.1298701298701;874.038557586656;634.5653227539151;643.0964494884695;646.9094326794205;647.773279352223;649.4122818848949;13166.73666526664;13388.598342049656;13598.660033499144;13765.862341376558;13797.172169669851;10962.30084336929;12543.372831358487;12857.485700571966;13258.00564957632;13318.600441986757;11060.668179954615;11356.034737047361;11412.885871141265;11416.828747568794;11467.713307167303;3560;3570;3573.6783689468075;3690.1881586051027;3699.2857962797707;87220;91272;91737.54131229367;91988.54005729995;92960;8548.529830859363;9964.501774911225;10235;10414.375137491776;10687.679369618923;19571;19580.314688985818;19736.40790776279;19823.702644460343;19826.50433739139;7478.588677622674;7506.361959233417;7553.962230188814;7558.622068896643;7567.659455324524;3870.206607645482;4192.182524407711;4225.260579398654;4273.743575385487;4288.699791014599;2534.6831646044243;3477.7217822574135;3490.441529355291;3493.283876805249;3505.2814173094453;539.8542393553719;542.4819297570889;645.7223393940651;664.1498881431775;664.4717449627558;477.5892732250262;553.5460922043966;608.4919092557764;645.0227904724346;665.3878431842717;8197.46716463428;8943.3739638226;8948.955255223682;9047.638094476148;9052.759872967996;587.4301927130676;593.5904226083989;594.2097010975478;631.823089534931;650.6844180572418;4105.876823695294;4213.873583792491;4454.265046267395;4562.58936695699;4668.85993420198;96510.51744741232;97047.51476242574;105996.46208736883;106375;106875;93360;98294.5085274569;98644.01355986348;100673;103002;57623.42376576264;75357.86963195556;78162;84777.15222847815;89161.9999999987;9307.76730581741;9340.252779777466;9540.475273860004;9554.28342874301;9574.425534467955;56408;56420;57125.857482850945;57833.4216657828;57868.71065644645;77011.61494192717;77214.61392693226;77872;80281;83188.16811831805;12059.57791477294;12266.509339626315;12460.439280232593;12577.748445030866;12607.180533265308;13596.98022648325;13996.580102596938;14279.857201427852;14754.04098733579;14936.327865245872;13283.535076272285;13300.268485233406;13712.108712933627;13862.306884655525;13879.444822206999;13020.609381718185;13230.206187628773;13383.39774710121;13824.101433406797;13966.30168491551;12418.441170147184;12513.687157821127;12887.420066097236;13464.528741494001;13511.527096551574;9520.571574279313;10622.8406573903;11114.722131946766;11516.021138203347;11639.941800290944;10734.248602597743;10977.451127443435;11251.212415130862;11318.773624527299;11458.656240312805 03:43:19 INFO - PROCESS | 1647 | __end_tp_report 03:43:19 INFO - PROCESS | 1647 | __start_cc_report 03:43:19 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,370 03:43:19 INFO - PROCESS | 1647 | __end_cc_report 03:43:19 INFO - PROCESS | 1647 | __startTimestamp1480678999112__endTimestamp 03:43:19 INFO - PROCESS | 1647 | 03:43:19 INFO - PROCESS | 1647 | ------- Summary: start ------- 03:43:19 INFO - PROCESS | 1647 | Number of tests: 6 03:43:19 INFO - PROCESS | 1647 | 03:43:19 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5678.74 Median:5915.08 stddev:687.67 (11.6%) stddev-sans-first:650.69 03:43:19 INFO - PROCESS | 1647 | Values: 2765.3 3284.9 3344.7 3351.1 3374.1 4249.7 5627.8 5655.5 5693.6 5826.4 5757.9 5783.1 5796.9 5800.0 5814.0 5210.6 5232.0 5242.9 5277.0 5313.0 5279.0 5305.9 5311.1 5314.0 5341.7 5285.2 5291.2 5294.1 5326.7 5329.6 5138.4 5177.9 5224.5 5262.3 5312.9 5212.7 5213.8 5215.6 5234.1 5351.1 5145.8 5872.2 5899.6 5911.2 5924.9 6052.9 6117.8 6170.0 6266.1 6273.6 6160.6 6168.1 6185.5 6207.4 6224.2 5807.0 5843.5 5858.5 5884.9 5905.0 6034.0 6249.8 6294.7 6306.5 6323.9 4456.3 4519.8 4890.0 4951.3 6070.2 5263.8 6186.8 6247.1 6256.8 6260.7 4911.4 6179.2 6217.3 6239.6 6239.6 5331.7 6131.8 6146.6 6170.1 6177.5 4813.9 4889.9 4949.7 4953.8 4994.3 3714.8 4335.2 4407.4 4414.5 4423.3 5195.8 5305.8 6006.8 6015.8 6039.4 6216.1 6218.7 6237.6 6238.4 6244.8 5807.6 5867.9 5870.6 5915.1 5917.2 5405.4 5408.1 5417.5 5417.5 5433.1 6218.8 6221.6 6242.9 6286.8 6289.7 6205.8 6230.8 6232.5 6253.1 6281.6 5755.6 6357.9 6382.8 6384.2 6390.6 6139.0 6139.8 6140.9 6159.8 6250.2 6121.7 6140.2 6199.0 6207.2 6274.4 5902.6 6061.1 6066.3 6081.4 6107.3 4963.8 6061.4 6081.1 6086.4 6117.3 5822.9 5880.2 6002.6 6015.8 6024.8 6115.7 6127.2 6127.6 6130.2 6134.8 5876.4 5954.7 5956.6 5959.9 5993.5 03:43:19 INFO - PROCESS | 1647 | 03:43:19 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:67614.97 Median:38660.40 stddev:150500.14 (389.3%) stddev-sans-first:150837.89 03:43:19 INFO - PROCESS | 1647 | Values: 469.4 505.3 525.6 546.2 546.6 27233.9 34526.5 37206.0 39304.8 39322.6 31854.7 40417.4 40454.8 40492.8 40513.8 8043.4 8985.6 9214.7 9415.6 9453.1 9367.8 9473.0 9503.5 9503.5 9522.6 9477.1 9498.3 9499.7 9518.0 9542.4 9409.2 9441.4 9452.4 9479.2 9486.4 9350.4 9373.2 9417.9 9426.6 9434.0 38236.0 38597.4 38648.2 38659.8 38661.0 3298.7 3391.6 3527.6 3588.4 3882.9 29039.1 29281.6 29861.4 29884.0 30187.5 3132.2 3149.2 3200.8 3225.0 3260.3 409911.0 490643.0 493662.0 497264.0 498068.0 55534.4 55679.7 55745.4 55877.4 56220.0 57989.0 57994.0 58436.0 58514.4 58598.7 14948.6 15129.1 15138.2 15187.5 15208.5 11817.1 11825.5 11928.2 11929.0 11959.6 13791.7 14668.8 14785.9 14811.8 15001.9 6877.2 6991.0 7048.7 7074.4 7088.6 784.8 818.8 931.8 938.3 958.3 25806.6 26033.5 26089.1 29688.0 29974.9 958.4 1002.5 1002.8 1043.4 1097.0 14684.0 14742.5 14833.3 14940.6 14947.3 555281.0 575139.0 595098.0 609287.0 671833.0 42587.9 52143.0 53484.0 53608.5 53707.7 1183.9 1325.7 1344.2 1350.2 1355.6 24778.8 26754.3 27525.0 27600.0 28327.7 46559.0 47282.5 47340.8 47387.3 47429.0 536638.0 604045.0 610286.0 610568.0 616453.0 24727.5 25068.7 25085.9 25092.7 25148.0 42396.6 42472.7 42565.9 43392.6 43846.3 34284.3 35933.5 36152.0 36369.5 36903.8 35590.3 35823.0 36367.6 36780.8 37066.0 26302.0 29957.6 34259.7 34369.5 34657.7 31721.4 32472.4 32888.8 32921.0 32955.8 23373.2 24416.9 24637.0 24691.8 24752.6 03:43:19 INFO - PROCESS | 1647 | 03:43:19 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:37844.56 Median:14518.99 stddev:102952.70 (709.1%) stddev-sans-first:103202.90 03:43:19 INFO - PROCESS | 1647 | Values: 292.0 322.5 360.8 376.6 379.6 18317.8 19039.5 20196.1 20327.4 20330.4 19699.1 19776.9 19976.6 20055.9 20369.9 5221.2 5732.8 6033.5 6045.5 6056.8 5151.0 5162.7 5163.9 5934.1 5951.6 5812.2 5890.5 5892.7 5914.0 5919.4 6314.5 7151.7 7538.6 7539.1 7609.1 7006.7 7309.4 7352.2 7427.4 7433.2 21932.7 28268.9 28449.9 28610.9 28746.0 2324.5 2532.3 2653.1 2676.3 2729.5 15707.4 16602.0 17063.9 17159.0 17284.0 1494.5 1900.6 1935.5 1962.5 2009.0 102615.0 132908.0 136284.3 140263.0 149660.3 18968.9 21176.0 21718.1 22065.0 22573.0 34893.1 35480.0 35487.0 35557.6 35661.6 9961.9 10066.7 10156.1 10213.6 10403.6 8311.5 8335.6 8335.7 8442.7 8453.7 8781.9 9422.4 9549.6 9822.3 9982.9 3334.5 3843.7 3851.4 3891.4 3892.9 788.6 882.4 915.5 954.1 970.9 17710.6 21288.4 21432.7 21490.7 21527.4 884.7 899.9 942.2 944.8 987.5 9193.8 11342.4 11343.2 11355.3 11398.6 607470.0 607992.0 608377.0 609724.0 636989.0 104401.0 144511.0 148900.0 151555.0 154354.0 937.6 972.7 1000.1 1001.7 1005.5 18965.5 19603.8 19669.3 19714.0 19730.6 55361.0 57301.4 57359.7 58294.7 59210.0 68817.3 83861.0 85264.0 85504.1 87194.6 7784.6 8078.8 8125.3 8231.2 8272.7 12289.1 12759.6 12810.7 12992.5 13042.3 10808.8 10890.0 11164.0 11498.8 11584.3 10792.4 10821.0 10943.3 11120.9 11340.0 11058.3 11209.7 11242.4 11408.4 11590.8 6888.4 7350.5 8010.5 8108.4 8109.5 10280.4 10899.2 11003.8 11096.7 11324.9 03:43:19 INFO - PROCESS | 1647 | 03:43:19 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5456.61 Median:4971.23 stddev:3032.06 (61.0%) stddev-sans-first:3025.16 03:43:19 INFO - PROCESS | 1647 | Values: 1389.2 1451.0 1472.7 1757.6 1764.7 7491.0 7822.9 8038.8 8521.2 8556.0 7926.8 8292.2 8325.9 8401.9 8416.5 2278.9 2323.1 2324.0 2325.5 2332.4 2320.9 2350.3 2369.0 2373.6 2381.6 2258.5 2306.0 2311.6 2311.7 2339.4 1729.5 1849.4 1913.7 2282.8 2321.0 2325.4 2358.2 2358.7 2359.4 2362.2 8395.9 8410.3 8449.6 8474.2 8478.2 4520.0 5079.5 5227.8 5234.4 5241.4 3241.3 3290.0 3372.9 3435.5 3626.1 1039.4 1056.4 1083.8 1104.0 1112.0 11028.6 11065.6 11124.4 11172.2 11184.7 3918.0 4599.4 4682.7 4688.8 4727.3 9519.9 9561.2 9568.2 9579.3 9632.9 5203.4 5209.2 5216.1 5217.1 5230.8 4246.9 4276.6 4696.0 4739.4 4783.5 3795.6 3822.2 3897.1 3927.2 3928.0 1259.7 1263.9 1268.0 1279.1 1280.0 824.2 888.3 902.7 913.7 926.3 5683.8 5731.5 5789.7 5880.6 5995.6 424.4 442.2 446.8 450.2 452.9 3823.9 3979.8 4063.9 4071.5 4091.6 9816.1 10310.5 10319.4 10342.6 10353.9 9597.7 9916.5 10113.9 10272.5 10295.1 7800.9 7913.3 8002.7 8070.1 8071.1 5703.5 5841.5 5858.8 5893.8 5900.7 7817.6 9060.7 9340.8 9399.3 9546.5 9400.4 9564.4 9572.3 9581.7 9615.6 5284.4 6560.1 6879.1 7168.3 7339.9 7846.2 8041.6 8081.2 8087.9 8095.8 7083.9 7226.6 7238.2 7245.6 7326.2 7011.6 7029.0 7103.0 7173.2 7229.1 5859.2 5996.4 6593.8 6738.2 6746.9 4896.8 4918.4 4919.0 4944.4 4973.8 4848.0 4875.5 4886.8 4968.7 5035.4 03:43:19 INFO - PROCESS | 1647 | 03:43:19 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4499.31 Median:4995.61 stddev:1430.72 (28.6%) stddev-sans-first:1398.45 03:43:19 INFO - PROCESS | 1647 | Values: 233.9 267.2 310.3 311.8 312.5 5245.0 5372.2 5418.8 5503.4 5533.0 4285.9 4414.1 4560.7 5093.9 5377.9 3260.9 4112.7 4166.9 4176.1 4176.5 3447.4 3725.9 3728.8 3776.9 4177.9 3415.6 4021.5 4027.8 4045.0 4061.2 3989.6 3994.4 4052.6 4059.6 4107.6 2860.9 3245.4 3325.8 3490.9 3491.8 4121.5 4635.2 4968.8 5244.2 5319.0 4529.6 4632.6 4641.0 4642.3 4650.9 5475.8 5510.9 5647.6 5652.6 5679.7 3324.7 3562.9 3798.9 3855.3 3860.3 5849.2 5956.6 5968.5 5973.5 6005.5 3518.9 3539.6 3748.9 3879.8 3904.9 3912.6 3951.7 3971.4 3975.1 3984.5 3765.4 4615.7 4624.4 4624.7 4660.5 4973.9 5010.3 5713.4 5743.1 5761.1 1054.9 1225.6 1245.9 1257.6 1293.8 726.4 751.0 862.3 873.5 882.3 4014.5 4599.2 4659.8 5075.8 5140.5 5032.0 5070.8 5206.7 5569.8 5637.0 4077.0 4179.0 4677.9 5097.3 5140.4 2757.6 2837.0 2882.5 2929.8 2939.9 5170.6 5471.7 5506.9 5683.8 5784.3 4506.7 5098.7 5497.7 5668.2 6097.5 5294.4 5411.9 5745.6 6163.5 6167.4 4430.4 5952.2 5975.3 5984.8 5988.7 4641.5 5790.7 5828.3 5840.4 5864.7 5582.9 5668.8 6107.5 6117.1 6150.6 3168.9 3801.3 3908.6 3942.4 3958.3 5637.0 5855.7 5868.3 5898.7 5915.4 5074.8 5685.9 5763.1 5803.5 5803.6 4800.6 4980.9 5727.0 5791.1 5797.4 4917.9 5391.4 5466.9 5626.6 5747.4 4825.8 5241.3 5288.9 5390.2 5802.3 4905.8 5635.9 5647.7 5661.9 5685.2 03:43:19 INFO - PROCESS | 1647 | 03:43:19 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:20495.23 Median:4615.72 stddev:29828.09 (646.2%) stddev-sans-first:29874.33 03:43:19 INFO - PROCESS | 1647 | Values: 591.3 661.0 671.3 781.2 783.6 14072.9 15010.7 15052.0 15067.7 15068.2 13460.7 13580.5 13592.8 13594.3 13660.4 651.0 811.4 824.7 825.8 828.4 662.3 798.8 824.7 841.2 845.4 855.8 865.3 876.5 877.8 878.0 867.8 867.9 869.1 870.1 874.0 634.6 643.1 646.9 647.8 649.4 13166.7 13388.6 13598.7 13765.9 13797.2 10962.3 12543.4 12857.5 13258.0 13318.6 11060.7 11356.0 11412.9 11416.8 11467.7 3560.0 3570.0 3573.7 3690.2 3699.3 87220.0 91272.0 91737.5 91988.5 92960.0 8548.5 9964.5 10235.0 10414.4 10687.7 19571.0 19580.3 19736.4 19823.7 19826.5 7478.6 7506.4 7554.0 7558.6 7567.7 3870.2 4192.2 4225.3 4273.7 4288.7 2534.7 3477.7 3490.4 3493.3 3505.3 539.9 542.5 645.7 664.1 664.5 477.6 553.5 608.5 645.0 665.4 8197.5 8943.4 8949.0 9047.6 9052.8 587.4 593.6 594.2 631.8 650.7 4105.9 4213.9 4454.3 4562.6 4668.9 96510.5 97047.5 105996.5 106375.0 106875.0 93360.0 98294.5 98644.0 100673.0 103002.0 57623.4 75357.9 78162.0 84777.2 89162.0 9307.8 9340.3 9540.5 9554.3 9574.4 56408.0 56420.0 57125.9 57833.4 57868.7 77011.6 77214.6 77872.0 80281.0 83188.2 12059.6 12266.5 12460.4 12577.7 12607.2 13597.0 13996.6 14279.9 14754.0 14936.3 13283.5 13300.3 13712.1 13862.3 13879.4 13020.6 13230.2 13383.4 13824.1 13966.3 12418.4 12513.7 12887.4 13464.5 13511.5 9520.6 10622.8 11114.7 11516.0 11639.9 10734.2 10977.5 11251.2 11318.8 11458.7 03:43:19 INFO - PROCESS | 1647 | -------- Summary: end -------- 03:43:19 INFO - PROCESS | 1647 | 03:43:19 INFO - TEST-INFO | 1647: exit 0 03:43:19 INFO - TEST-OK | dromaeo_css | took 1117905ms 03:43:19 INFO - TEST-START | kraken 03:43:19 INFO - operating with platform_type : mac_ 03:43:19 INFO - Initialising browser for kraken test... 03:43:19 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpXkGezv/profile http://localhost:49238/getInfo.html) 03:43:22 INFO - PROCESS | 1679 | 2016-12-02 03:43:22.772 plugin-container[1681:10988] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 03:43:22 INFO - PROCESS | 1679 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:43:22 INFO - PROCESS | 1679 | 2016-12-02 03:43:22.773 plugin-container[1681:10988] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4623, name = 'com.apple.CFPasteboardClient' 03:43:22 INFO - PROCESS | 1679 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:43:22 INFO - PROCESS | 1679 | 2016-12-02 03:43:22.773 plugin-container[1681:10988] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:43:22 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 03:43:22 INFO - PROCESS | 1679 | colorDepth:24 03:43:22 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 03:43:22 INFO - PROCESS | 1679 | __metrics 03:43:22 ERROR - PROCESS | 1679 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 03:43:23 INFO - TEST-INFO | 1679: exit 0 03:43:23 INFO - Browser initialized. 03:43:23 INFO - Running cycle 1/1 for kraken test... 03:43:23 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpXkGezv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:43:26 INFO - PROCESS | 1682 | 2016-12-02 03:43:26.830 plugin-container[1683:11109] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a33, name = 'com.apple.tsm.portname' 03:43:26 INFO - PROCESS | 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:43:26 INFO - PROCESS | 1682 | 2016-12-02 03:43:26.830 plugin-container[1683:11109] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 03:43:26 INFO - PROCESS | 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:43:26 INFO - PROCESS | 1682 | 2016-12-02 03:43:26.830 plugin-container[1683:11109] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:43:36 INFO - PROCESS | 1682 | RSS: Main: 229548032 03:43:36 INFO - PROCESS | 1682 | 03:43:39 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection) 03:43:40 INFO - PROCESS | 1682 | RSS: Main: 241598464 03:43:40 INFO - PROCESS | 1682 | 03:43:42 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-dft) 03:43:43 INFO - PROCESS | 1682 | RSS: Main: 249143296 03:43:43 INFO - PROCESS | 1682 | 03:43:46 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-fft) 03:43:47 INFO - PROCESS | 1682 | RSS: Main: 252825600 03:43:47 INFO - PROCESS | 1682 | 03:43:49 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator) 03:43:49 INFO - PROCESS | 1682 | RSS: Main: 253448192 03:43:49 INFO - PROCESS | 1682 | 03:43:51 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:43:52 INFO - PROCESS | 1682 | RSS: Main: 254558208 03:43:52 INFO - PROCESS | 1682 | 03:43:55 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom) 03:43:56 INFO - PROCESS | 1682 | RSS: Main: 258678784 03:43:56 INFO - PROCESS | 1682 | 03:43:59 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate) 03:43:59 INFO - PROCESS | 1682 | RSS: Main: 258027520 03:43:59 INFO - PROCESS | 1682 | 03:44:03 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial) 03:44:03 INFO - PROCESS | 1682 | RSS: Main: 258301952 03:44:03 INFO - PROCESS | 1682 | 03:44:05 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:44:05 INFO - PROCESS | 1682 | RSS: Main: 257077248 03:44:05 INFO - PROCESS | 1682 | 03:44:07 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:44:08 INFO - PROCESS | 1682 | RSS: Main: 257212416 03:44:08 INFO - PROCESS | 1682 | 03:44:10 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:44:11 INFO - PROCESS | 1682 | RSS: Main: 257257472 03:44:11 INFO - PROCESS | 1682 | 03:44:14 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:44:15 INFO - PROCESS | 1682 | RSS: Main: 257478656 03:44:15 INFO - PROCESS | 1682 | 03:44:18 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:44:18 INFO - PROCESS | 1682 | RSS: Main: 257323008 03:44:18 INFO - PROCESS | 1682 | 03:44:20 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49238/tests/kraken/driver.html?testName=ai-astar) 03:44:20 INFO - PROCESS | 1682 | RSS: Main: 258908160 03:44:20 INFO - PROCESS | 1682 | 03:44:20 INFO - PROCESS | 1682 | __start_tp_report 03:44:20 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 03:44:20 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 03:44:20 INFO - PROCESS | 1682 | |i|pagename|runs| 03:44:20 INFO - PROCESS | 1682 | |0;ai-astar;114;118;119;117;118;117;117;118;116;119 03:44:20 INFO - PROCESS | 1682 | |1;audio-beat-detection;155;137;115;156;106;127;150;148;142;117 03:44:20 INFO - PROCESS | 1682 | |2;audio-dft;196;196;206;199;195;194;196;197;199;199 03:44:20 INFO - PROCESS | 1682 | |3;audio-fft;83;57;78;111;51;84;83;53;96;51 03:44:20 INFO - PROCESS | 1682 | |4;audio-oscillator;84;72;91;87;71;68;86;75;102;124 03:44:20 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;104;125;136;79;130;132;126;86;87;131 03:44:20 INFO - PROCESS | 1682 | |6;imaging-darkroom;94;97;93;132;155;94;97;95;96;112 03:44:20 INFO - PROCESS | 1682 | |7;imaging-desaturate;99;89;135;79;135;141;126;90;88;90 03:44:20 INFO - PROCESS | 1682 | |8;json-parse-financial;56;55;58;61;61;60;60;60;60;57 03:44:20 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;69;75;59;110;68;76;101;104;95;71 03:44:20 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;84;84;100;112;77;96;104;105;80;88 03:44:20 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;145;146;148;144;150;152;153;156;200;155 03:44:20 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;171;172;172;178;239;235;160;220;211;171 03:44:20 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;72;72;70;69;70;70;69;70;71;70 03:44:20 INFO - PROCESS | 1682 | __end_tp_report 03:44:20 INFO - PROCESS | 1682 | __start_cc_report 03:44:20 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,1005 03:44:20 INFO - PROCESS | 1682 | __end_cc_report 03:44:20 INFO - PROCESS | 1682 | __startTimestamp1480679060899__endTimestamp 03:44:20 INFO - PROCESS | 1682 | 03:44:20 INFO - PROCESS | 1682 | ------- Summary: start ------- 03:44:20 INFO - PROCESS | 1682 | Number of tests: 14 03:44:20 INFO - PROCESS | 1682 | 03:44:20 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:117.30 Median:118.00 stddev:1.49 (1.3%) stddev-sans-first:1.00 03:44:20 INFO - PROCESS | 1682 | Values: 114.0 118.0 119.0 117.0 118.0 117.0 117.0 118.0 116.0 119.0 03:44:20 INFO - PROCESS | 1682 | 03:44:20 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:135.30 Median:145.00 stddev:18.00 (12.4%) stddev-sans-first:17.62 03:44:20 INFO - PROCESS | 1682 | Values: 155.0 137.0 115.0 156.0 106.0 127.0 150.0 148.0 142.0 117.0 03:44:20 INFO - PROCESS | 1682 | 03:44:20 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:197.70 Median:198.00 stddev:3.40 (1.7%) stddev-sans-first:3.55 03:44:20 INFO - PROCESS | 1682 | Values: 196.0 196.0 206.0 199.0 195.0 194.0 196.0 197.0 199.0 199.0 03:44:20 INFO - PROCESS | 1682 | 03:44:20 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:74.70 Median:80.50 stddev:20.85 (25.9%) stddev-sans-first:21.90 03:44:20 INFO - PROCESS | 1682 | Values: 83.0 57.0 78.0 111.0 51.0 84.0 83.0 53.0 96.0 51.0 03:44:20 INFO - PROCESS | 1682 | 03:44:20 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:86.00 Median:79.50 stddev:16.98 (21.4%) stddev-sans-first:18.00 03:44:20 INFO - PROCESS | 1682 | Values: 84.0 72.0 91.0 87.0 71.0 68.0 86.0 75.0 102.0 124.0 03:44:20 INFO - PROCESS | 1682 | 03:44:20 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:113.60 Median:134.00 stddev:22.25 (16.6%) stddev-sans-first:23.32 03:44:20 INFO - PROCESS | 1682 | Values: 104.0 125.0 136.0 79.0 130.0 132.0 126.0 86.0 87.0 131.0 03:44:20 INFO - PROCESS | 1682 | 03:44:20 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:106.50 Median:94.50 stddev:20.95 (22.2%) stddev-sans-first:21.73 03:44:20 INFO - PROCESS | 1682 | Values: 94.0 97.0 93.0 132.0 155.0 94.0 97.0 95.0 96.0 112.0 03:44:20 INFO - PROCESS | 1682 | 03:44:20 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:107.20 Median:88.50 stddev:24.03 (27.1%) stddev-sans-first:25.30 03:44:20 INFO - PROCESS | 1682 | Values: 99.0 89.0 135.0 79.0 135.0 141.0 126.0 90.0 88.0 90.0 03:44:20 INFO - PROCESS | 1682 | 03:44:20 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:60.00 stddev:2.15 (3.6%) stddev-sans-first:2.03 03:44:20 INFO - PROCESS | 1682 | Values: 56.0 55.0 58.0 61.0 61.0 60.0 60.0 60.0 60.0 57.0 03:44:20 INFO - PROCESS | 1682 | 03:44:20 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:82.80 Median:70.00 stddev:17.92 (25.6%) stddev-sans-first:18.30 03:44:20 INFO - PROCESS | 1682 | Values: 69.0 75.0 59.0 110.0 68.0 76.0 101.0 104.0 95.0 71.0 03:44:20 INFO - PROCESS | 1682 | 03:44:20 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:82.00 stddev:12.00 (14.6%) stddev-sans-first:12.28 03:44:20 INFO - PROCESS | 1682 | Values: 84.0 84.0 100.0 112.0 77.0 96.0 104.0 105.0 80.0 88.0 03:44:20 INFO - PROCESS | 1682 | 03:44:20 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:154.90 Median:152.50 stddev:16.38 (10.7%) stddev-sans-first:16.98 03:44:20 INFO - PROCESS | 1682 | Values: 145.0 146.0 148.0 144.0 150.0 152.0 153.0 156.0 200.0 155.0 03:44:20 INFO - PROCESS | 1682 | 03:44:20 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.90 Median:194.50 stddev:29.99 (15.4%) stddev-sans-first:30.75 03:44:20 INFO - PROCESS | 1682 | Values: 171.0 172.0 172.0 178.0 239.0 235.0 160.0 220.0 211.0 171.0 03:44:20 INFO - PROCESS | 1682 | 03:44:20 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:70.00 stddev:1.06 (1.5%) stddev-sans-first:0.93 03:44:20 INFO - PROCESS | 1682 | Values: 72.0 72.0 70.0 69.0 70.0 70.0 69.0 70.0 71.0 70.0 03:44:20 INFO - PROCESS | 1682 | -------- Summary: end -------- 03:44:20 INFO - PROCESS | 1682 | 03:44:21 INFO - TEST-INFO | 1682: exit 0 03:44:21 INFO - TEST-OK | kraken | took 62197ms 03:44:21 INFO - SUITE-END | took 1180s 03:44:22 INFO - Completed test suite (00:19:40) 03:44:22 INFO - javascript benchmark 03:44:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7247.629092512617, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2765.253381586971, 3284.9178770530734, 3344.6822551857563, 3351.1287065363, 3374.1227280906955, 4249.707795126921, 5627.831165065049, 5655.5475561955045, 5693.629914055588, 5826.359100498942, 5757.942420575793, 5783.103618939061, 5796.942030579704, 5800.0, 5814.0, 5210.635255532124, 5232.032074066291, 5242.895142097156, 5277.023750606131, 5312.990531798965, 5279.0, 5305.946940530584, 5311.097113490697, 5313.9734301328435, 5341.706206158659, 5285.180796976456, 5291.153415453528, 5294.10000299948, 5326.6537675051195, 5329.600279978998, 5138.3577052868395, 5177.922331165036, 5224.52979231871, 5262.315898933152, 5312.946870531284, 5212.687238765686, 5213.817516386908, 5215.6349055566125, 5234.057869583474, 5351.063563876306, 5145.819896303611, 5872.207252021008, 5899.646021238739, 5911.201987731687, 5924.851878703023, 6052.939470605282, 6117.796956032686, 6169.969150154266, 6266.122742816009, 6273.62358258506, 6160.599561028519, 6168.074788781719, 6185.505159587222, 6207.379262073801, 6224.221972253468, 5807.0, 5843.5033022193165, 5858.502027327725, 5884.882302353929, 5905.025670764362, 6034.034554471351, 6249.812505624838, 6294.65379404128, 6306.49548036156, 6323.924932761442, 4456.286994080931, 4519.847438903124, 4890.0, 4951.331570238044, 6070.210872586624, 5263.7894484220205, 6186.783462578788, 6247.094171345129, 6256.781012664626, 6260.655663938527, 4911.435184953704, 6179.196704428396, 6217.285012223652, 6239.594426362272, 6239.625622462576, 5331.70675612845, 6131.8354600871235, 6146.555559443527, 6170.074488826712, 6177.505799536027, 4813.9037219256115, 4889.853304400874, 4949.678270912451, 4953.7523123844385, 4994.300797888226, 3714.814259287025, 4335.241332766753, 4407.4050003249795, 4414.536473670282, 4423.314386270134, 5195.792168313301, 5305.761240744098, 6006.849828754229, 6015.819525414245, 6039.396060394013, 6216.067589861557, 6218.689065546704, 6237.563370564108, 6238.407351301619, 6244.781432649836, 5807.5644326675665, 5867.853303667357, 5870.55970802183, 5915.08316210988, 5917.230760001173, 5405.405405405402, 5408.107662235766, 5417.485338892799, 5417.539509141767, 5433.130699088127, 6218.782342617987, 6221.626702397871, 6242.875142497124, 6286.8428289291305, 6289.716962736779, 6205.751769929333, 6230.7507699691505, 6232.501399887908, 6253.093301471352, 6281.560290779785, 5755.5971082025535, 6357.904631430625, 6382.840428989312, 6384.201974753156, 6390.5526613136635, 6139.0, 6139.815805525842, 6140.938590614215, 6159.84600384994, 6250.1874756281395, 6121.666376154927, 6140.228185505611, 6198.977168767103, 6207.16203312547, 6274.372562743689, 5902.6458412495385, 6061.121137435135, 6066.272047354346, 6081.391860813883, 6107.328193898578, 4963.776630051584, 6061.36355682647, 6081.087836824512, 6086.421789929949, 6117.296510901126, 5822.912656310074, 5880.176775251553, 6002.6398416093425, 6015.789447369321, 6024.759009639565, 6115.694215289307, 6127.234095738033, 6127.571070025234, 6130.172426722514, 6134.7852825152695, 5876.3536011037895, 5954.672493012926, 5956.5532585055425, 5959.880802383841, 5993.490553303018], "value": 5634.638639031062, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.4319872953726, 505.2698850161516, 525.5952916254485, 546.2270886695323, 546.5518275014489, 27233.863830680843, 34526.48210276848, 37206.0, 39304.80347598262, 39322.60677393225, 31854.68145318546, 40417.39373909394, 40454.797726011326, 40492.79753601235, 40513.79743101288, 8043.436959412844, 8985.607230879225, 9214.723558293263, 9415.62337506499, 9453.054694530561, 9367.81264374712, 9473.0, 9503.477308748015, 9503.524823758818, 9522.57148428319, 9477.14705676489, 9498.28762842786, 9499.715008549754, 9517.952410237938, 9542.379745316566, 9409.200217981446, 9441.386309889835, 9452.385594936375, 9479.194268487186, 9486.430814151174, 9350.392224505453, 9373.203277721403, 9417.858732119024, 9426.622935082589, 9434.0, 38236.0, 38597.42103868444, 38648.227035459415, 38659.8067009666, 38661.0, 3298.719608833252, 3391.6269210386836, 3527.6472352764777, 3588.372034893909, 3882.9223415531815, 29039.12882613525, 29281.56077658837, 29861.43717719464, 29883.954061607958, 30187.547186792217, 3132.183333666468, 3149.165471150147, 3200.8399580020905, 3224.951625725616, 3260.2990357073104, 409911.0, 490643.0, 493662.0, 497264.0, 498068.0, 55534.444655553736, 55679.721601391735, 55745.442545574835, 55877.44122558722, 56220.0, 57989.0, 57994.0, 58436.0, 58514.41485585175, 58598.7070064647, 14948.626284342761, 15129.092254464767, 15138.243087845784, 15187.544373668808, 15208.46770363032, 11817.113716471298, 11825.526978920747, 11928.224665396721, 11929.0, 11959.641210763692, 13791.724165516633, 14668.826493880464, 14785.852141478663, 14811.777823332659, 15001.87485938537, 6877.1747390313485, 6990.965045174741, 7048.718051277891, 7074.363307302265, 7088.574685518885, 784.7684932944795, 818.8444195602816, 931.8043210925781, 938.2681508423441, 958.2908647600725, 25806.612900806504, 26033.479330413666, 26089.08688195904, 29688.0, 29974.85012574967, 958.3962103874514, 1002.4586723169552, 1002.8245057114975, 1043.3739756146251, 1096.9743290023853, 14684.0, 14742.484013059491, 14833.332500037524, 14940.551783446514, 14947.327370268364, 555281.0, 575139.0, 595098.0, 609287.0, 671833.0, 42587.935301617086, 52143.0, 53484.0, 53608.46391536113, 53707.731461342446, 1183.9052875769917, 1325.6685828542863, 1344.2069179184132, 1350.1898860683707, 1355.5662188099714, 24778.752212478365, 26754.331141721614, 27525.0, 27600.033998809944, 28327.71672283251, 46559.0, 47282.52717472781, 47340.7632961833, 47387.28919066286, 47429.0, 536638.0, 604044.9797750983, 610286.0, 610568.0, 616453.0, 24727.50544989126, 25068.749312506643, 25085.87457062776, 25092.745362731424, 25148.0, 42396.57603423926, 42472.7258182255, 42565.93585160396, 43392.56607433885, 43846.34230486609, 34284.31431371309, 35933.46099808453, 36152.0, 36369.45445818262, 36903.81548092242, 35590.28819423545, 35823.0, 36367.63632363643, 36780.81609591935, 37066.0, 26302.0, 29957.55063674003, 34259.65740342565, 34369.484457733655, 34657.65342346645, 31721.36557268888, 32472.35055298928, 32888.835555822065, 32921.0, 32955.83522082374, 23373.181938632068, 24416.90123944462, 24637.00187482525, 24691.753082468946, 24752.632782744546], "value": 18820.576076480225, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.99124026279213, 322.52910750304557, 360.75468681296724, 376.5707093912938, 379.62986088563656, 18317.816821831777, 19039.52401189969, 20196.091175897083, 20327.390178294674, 20330.390088297358, 19699.113539890703, 19776.90111549444, 19976.600467990633, 20055.899720501377, 20369.898150509227, 5221.242919776649, 5732.828015159542, 6033.5173186145, 6045.486133678643, 6056.818295451144, 5151.0, 5162.664426812264, 5163.948360516384, 5934.139549765281, 5951.553633477485, 5812.244408226925, 5890.528757699395, 5892.675902825343, 5913.970430147843, 5919.37846526115, 6314.494840412756, 7151.678174482135, 7538.623068846536, 7539.132999705022, 7609.0869095708595, 7006.7197312107455, 7309.378702810268, 7352.154502232205, 7427.368673662745, 7433.219511951222, 21932.684038957712, 28268.858655706794, 28449.857750711322, 28610.85694571514, 28745.98778085535, 2324.4970038493443, 2532.2529853693204, 2653.0714250012534, 2676.2907829425217, 2729.5359788835945, 15707.371705131782, 16602.0, 17063.91468042652, 17159.0, 17284.0, 1494.454524098707, 1900.591372854829, 1935.5451468904796, 1962.4995626115392, 2008.9698654520193, 102615.0, 132908.0, 136284.31857840746, 140263.0, 149660.25169874082, 18968.905155474135, 21176.0, 21718.090743666293, 22065.0, 22573.0, 34893.127671808405, 35480.0, 35487.0, 35557.644423555954, 35661.64338356635, 9961.900380996241, 10066.748331291776, 10156.085952264331, 10213.642522511826, 10403.58385664565, 8311.459755116019, 8335.583220838933, 8335.749927502064, 8442.66229350819, 8453.66185352579, 8781.902262217223, 9422.434653920649, 9549.570269337897, 9822.312438129406, 9982.850257246148, 3334.549835772188, 3843.674576880947, 3851.3645248533676, 3891.3579259422445, 3892.9221415572097, 788.6056971514242, 882.4087861133056, 915.5056269614333, 954.1412728544227, 970.9416735758058, 17710.557236069202, 21288.36134915955, 21432.678509822366, 21490.749737264876, 21527.354179374644, 884.7390019944132, 899.9370044096981, 942.2179590939504, 944.8441007233866, 987.5013118375318, 9193.816123677489, 11342.376169310603, 11343.20597558163, 11355.26190797612, 11398.632164140356, 607470.0, 607992.0, 608377.0, 609724.0, 636989.0, 104401.0, 144511.0, 148900.0, 151555.0, 154354.0, 937.5827756648226, 972.6838777397375, 1000.1298869983161, 1001.7095042437611, 1005.4771518810178, 18965.525861853013, 19603.8039619602, 19669.328107111054, 19714.0, 19730.60538789245, 55361.0, 57301.42698572961, 57359.713201433726, 58294.7085264571, 59210.0, 68817.3118268811, 83861.0, 85264.0, 85504.14495854962, 87194.564027182, 7784.610769461391, 8078.757637270891, 8125.309348705427, 8231.218034286732, 8272.710455134042, 12289.1397602167, 12759.553415630407, 12810.74378512406, 12992.480300787862, 13042.282674452996, 10808.837867432152, 10890.0, 11164.0, 11498.77002459963, 11584.304941703525, 10792.406417647106, 10821.0, 10943.264183954012, 11120.888791111985, 11340.0, 11058.336499810037, 11209.71975700614, 11242.437878106226, 11408.372539510407, 11590.768184636428, 6888.380045795902, 7350.4854660175415, 8010.5154257898, 8108.432409731262, 8109.47288426274, 10280.434576098087, 10899.237053406185, 11003.834942475709, 11096.722581935515, 11324.886751132382], "value": 10805.634725053582, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1389.2220356600305, 1450.9625617683357, 1472.6907349456615, 1757.5957529768161, 1764.6735353959511, 7490.96254518728, 7822.921770782291, 8038.758837234885, 8521.19048690375, 8555.957220213899, 7926.762197134095, 8292.212239837221, 8325.875111873327, 8401.91598084019, 8416.537090460022, 2278.897449614767, 2323.0591610397732, 2324.0122947747222, 2325.4535184231777, 2332.4285550040263, 2320.93037208884, 2350.2949115265437, 2368.9813380246487, 2373.6320870265135, 2381.6189409694452, 2258.4918393361527, 2305.9769402306015, 2311.630139177732, 2311.66480860276, 2339.4034521197073, 1729.541671457065, 1849.3619701203063, 1913.7416448779516, 2282.7603101674335, 2320.953580928372, 2325.430269583946, 2358.2217868103485, 2358.7287461941924, 2359.433735903388, 2362.208660098852, 8395.916040839575, 8410.285125764318, 8449.57752112392, 8474.23731864129, 8478.194571515696, 4519.954800451987, 5079.542841144278, 5227.79088836446, 5234.371875374979, 5241.42344342122, 3241.2707140893112, 3289.9835500822583, 3372.865085396581, 3435.4675025371107, 3626.0934766308424, 1039.3555995282973, 1056.3820165203365, 1083.8049151152868, 1104.0119093411352, 1111.972200694981, 11028.558857645685, 11065.612703555336, 11124.38815865127, 11172.162087843362, 11184.720381990433, 3918.040080180317, 4599.425071866017, 4682.672212945129, 4688.757479267997, 4727.267273572602, 9519.857202141973, 9561.235101191887, 9568.186704130227, 9579.329446938647, 9632.90367096334, 5203.401608814959, 5209.244659524347, 5216.087184742731, 5217.087009773274, 5230.764615592308, 4246.8725938221905, 4276.593723596254, 4696.01383709418, 4739.3838800955655, 4783.497732738082, 3795.6014618464665, 3822.1782316802023, 3897.1231472918366, 3927.23418933311, 3927.9607203927594, 1259.6724851538665, 1263.867293934142, 1267.9556215532411, 1279.1238001968682, 1279.9552015679405, 824.1799409587499, 888.3071204460531, 902.7336935604012, 913.6797327861177, 926.2775035472342, 5683.772649094072, 5731.484166425042, 5789.739461724233, 5880.588358814927, 5995.550333724988, 424.4418589554726, 442.219482613188, 446.7810772721408, 450.22561194744986, 452.9026259354255, 3823.904402389907, 3979.820908059144, 4063.9433747225935, 4071.450354202145, 4091.6112969267874, 9816.067473589997, 10310.484475776182, 10319.380837149794, 10342.638007669695, 10353.948230258951, 9597.664081757104, 9916.504174791376, 10113.898861011296, 10272.486375681186, 10295.07344339013, 7800.882986755203, 7913.32736717374, 8002.679892804339, 8070.071941726659, 8071.071826739882, 5703.486686198262, 5841.532677385884, 5858.794942177004, 5893.823185304448, 5900.734466948912, 7817.609119543887, 9060.682876099305, 9340.766480837761, 9399.295052870926, 9546.52267386614, 9400.388974716621, 9564.426134431957, 9572.282078843975, 9581.664641737505, 9615.615375384907, 5284.445133260952, 6560.081588577507, 6879.105716256855, 7168.2831716827895, 7339.853202935805, 7846.215378462108, 8041.597920104088, 8081.232282933112, 8087.919120808717, 8095.757127286191, 7083.858322833411, 7226.566406015656, 7238.167610724623, 7245.601491917784, 7326.157491888288, 7011.579305241702, 7029.0, 7102.964485177541, 7173.210946795744, 7229.09636295463, 5859.179714839841, 5996.37038110992, 6593.769218077345, 6738.157730283715, 6746.86506269862, 4896.828610998598, 4918.360613120271, 4918.950810491992, 4944.431390390151, 4973.776180071978, 4848.0, 4875.463698993179, 4886.804527818847, 4968.677035992649, 5035.446100928968], "value": 4340.251606381619, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [233.9087755775247, 267.21172541004034, 310.31110933727126, 311.8487533546231, 312.5483676088054, 5244.9613797376, 5372.2210279509445, 5418.810341638038, 5503.394626591077, 5532.9999999999945, 4285.864245974819, 4414.117176564692, 4560.683453417017, 5093.872653183672, 5377.946220537793, 3260.9021729348156, 4112.732672376301, 4166.916661666764, 4176.060386413048, 4176.45706058212, 3447.3967055765143, 3725.864290637404, 3728.8254199927073, 3776.924461510768, 4177.853775117872, 3415.573053368329, 4021.5174179098403, 4027.8187481563255, 4045.0494133878515, 4061.1877624475073, 3989.640932316091, 3994.380870964976, 4052.615001574846, 4059.573744756817, 4107.609777071173, 2860.856957152134, 3245.367153405087, 3325.833708314575, 3490.89527314181, 3491.7555771096036, 4121.505419349697, 4635.165670179366, 4968.801247950078, 5244.213367994793, 5319.0, 4529.569690879394, 4632.559906808848, 4640.953590464086, 4642.326862604903, 4650.86047418578, 5475.753591088411, 5510.889782204374, 5647.604667673265, 5652.615109298248, 5679.7160141993145, 3324.6841550052704, 3562.946555801665, 3798.8793305974805, 3855.306044911915, 3860.305145073886, 5849.239598852165, 5956.583039187259, 5968.46283834457, 5973.492253158487, 6005.4595086442, 3518.9443167050295, 3539.64603539644, 3748.9250214995545, 3879.825407856597, 3904.90237744053, 3912.56961734209, 3951.664108550748, 3971.4439978403047, 3975.1453437511072, 3984.541777695602, 3765.4351847223134, 4615.746133962597, 4624.375709279271, 4624.72819974503, 4660.53394660538, 4973.90052198954, 5010.323606313101, 5713.371529131792, 5743.052396354555, 5761.135829625506, 1054.8681414823147, 1225.577175874322, 1245.9409501923474, 1257.6101408563375, 1293.7579923273574, 726.3644311227675, 750.9549427034286, 862.3230763850347, 873.5457562067689, 882.2544949517647, 4014.498187726534, 4599.182678376939, 4659.8136074557315, 5075.767031979488, 5140.485951404829, 5031.974840125849, 5070.797168113234, 5206.661566998133, 5569.766977097435, 5637.013522633605, 4076.979615101965, 4179.0, 4677.859664210079, 5097.311862898461, 5140.40885298188, 2757.586362045669, 2836.971630283712, 2882.509973304543, 2929.7802664800224, 2939.9416210164322, 5170.6122040847085, 5471.699056551848, 5506.889862202814, 5683.77264909399, 5784.322546461563, 4506.684532082788, 5098.745062746848, 5497.725113744297, 5668.206451096849, 6097.512199024068, 5294.41761406245, 5411.891762164813, 5745.597808153473, 6163.537734669918, 6167.352427995085, 4430.363933459586, 5952.15483200192, 5975.312839023568, 5984.815542327106, 5988.694352674164, 4641.512641172763, 5790.726040271134, 5828.260717462786, 5840.357560668408, 5864.67744274069, 5582.916256256241, 5668.8095499945475, 6107.511399087974, 6117.134274046459, 6150.630962142286, 3168.920776980594, 3801.334766415811, 3908.6482216600643, 3942.4086387042175, 3958.267720471722, 5636.9718151410625, 5855.7364918579615, 5868.3251426084835, 5898.675572843535, 5915.40845915405, 5074.82238121664, 5685.857853553694, 5763.11844077961, 5803.47768700819, 5803.564732644984, 4800.591949684316, 4980.900381992412, 5727.021948586795, 5791.117886686928, 5797.420257974169, 4917.852464426073, 5391.433899440659, 5466.91799622998, 5626.64960409507, 5747.396523365153, 4825.782839772149, 5241.31862857826, 5288.862894477552, 5390.245365648734, 5802.332731735904, 4905.77923993414, 5635.9436405635415, 5647.661140331593, 5661.886762264814, 5685.175649530712], "value": 4033.2322963624442, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.2742258189744, 660.9537332386735, 671.251554516714, 781.2109769133174, 783.64735868859, 14072.929635351822, 15010.699786004274, 15052.0, 15067.6986460271, 15068.171250581214, 13460.66348341292, 13580.456781728744, 13592.796108058363, 13594.320283985786, 13660.385282662279, 650.9837254068663, 811.3711873298197, 824.6618886256636, 825.8018075661828, 828.3580225325361, 662.2648859765657, 798.7603718884341, 824.7154731617612, 841.1630427724423, 845.3575282785097, 855.7774978505571, 865.338016417438, 876.5441970175505, 877.7937184761576, 878.0341624213378, 867.8394497018072, 867.9435836670659, 869.0527325215483, 870.1298701298701, 874.038557586656, 634.5653227539151, 643.0964494884695, 646.9094326794205, 647.773279352223, 649.4122818848949, 13166.73666526664, 13388.598342049656, 13598.660033499144, 13765.862341376558, 13797.172169669851, 10962.30084336929, 12543.372831358487, 12857.485700571966, 13258.00564957632, 13318.600441986757, 11060.668179954615, 11356.034737047361, 11412.885871141265, 11416.828747568794, 11467.713307167303, 3560.0, 3570.0, 3573.6783689468075, 3690.1881586051027, 3699.2857962797707, 87220.0, 91272.0, 91737.54131229367, 91988.54005729995, 92960.0, 8548.529830859363, 9964.501774911225, 10235.0, 10414.375137491776, 10687.679369618923, 19571.0, 19580.314688985818, 19736.40790776279, 19823.702644460343, 19826.50433739139, 7478.588677622674, 7506.361959233417, 7553.962230188814, 7558.622068896643, 7567.659455324524, 3870.206607645482, 4192.182524407711, 4225.260579398654, 4273.743575385487, 4288.699791014599, 2534.6831646044243, 3477.7217822574135, 3490.441529355291, 3493.283876805249, 3505.2814173094453, 539.8542393553719, 542.4819297570889, 645.7223393940651, 664.1498881431775, 664.4717449627558, 477.5892732250262, 553.5460922043966, 608.4919092557764, 645.0227904724346, 665.3878431842717, 8197.46716463428, 8943.3739638226, 8948.955255223682, 9047.638094476148, 9052.759872967996, 587.4301927130676, 593.5904226083989, 594.2097010975478, 631.823089534931, 650.6844180572418, 4105.876823695294, 4213.873583792491, 4454.265046267395, 4562.58936695699, 4668.85993420198, 96510.51744741232, 97047.51476242574, 105996.46208736883, 106375.0, 106875.0, 93360.0, 98294.5085274569, 98644.01355986348, 100673.0, 103002.0, 57623.42376576264, 75357.86963195556, 78162.0, 84777.15222847815, 89161.9999999987, 9307.76730581741, 9340.252779777466, 9540.475273860004, 9554.28342874301, 9574.425534467955, 56408.0, 56420.0, 57125.857482850945, 57833.4216657828, 57868.71065644645, 77011.61494192717, 77214.61392693226, 77872.0, 80281.0, 83188.16811831805, 12059.57791477294, 12266.509339626315, 12460.439280232593, 12577.748445030866, 12607.180533265308, 13596.98022648325, 13996.580102596938, 14279.857201427852, 14754.04098733579, 14936.327865245872, 13283.535076272285, 13300.268485233406, 13712.108712933627, 13862.306884655525, 13879.444822206999, 13020.609381718185, 13230.206187628773, 13383.39774710121, 13824.101433406797, 13966.30168491551, 12418.441170147184, 12513.687157821127, 12887.420066097236, 13464.528741494001, 13511.527096551574, 9520.571574279313, 10622.8406573903, 11114.722131946766, 11516.021138203347, 11639.941800290944, 10734.248602597743, 10977.451127443435, 11251.212415130862, 11318.773624527299, 11458.656240312805], "value": 7224.531688975787, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1591.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 118.0, 119.0, 117.0, 118.0, 117.0, 117.0, 118.0, 116.0, 119.0], "value": 117.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 137.0, 115.0, 156.0, 106.0, 127.0, 150.0, 148.0, 142.0, 117.0], "value": 135.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 196.0, 206.0, 199.0, 195.0, 194.0, 196.0, 197.0, 199.0, 199.0], "value": 197.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 57.0, 78.0, 111.0, 51.0, 84.0, 83.0, 53.0, 96.0, 51.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 72.0, 91.0, 87.0, 71.0, 68.0, 86.0, 75.0, 102.0, 124.0], "value": 86.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 125.0, 136.0, 79.0, 130.0, 132.0, 126.0, 86.0, 87.0, 131.0], "value": 113.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 93.0, 132.0, 155.0, 94.0, 97.0, 95.0, 96.0, 112.0], "value": 106.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 89.0, 135.0, 79.0, 135.0, 141.0, 126.0, 90.0, 88.0, 90.0], "value": 107.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 58.0, 61.0, 61.0, 60.0, 60.0, 60.0, 60.0, 57.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 75.0, 59.0, 110.0, 68.0, 76.0, 101.0, 104.0, 95.0, 71.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 100.0, 112.0, 77.0, 96.0, 104.0, 105.0, 80.0, 88.0], "value": 93.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 148.0, 144.0, 150.0, 152.0, 153.0, 156.0, 200.0, 155.0], "value": 154.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 172.0, 178.0, 239.0, 235.0, 160.0, 220.0, 211.0, 171.0], "value": 192.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 70.0, 69.0, 70.0, 70.0, 69.0, 70.0, 71.0, 70.0], "value": 70.3, "unit": "score"}]}]} 03:44:22 INFO - Return code: 0 03:44:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:44:22 ERROR - # TBPL SUCCESS # 03:44:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:44:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:44:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:44:22 INFO - 2016-12-02 03:44:22.244 screenresolution[1687:11483] starting screenresolution argv=screenresolution get 03:44:22 INFO - 2016-12-02 03:44:22.247 screenresolution[1687:11483] Display 0: 1600x1200x32@60 03:44:22 INFO - 2016-12-02 03:44:22.253 screenresolution[1688:11486] starting screenresolution argv=screenresolution list 03:44:22 INFO - Available Modes on Display 0 03:44:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:44:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:44:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:44:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:44:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:44:22 INFO - Intel Iris: 03:44:22 INFO - Chipset Model: Intel Iris 03:44:22 INFO - Type: GPU 03:44:22 INFO - Bus: Built-In 03:44:22 INFO - VRAM (Dynamic, Max): 1536 MB 03:44:22 INFO - Vendor: Intel (0x8086) 03:44:22 INFO - Device ID: 0x0a2e 03:44:22 INFO - Revision ID: 0x0009 03:44:22 INFO - Displays: 03:44:22 INFO - Display: 03:44:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:44:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:44:22 INFO - Display Serial Number: 1600x1200 60 03:44:22 INFO - Main Display: Yes 03:44:22 INFO - Mirror: Off 03:44:22 INFO - Online: Yes 03:44:22 INFO - Rotation: Supported 03:44:22 INFO - Return code: 0 03:44:22 INFO - Running post-action listener: _resource_record_post_action 03:44:22 INFO - [mozharness: 2016-12-02 11:44:22.496776Z] Finished run-tests step (success) 03:44:22 INFO - Running post-run listener: _resource_record_post_run 03:44:22 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 34893824; Write bytes: 658038272; Read time: 326; Write time: 1991 03:44:22 INFO - TinderboxPrint: CPU usage
54.4% 03:44:22 INFO - TinderboxPrint: I/O read bytes / time
34,893,824 / 326 03:44:22 INFO - TinderboxPrint: I/O write bytes / time
658,038,272 / 1,991 03:44:22 INFO - TinderboxPrint: CPU idle
2,208.2 (45.6%) 03:44:22 INFO - TinderboxPrint: CPU system
985.2 (20.3%) 03:44:22 INFO - TinderboxPrint: CPU user
1,652.3 (34.1%) 03:44:22 INFO - TinderboxPrint: Swap in / out
380,149,760 / 0 03:44:22 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 332476928; Write bytes: 334133248; Read time: 25648; Write time: 724 03:44:22 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 33984512; Write bytes: 306169344; Read time: 313; Write time: 1213 03:44:22 INFO - Running post-run listener: _upload_blobber_files 03:44:22 INFO - Blob upload gear active. 03:44:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:44:22 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 03:44:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:44:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:44:23 INFO - (blobuploader) - INFO - Open directory for files ... 03:44:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:44:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:44:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:44:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:44:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:44:24 INFO - (blobuploader) - INFO - Done attempting. 03:44:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:44:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:44:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:44:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:44:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:44:25 INFO - (blobuploader) - INFO - Done attempting. 03:44:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:44:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:44:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:44:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:44:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:44:27 INFO - (blobuploader) - INFO - Done attempting. 03:44:27 INFO - (blobuploader) - INFO - Iteration through files over. 03:44:27 INFO - Return code: 0 03:44:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:44:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:44:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2725727d9685b76c9d565576079280074b7d8788839392e8c954c5f74c572df3acc5266fcc74757140c331e356c6c4df85fd1dbc1c20ee736de80bb7e19c6da6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/41b126addd5a44254c6168d5022d3251163efe0a3fb2a4d9d1db8811ba5cc09621c037a84a8f5bb7ca75e47f309d4adae4d661e215f32f45bd15d1b1c01cb461", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c25b8eef40e0c2c0b8f0ba9669fd4b8ebe25aebb4366d2b912918124cfa9a07292316e97e96369bbf288a487e28048da572186492787c31c48b2a31fc277f875"} 03:44:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:44:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:44:27 INFO - Contents: 03:44:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2725727d9685b76c9d565576079280074b7d8788839392e8c954c5f74c572df3acc5266fcc74757140c331e356c6c4df85fd1dbc1c20ee736de80bb7e19c6da6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/41b126addd5a44254c6168d5022d3251163efe0a3fb2a4d9d1db8811ba5cc09621c037a84a8f5bb7ca75e47f309d4adae4d661e215f32f45bd15d1b1c01cb461", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c25b8eef40e0c2c0b8f0ba9669fd4b8ebe25aebb4366d2b912918124cfa9a07292316e97e96369bbf288a487e28048da572186492787c31c48b2a31fc277f875"} 03:44:27 INFO - Running post-run listener: copy_logs_to_upload_dir 03:44:27 INFO - Copying logs to upload dir... 03:44:27 INFO - mkdir: /builds/slave/test/build/upload/logs 03:44:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.115496 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-02 03:44:27.515213) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-02 03:44:27.518419) ========= 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.PxYlp8oQDz/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.WFaChyWfBj/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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/Mozilla-Aurora/sha512/2725727d9685b76c9d565576079280074b7d8788839392e8c954c5f74c572df3acc5266fcc74757140c331e356c6c4df85fd1dbc1c20ee736de80bb7e19c6da6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/41b126addd5a44254c6168d5022d3251163efe0a3fb2a4d9d1db8811ba5cc09621c037a84a8f5bb7ca75e47f309d4adae4d661e215f32f45bd15d1b1c01cb461", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c25b8eef40e0c2c0b8f0ba9669fd4b8ebe25aebb4366d2b912918124cfa9a07292316e97e96369bbf288a487e28048da572186492787c31c48b2a31fc277f875"} build_url:https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011131 build_url: 'https://queue.taskcluster.net/v1/task/Y-fbpuFcQZWU-2teRRBDqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2725727d9685b76c9d565576079280074b7d8788839392e8c954c5f74c572df3acc5266fcc74757140c331e356c6c4df85fd1dbc1c20ee736de80bb7e19c6da6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/41b126addd5a44254c6168d5022d3251163efe0a3fb2a4d9d1db8811ba5cc09621c037a84a8f5bb7ca75e47f309d4adae4d661e215f32f45bd15d1b1c01cb461", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c25b8eef40e0c2c0b8f0ba9669fd4b8ebe25aebb4366d2b912918124cfa9a07292316e97e96369bbf288a487e28048da572186492787c31c48b2a31fc277f875"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-02 03:44:27.551725) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-02 03:44:27.552050) ========= 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.PxYlp8oQDz/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.WFaChyWfBj/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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.005162 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-02 03:44:27.600995) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-02 03:44:27.601345) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-02 03:44:27.601682) ========= ========= Total master_lag: 0.18 =========