builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0210 starttime: 1479986102.73 results: success (0) buildid: 20161124020338 builduid: 9edfea74f9b444a3bb8da94f5aeee042 revision: 7f611a717df6bf178b2cab2da4ef8c005f80da8a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:02.726423) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:02.726862) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:02.727162) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:02.760852) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:02.761220) ========= 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.lIm8b1BQ6j/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.iiGhUfoHz4/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-24 03:15:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2016-11-24 03:15:03 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.516144 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:03.301669) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:03.302030) ========= 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.lIm8b1BQ6j/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.iiGhUfoHz4/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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.023855 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:03.350738) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-24 03:15:03.351105) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7f611a717df6bf178b2cab2da4ef8c005f80da8a --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 7f611a717df6bf178b2cab2da4ef8c005f80da8a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lIm8b1BQ6j/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.iiGhUfoHz4/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-24 03:15:03,449 truncating revision to first 12 chars 2016-11-24 03:15:03,450 Setting DEBUG logging. 2016-11-24 03:15:03,450 attempt 1/10 2016-11-24 03:15:03,450 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7f611a717df6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-24 03:15:04,152 unpacking tar archive at: mozilla-aurora-7f611a717df6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.995306 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-24 03:15:04.369701) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:04.370053) ========= script_repo_revision: 7f611a717df6bf178b2cab2da4ef8c005f80da8a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:04.370434) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:04.370695) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:04.390491) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-11-24 03:15:04.390823) ========= /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.lIm8b1BQ6j/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.iiGhUfoHz4/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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:15:04 INFO - MultiFileLogger online at 20161124 03:15:04 in /builds/slave/test 03:15:04 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:15:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:15:04 INFO - {'append_to_log': False, 03:15:04 INFO - 'base_work_dir': '/builds/slave/test', 03:15:04 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:15:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:15:04 INFO - 'branch': 'Mozilla-Aurora', 03:15:04 INFO - 'buildbot_json_path': 'buildprops.json', 03:15:04 INFO - 'config_files': ('talos/mac_config.py',), 03:15:04 INFO - 'default_actions': ('clobber', 03:15:04 INFO - 'read-buildbot-config', 03:15:04 INFO - 'download-and-extract', 03:15:04 INFO - 'populate-webroot', 03:15:04 INFO - 'create-virtualenv', 03:15:04 INFO - 'install', 03:15:04 INFO - 'run-tests'), 03:15:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:15:04 INFO - 'download_minidump_stackwalk': True, 03:15:04 INFO - 'download_symbols': 'ondemand', 03:15:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:15:04 INFO - 'tooltool.py': '/tools/tooltool.py', 03:15:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:15:04 INFO - '/tools/misc-python/virtualenv.py')}, 03:15:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:15:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:15:04 INFO - 'installer_path': 'installer.exe', 03:15:04 INFO - 'log_level': 'info', 03:15:04 INFO - 'log_name': 'talos', 03:15:04 INFO - 'log_to_console': True, 03:15:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:15:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:15:04 INFO - 'opt_config_files': (), 03:15:04 INFO - 'pip_index': False, 03:15:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:15:04 INFO - 'cmd': ('bash', 03:15:04 INFO - '-c', 03:15:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:15:04 INFO - 'enabled': True, 03:15:04 INFO - 'halt_on_failure': False, 03:15:04 INFO - 'name': 'check_screen_resolution'},), 03:15:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:15:04 INFO - 'cmd': ('bash', 03:15:04 INFO - '-c', 03:15:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:15:04 INFO - 'enabled': True, 03:15:04 INFO - 'halt_on_failure': False, 03:15:04 INFO - 'name': 'check_screen_resolution'},), 03:15:04 INFO - 'run_cmd_checks_enabled': True, 03:15:04 INFO - 'sps_profile': False, 03:15:04 INFO - 'sps_profile_interval': 0, 03:15:04 INFO - 'suite': 'dromaeojs-e10s', 03:15:04 INFO - 'system_bits': '32', 03:15:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:15:04 INFO - 'title': 't-yosemite-r7-0210', 03:15:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:15:04 INFO - 'use_talos_json': True, 03:15:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:15:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:15:04 INFO - 'work_dir': 'build'} 03:15:04 INFO - [mozharness: 2016-11-24 11:15:04.596785Z] Running clobber step. 03:15:04 INFO - Running pre-action listener: _resource_record_pre_action 03:15:04 INFO - Running main action method: clobber 03:15:04 INFO - rmtree: /builds/slave/test/build 03:15:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:15:06 INFO - Running post-action listener: _resource_record_post_action 03:15:06 INFO - [mozharness: 2016-11-24 11:15:06.877768Z] Finished clobber step (success) 03:15:06 INFO - [mozharness: 2016-11-24 11:15:06.877902Z] Running read-buildbot-config step. 03:15:06 INFO - Running pre-action listener: _resource_record_pre_action 03:15:06 INFO - Running main action method: read_buildbot_config 03:15:06 INFO - Using buildbot properties: 03:15:06 INFO - { 03:15:06 INFO - "project": "", 03:15:06 INFO - "product": "firefox", 03:15:06 INFO - "script_repo_revision": "production", 03:15:06 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 03:15:06 INFO - "repository": "", 03:15:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 03:15:06 INFO - "buildid": "20161124020338", 03:15:06 INFO - "pgo_build": "False", 03:15:06 INFO - "basedir": "/builds/slave/test", 03:15:06 INFO - "buildnumber": 4, 03:15:06 INFO - "slavename": "t-yosemite-r7-0210", 03:15:06 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:15:06 INFO - "platform": "yosemite_r7", 03:15:06 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:15:06 INFO - "branch": "mozilla-aurora", 03:15:06 INFO - "repo_path": "releases/mozilla-aurora", 03:15:06 INFO - "revision": "7f611a717df6bf178b2cab2da4ef8c005f80da8a", 03:15:06 INFO - "stage_platform": "macosx64", 03:15:06 INFO - "builduid": "9edfea74f9b444a3bb8da94f5aeee042", 03:15:06 INFO - "slavebuilddir": "test" 03:15:06 INFO - } 03:15:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:15:06 INFO - Running post-action listener: _resource_record_post_action 03:15:06 INFO - [mozharness: 2016-11-24 11:15:06.881126Z] Finished read-buildbot-config step (success) 03:15:06 INFO - [mozharness: 2016-11-24 11:15:06.881242Z] Running download-and-extract step. 03:15:06 INFO - Running pre-action listener: _resource_record_pre_action 03:15:06 INFO - Running main action method: download_and_extract 03:15:06 INFO - mkdir: /builds/slave/test/build/tests 03:15:06 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:15:06 INFO - trying https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:15:06 INFO - Downloading https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/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:15:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/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:15:08 INFO - Downloaded 1199 bytes. 03:15:08 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:15:08 INFO - Using the following test package requirements: 03:15:08 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:15:08 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:15:08 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:15:08 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:15:08 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:15:08 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:15:08 INFO - u'jsshell-mac.zip'], 03:15:08 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:15:08 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:15:08 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:15:08 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:15:08 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:15:08 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:15:08 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:15:08 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:15:08 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:15:08 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:15:08 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:15:08 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:15:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:15:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:15:08 INFO - Fetch https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:15:08 INFO - Http code: 200 03:15:08 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 03:15:08 INFO - x-amz-cf-id: -LZLNzfSN7DlLvc2q6HH6ix_Qw80YVhX-TPGIjRMozz0BMZfgQzBHQ== 03:15:08 INFO - x-amz-version-id: .JkhS4NWSfW97BSkDFQ8csygiCCMciA6 03:15:09 INFO - Expected file size: 16278237 03:15:09 INFO - Obtained file size: 16278237 03:15:15 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:15:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:15:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:15:15 INFO - Fetch https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:15:16 INFO - Http code: 200 03:15:16 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 03:15:16 INFO - x-amz-cf-id: f5lLSFAstibftF4QhNzMg5OlefsiGitu2hPPyN2LEX-urOO_M57ajQ== 03:15:16 INFO - x-amz-version-id: .JkhS4NWSfW97BSkDFQ8csygiCCMciA6 03:15:16 INFO - Expected file size: 16278237 03:15:16 INFO - Obtained file size: 16278237 03:15:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:15:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:15:23 INFO - Fetch https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:15:24 INFO - Http code: 200 03:15:24 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 03:15:24 INFO - x-amz-cf-id: RB3KtHK3_3CYlh71W1qQ15DBGBynzoxNkVsnMz1wqWFmyAb6-_5lSw== 03:15:24 INFO - x-amz-version-id: ryLXl2Who.UWJ3mcswxZJ3J_NeAsAePn 03:15:24 INFO - Expected file size: 14409038 03:15:24 INFO - Obtained file size: 14409038 03:15:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:24 INFO - trying https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:15:24 INFO - Downloading https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:15:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/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:15:27 INFO - Downloaded 109697047 bytes. 03:15:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:15:27 INFO - mkdir: /builds/slave/test/properties 03:15:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:15:27 INFO - Writing to file /builds/slave/test/properties/build_url 03:15:27 INFO - Contents: 03:15:27 INFO - build_url:https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:15:28 INFO - Running post-action listener: _resource_record_post_action 03:15:28 INFO - Running post-action listener: set_extra_try_arguments 03:15:28 INFO - [mozharness: 2016-11-24 11:15:28.570971Z] Finished download-and-extract step (success) 03:15:28 INFO - [mozharness: 2016-11-24 11:15:28.571136Z] Running populate-webroot step. 03:15:28 INFO - Running pre-action listener: _resource_record_pre_action 03:15:28 INFO - Running main action method: populate_webroot 03:15:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:15:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:15:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:15:28 INFO - '--tppagecycles', 03:15:28 INFO - '7'], 03:15:28 INFO - 'tests': ['tsvgm']}}, 03:15:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:15:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:15:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:15:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:15:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:15:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:15:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:28 INFO - 'talos_options': ['--disable-e10s'], 03:15:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:15:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:15:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:28 INFO - 'talos_options': ['--disable-e10s'], 03:15:28 INFO - 'tests': ['damp', 'tps']}, 03:15:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:28 INFO - 'tests': ['damp', 'tps']}, 03:15:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:15:28 INFO - 'tests': ['dromaeo_dom']}, 03:15:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:15:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:15:28 INFO - 'tests': ['basic_compositor_video']}, 03:15:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:15:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:15:28 INFO - 'tests': ['a11yr', 03:15:28 INFO - 'ts_paint', 03:15:28 INFO - 'tpaint', 03:15:28 INFO - 'sessionrestore', 03:15:28 INFO - 'sessionrestore_no_auto_restore', 03:15:28 INFO - 'tabpaint']}, 03:15:28 INFO - 'other-e10s': {'tests': ['a11yr', 03:15:28 INFO - 'ts_paint', 03:15:28 INFO - 'tpaint', 03:15:28 INFO - 'sessionrestore', 03:15:28 INFO - 'sessionrestore_no_auto_restore', 03:15:28 INFO - 'tabpaint']}, 03:15:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:15:28 INFO - 'ts_paint', 03:15:28 INFO - 'tpaint', 03:15:28 INFO - 'sessionrestore', 03:15:28 INFO - 'sessionrestore_no_auto_restore', 03:15:28 INFO - 'tabpaint']}, 03:15:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:15:28 INFO - 'ts_paint', 03:15:28 INFO - 'tpaint', 03:15:28 INFO - 'sessionrestore', 03:15:28 INFO - 'sessionrestore_no_auto_restore', 03:15:28 INFO - 'tabpaint']}, 03:15:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:15:28 INFO - 'tests': ['a11yr', 03:15:28 INFO - 'ts_paint', 03:15:28 INFO - 'tpaint', 03:15:28 INFO - 'sessionrestore', 03:15:28 INFO - 'sessionrestore_no_auto_restore', 03:15:28 INFO - 'tabpaint']}, 03:15:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:15:28 INFO - 'tests': ['a11yr', 03:15:28 INFO - 'ts_paint', 03:15:28 INFO - 'tpaint', 03:15:28 INFO - 'sessionrestore', 03:15:28 INFO - 'sessionrestore_no_auto_restore', 03:15:28 INFO - 'tabpaint']}, 03:15:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:15:28 INFO - 'tests': ['tsvgx', 03:15:28 INFO - 'tsvgr_opacity', 03:15:28 INFO - 'tart', 03:15:28 INFO - 'tscrollx', 03:15:28 INFO - 'cart']}, 03:15:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:15:28 INFO - 'tsvgr_opacity', 03:15:28 INFO - 'tart', 03:15:28 INFO - 'tscrollx', 03:15:28 INFO - 'cart']}, 03:15:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:28 INFO - 'talos_options': ['--disable-e10s'], 03:15:28 INFO - 'tests': ['tp5o']}, 03:15:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:28 INFO - 'tests': ['tp5o']}, 03:15:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:28 INFO - 'talos_options': ['--disable-e10s', 03:15:28 INFO - '--xperf_path', 03:15:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:15:28 INFO - 'tests': ['tp5n']}, 03:15:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:28 INFO - 'talos_options': ['--xperf_path', 03:15:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:15:28 INFO - 'tests': ['tp5n']}}, 03:15:28 INFO - 'talos.zip': {'path': '', 03:15:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:15:28 INFO - Running post-action listener: _resource_record_post_action 03:15:28 INFO - [mozharness: 2016-11-24 11:15:28.590235Z] Finished populate-webroot step (success) 03:15:28 INFO - [mozharness: 2016-11-24 11:15:28.590374Z] Running create-virtualenv step. 03:15:28 INFO - Running pre-action listener: _resource_record_pre_action 03:15:28 INFO - Running main action method: create_virtualenv 03:15:28 INFO - Creating virtualenv /builds/slave/test/build/venv 03:15:28 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:15:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:15:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:15:28 INFO - Using real prefix '/tools/python27' 03:15:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:15:29 INFO - Installing distribute.............................................................................................................................................................................................done. 03:15:32 INFO - Installing pip.................done. 03:15:32 INFO - Return code: 0 03:15:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:15:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:15:32 INFO - Reading from file tmpfile_stdout 03:15:32 INFO - Output received: 03:15:32 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:15:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10473d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046b3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81e34d3570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104551d30>, '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 0x1043b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104549b20>, '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.lIm8b1BQ6j/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.iiGhUfoHz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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:15:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:15:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:15:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIm8b1BQ6j/Render', 03:15:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:32 INFO - 'HOME': '/Users/cltbld', 03:15:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:32 INFO - 'LOGNAME': 'cltbld', 03:15:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:32 INFO - 'MOZ_NO_REMOTE': '1', 03:15:32 INFO - 'NO_EM_RESTART': '1', 03:15:32 INFO - 'PAGER': '/bin/cat', 03:15:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:32 INFO - 'PWD': '/builds/slave/test', 03:15:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:32 INFO - 'SHELL': '/bin/bash', 03:15:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iiGhUfoHz4/Listeners', 03:15:32 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 03:15:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:32 INFO - 'USER': 'cltbld', 03:15:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:32 INFO - 'XPC_FLAGS': '0x0', 03:15:32 INFO - 'XPC_SERVICE_NAME': '0', 03:15:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:32 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:15:32 INFO - Cleaning up... 03:15:32 INFO - Return code: 0 03:15:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10473d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046b3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81e34d3570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104551d30>, '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 0x1043b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104549b20>, '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.lIm8b1BQ6j/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.iiGhUfoHz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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:15:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:15:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:15:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIm8b1BQ6j/Render', 03:15:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:32 INFO - 'HOME': '/Users/cltbld', 03:15:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:32 INFO - 'LOGNAME': 'cltbld', 03:15:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:32 INFO - 'MOZ_NO_REMOTE': '1', 03:15:32 INFO - 'NO_EM_RESTART': '1', 03:15:32 INFO - 'PAGER': '/bin/cat', 03:15:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:32 INFO - 'PWD': '/builds/slave/test', 03:15:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:32 INFO - 'SHELL': '/bin/bash', 03:15:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iiGhUfoHz4/Listeners', 03:15:32 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 03:15:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:32 INFO - 'USER': 'cltbld', 03:15:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:32 INFO - 'XPC_FLAGS': '0x0', 03:15:32 INFO - 'XPC_SERVICE_NAME': '0', 03:15:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:33 INFO - Downloading/unpacking psutil>=3.1.1 03:15:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:15:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:15:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:15:36 INFO - Installing collected packages: psutil 03:15:36 INFO - Running setup.py install for psutil 03:15:37 INFO - building 'psutil._psutil_osx' extension 03:15:37 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:15:37 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:15:37 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:15:37 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:15:37 INFO - building 'psutil._psutil_posix' extension 03:15:37 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:15:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:15:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:15:37 INFO - ^ 03:15:37 INFO - 1 warning generated. 03:15:37 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:15:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:15:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:15:38 INFO - Successfully installed psutil 03:15:38 INFO - Cleaning up... 03:15:38 INFO - Return code: 0 03:15:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:15:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10473d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046b3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81e34d3570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104551d30>, '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 0x1043b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104549b20>, '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.lIm8b1BQ6j/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.iiGhUfoHz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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:15:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:15:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:15:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIm8b1BQ6j/Render', 03:15:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:38 INFO - 'HOME': '/Users/cltbld', 03:15:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:38 INFO - 'LOGNAME': 'cltbld', 03:15:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:38 INFO - 'MOZ_NO_REMOTE': '1', 03:15:38 INFO - 'NO_EM_RESTART': '1', 03:15:38 INFO - 'PAGER': '/bin/cat', 03:15:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:38 INFO - 'PWD': '/builds/slave/test', 03:15:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:38 INFO - 'SHELL': '/bin/bash', 03:15:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iiGhUfoHz4/Listeners', 03:15:38 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 03:15:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:38 INFO - 'USER': 'cltbld', 03:15:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:38 INFO - 'XPC_FLAGS': '0x0', 03:15:38 INFO - 'XPC_SERVICE_NAME': '0', 03:15:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:15:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:15:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:15:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:15:41 INFO - Installing collected packages: mozsystemmonitor 03:15:41 INFO - Running setup.py install for mozsystemmonitor 03:15:41 INFO - Successfully installed mozsystemmonitor 03:15:41 INFO - Cleaning up... 03:15:41 INFO - Return code: 0 03:15:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:15: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:15:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15: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:15:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15: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', '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 0x10473d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046b3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81e34d3570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104551d30>, '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 0x1043b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104549b20>, '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.lIm8b1BQ6j/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.iiGhUfoHz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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:15: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:15: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 jsonschema==2.5.1 03:15:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIm8b1BQ6j/Render', 03:15:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:41 INFO - 'HOME': '/Users/cltbld', 03:15:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:41 INFO - 'LOGNAME': 'cltbld', 03:15:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:41 INFO - 'MOZ_NO_REMOTE': '1', 03:15:41 INFO - 'NO_EM_RESTART': '1', 03:15:41 INFO - 'PAGER': '/bin/cat', 03:15:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:41 INFO - 'PWD': '/builds/slave/test', 03:15:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:41 INFO - 'SHELL': '/bin/bash', 03:15:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iiGhUfoHz4/Listeners', 03:15:41 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 03:15:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:41 INFO - 'USER': 'cltbld', 03:15:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:41 INFO - 'XPC_FLAGS': '0x0', 03:15:41 INFO - 'XPC_SERVICE_NAME': '0', 03:15:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:41 INFO - Downloading/unpacking jsonschema==2.5.1 03:15:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:15:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:15:43 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:15:43 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:15:43 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:15:43 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:15:43 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:15:43 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:15:44 INFO - Downloading functools32-3.2.3-2.tar.gz 03:15:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:15:44 INFO - warning: no files found matching '*.txt' 03:15:44 INFO - no previously-included directories found matching 'build' 03:15:44 INFO - no previously-included directories found matching 'dist' 03:15:44 INFO - no previously-included directories found matching '.git*' 03:15:44 INFO - Installing collected packages: jsonschema, functools32 03:15:44 INFO - Running setup.py install for functools32 03:15:44 INFO - warning: no files found matching '*.txt' 03:15:44 INFO - no previously-included directories found matching 'build' 03:15:44 INFO - no previously-included directories found matching 'dist' 03:15:44 INFO - no previously-included directories found matching '.git*' 03:15:44 INFO - Successfully installed jsonschema functools32 03:15:44 INFO - Cleaning up... 03:15:44 INFO - Return code: 0 03:15:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:15:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:44 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 0x10473d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046b3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81e34d3570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104551d30>, '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 0x1043b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104549b20>, '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.lIm8b1BQ6j/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.iiGhUfoHz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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:15:44 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:15:44 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:15:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIm8b1BQ6j/Render', 03:15:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:44 INFO - 'HOME': '/Users/cltbld', 03:15:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:44 INFO - 'LOGNAME': 'cltbld', 03:15:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:44 INFO - 'MOZ_NO_REMOTE': '1', 03:15:44 INFO - 'NO_EM_RESTART': '1', 03:15:44 INFO - 'PAGER': '/bin/cat', 03:15:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:44 INFO - 'PWD': '/builds/slave/test', 03:15:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:44 INFO - 'SHELL': '/bin/bash', 03:15:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iiGhUfoHz4/Listeners', 03:15:44 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 03:15:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:44 INFO - 'USER': 'cltbld', 03:15:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:44 INFO - 'XPC_FLAGS': '0x0', 03:15:44 INFO - 'XPC_SERVICE_NAME': '0', 03:15:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:15:44 INFO - Cleaning up... 03:15:44 INFO - Return code: 0 03:15:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:15:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:44 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 0x10473d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046b3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81e34d3570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104551d30>, '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 0x1043b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104549b20>, '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.lIm8b1BQ6j/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.iiGhUfoHz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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:15:44 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:15:44 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:15:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIm8b1BQ6j/Render', 03:15:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:44 INFO - 'HOME': '/Users/cltbld', 03:15:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:44 INFO - 'LOGNAME': 'cltbld', 03:15:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:44 INFO - 'MOZ_NO_REMOTE': '1', 03:15:44 INFO - 'NO_EM_RESTART': '1', 03:15:44 INFO - 'PAGER': '/bin/cat', 03:15:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:44 INFO - 'PWD': '/builds/slave/test', 03:15:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:44 INFO - 'SHELL': '/bin/bash', 03:15:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iiGhUfoHz4/Listeners', 03:15:44 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 03:15:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:44 INFO - 'USER': 'cltbld', 03:15:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:44 INFO - 'XPC_FLAGS': '0x0', 03:15:44 INFO - 'XPC_SERVICE_NAME': '0', 03:15:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:45 INFO - Downloading/unpacking blobuploader==1.2.4 03:15:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:47 INFO - Downloading blobuploader-1.2.4.tar.gz 03:15:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:15:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:15: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:15: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:15: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:15: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:15: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:15: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:15:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:15:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:48 INFO - Downloading docopt-0.6.1.tar.gz 03:15:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:15:49 INFO - Installing collected packages: blobuploader, requests, docopt 03:15:49 INFO - Running setup.py install for blobuploader 03:15:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:15:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:15:49 INFO - Running setup.py install for requests 03:15:49 INFO - Running setup.py install for docopt 03:15:49 INFO - Successfully installed blobuploader requests docopt 03:15:49 INFO - Cleaning up... 03:15:49 INFO - Return code: 0 03:15:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:49 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 0x10473d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046b3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81e34d3570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104551d30>, '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 0x1043b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104549b20>, '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.lIm8b1BQ6j/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.iiGhUfoHz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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:15:49 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:15:49 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:15:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIm8b1BQ6j/Render', 03:15:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:49 INFO - 'HOME': '/Users/cltbld', 03:15:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:49 INFO - 'LOGNAME': 'cltbld', 03:15:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:49 INFO - 'MOZ_NO_REMOTE': '1', 03:15:49 INFO - 'NO_EM_RESTART': '1', 03:15:49 INFO - 'PAGER': '/bin/cat', 03:15:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:49 INFO - 'PWD': '/builds/slave/test', 03:15:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:49 INFO - 'SHELL': '/bin/bash', 03:15:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iiGhUfoHz4/Listeners', 03:15:49 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 03:15:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:49 INFO - 'USER': 'cltbld', 03:15:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:49 INFO - 'XPC_FLAGS': '0x0', 03:15:49 INFO - 'XPC_SERVICE_NAME': '0', 03:15:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:15:50 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-hndf11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:15:50 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-wzbZhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:15:50 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-XIniqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:15:50 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-3D63Xg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:15:50 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-ewvZXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:15:50 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-RwiMb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:15:50 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-FLxeye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:15:50 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-7jB5RZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:15:50 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-dLXFT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:15:51 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-pOjHNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:15:51 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-oWZ03b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:15:51 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-UxJGzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:15:51 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-XUed0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:15:51 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-W5o1lG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:15:51 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-D3Pzae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:15:51 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-427TNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:15:51 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-5zhIMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:15:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:15:51 INFO - Running setup.py install for manifestparser 03:15:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:15:52 INFO - Running setup.py install for mozcrash 03:15:52 INFO - Running setup.py install for mozdebug 03:15:52 INFO - Running setup.py install for mozdevice 03:15:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:15:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:15:52 INFO - Running setup.py install for mozfile 03:15:52 INFO - Running setup.py install for mozhttpd 03:15:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:15:52 INFO - Running setup.py install for mozinfo 03:15:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:15:52 INFO - Running setup.py install for mozInstall 03:15:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:15:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:15:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:15:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:15:53 INFO - Running setup.py install for mozleak 03:15:53 INFO - Running setup.py install for mozlog 03:15:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:15:53 INFO - Running setup.py install for moznetwork 03:15:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:15:53 INFO - Running setup.py install for mozprocess 03:15:53 INFO - Running setup.py install for mozprofile 03:15:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:15:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:15:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:15:53 INFO - Running setup.py install for mozrunner 03:15:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:15:54 INFO - Running setup.py install for mozscreenshot 03:15:54 INFO - Running setup.py install for moztest 03:15:54 INFO - Running setup.py install for mozversion 03:15:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:15:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:15:54 INFO - Cleaning up... 03:15:54 INFO - Return code: 0 03:15:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:15: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:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15: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:15:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:54 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 0x10473d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046b3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81e34d3570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104551d30>, '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 0x1043b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104549b20>, '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.lIm8b1BQ6j/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.iiGhUfoHz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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:15:54 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:15:54 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:15:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIm8b1BQ6j/Render', 03:15:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:54 INFO - 'HOME': '/Users/cltbld', 03:15:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:54 INFO - 'LOGNAME': 'cltbld', 03:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:54 INFO - 'MOZ_NO_REMOTE': '1', 03:15:54 INFO - 'NO_EM_RESTART': '1', 03:15:54 INFO - 'PAGER': '/bin/cat', 03:15:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:54 INFO - 'PWD': '/builds/slave/test', 03:15:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:54 INFO - 'SHELL': '/bin/bash', 03:15:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iiGhUfoHz4/Listeners', 03:15:54 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 03:15:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:54 INFO - 'USER': 'cltbld', 03:15:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:54 INFO - 'XPC_FLAGS': '0x0', 03:15:54 INFO - 'XPC_SERVICE_NAME': '0', 03:15:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:15:54 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-T74Jmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:15:54 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:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:15:54 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-DBmbHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:15:54 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:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:15:54 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-c01Kom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:15:55 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:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:15:55 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-3XdNM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:15:55 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:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:15:55 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-WH444t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:15:55 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:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:15:55 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-JlkJjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:15:55 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:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:15:55 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-LVRMgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:15:55 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:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:15:55 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-qan51z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:15:55 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:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:15:55 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-pXAtFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:15:55 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:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:15:55 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-Vc_n1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:15:55 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:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:15:55 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-zg8eVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:15:55 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:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:15:55 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-7QSo9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:15:56 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:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:15:56 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-Z1BGAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:15:56 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:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:15:56 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-ILkzpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:15:56 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:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:15:56 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-8GKqUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:15:56 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:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:15:56 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-qCNZFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:15:56 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:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:15:56 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-87DLXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:15:56 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:15:56 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:15:56 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:15:56 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:15:56 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:15:56 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:15:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:15:56 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:15:56 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:15:56 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:15:56 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:15:56 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:15:56 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:15:59 INFO - Downloading blessings-1.6.tar.gz 03:15:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:15:59 INFO - Installing collected packages: blessings 03:15:59 INFO - Running setup.py install for blessings 03:15:59 INFO - Successfully installed blessings 03:15:59 INFO - Cleaning up... 03:15:59 INFO - Return code: 0 03:15:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:15:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:15:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:15:59 INFO - Reading from file tmpfile_stdout 03:15:59 INFO - Current package versions: 03:15:59 INFO - blessings == 1.6 03:15:59 INFO - blobuploader == 1.2.4 03:15:59 INFO - docopt == 0.6.1 03:15:59 INFO - functools32 == 3.2.3-2 03:15:59 INFO - jsonschema == 2.5.1 03:15:59 INFO - manifestparser == 1.1 03:15:59 INFO - mozInstall == 1.12 03:15:59 INFO - mozcrash == 1.0 03:15:59 INFO - mozdebug == 0.1 03:15:59 INFO - mozdevice == 0.48 03:15:59 INFO - mozfile == 1.2 03:15:59 INFO - mozhttpd == 0.7 03:15:59 INFO - mozinfo == 0.9 03:15:59 INFO - mozleak == 0.1 03:15:59 INFO - mozlog == 3.4 03:15:59 INFO - moznetwork == 0.27 03:15:59 INFO - mozprocess == 0.23 03:15:59 INFO - mozprofile == 0.28 03:15:59 INFO - mozrunner == 6.13 03:15:59 INFO - mozscreenshot == 0.1 03:15:59 INFO - mozsystemmonitor == 0.3 03:15:59 INFO - moztest == 0.7 03:15:59 INFO - mozversion == 1.4 03:15:59 INFO - psutil == 3.1.1 03:15:59 INFO - requests == 1.2.3 03:15:59 INFO - wsgiref == 0.1.2 03:15:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:15: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:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15: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:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:59 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 0x10473d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046b3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81e34d3570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104551d30>, '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 0x1043b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104549b20>, '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.lIm8b1BQ6j/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.iiGhUfoHz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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:15:59 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:15:59 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:15:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIm8b1BQ6j/Render', 03:15:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:59 INFO - 'HOME': '/Users/cltbld', 03:15:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:59 INFO - 'LOGNAME': 'cltbld', 03:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:59 INFO - 'MOZ_NO_REMOTE': '1', 03:15:59 INFO - 'NO_EM_RESTART': '1', 03:15:59 INFO - 'PAGER': '/bin/cat', 03:15:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:59 INFO - 'PWD': '/builds/slave/test', 03:15:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:59 INFO - 'SHELL': '/bin/bash', 03:15:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iiGhUfoHz4/Listeners', 03:15:59 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 03:15:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:59 INFO - 'USER': 'cltbld', 03:15:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:59 INFO - 'XPC_FLAGS': '0x0', 03:15:59 INFO - 'XPC_SERVICE_NAME': '0', 03:15:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:00 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:16:00 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:16:00 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:16:00 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:16:00 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:16:00 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:16:00 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:16:00 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:16:00 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:16:00 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:16:00 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:16:00 INFO - Cleaning up... 03:16:00 INFO - Return code: 0 03:16:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:16:00 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:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:00 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:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:00 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 0x10473d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046b3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81e34d3570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104551d30>, '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 0x1043b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104549b20>, '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.lIm8b1BQ6j/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.iiGhUfoHz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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:16:00 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:16:00 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:16:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIm8b1BQ6j/Render', 03:16:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:16:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:16:00 INFO - 'HOME': '/Users/cltbld', 03:16:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:16:00 INFO - 'LOGNAME': 'cltbld', 03:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:00 INFO - 'MOZ_NO_REMOTE': '1', 03:16:00 INFO - 'NO_EM_RESTART': '1', 03:16:00 INFO - 'PAGER': '/bin/cat', 03:16:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:00 INFO - 'PWD': '/builds/slave/test', 03:16:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:16:00 INFO - 'SHELL': '/bin/bash', 03:16:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iiGhUfoHz4/Listeners', 03:16:00 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 03:16:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:16:00 INFO - 'USER': 'cltbld', 03:16:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:16:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:00 INFO - 'XPC_FLAGS': '0x0', 03:16:00 INFO - 'XPC_SERVICE_NAME': '0', 03:16:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:16:00 INFO - Cleaning up... 03:16:00 INFO - Return code: 0 03:16:00 INFO - Running post-action listener: _resource_record_post_action 03:16:00 INFO - Running post-action listener: _start_resource_monitoring 03:16:00 INFO - Starting resource monitoring. 03:16:00 INFO - [mozharness: 2016-11-24 11:16:00.319541Z] Finished create-virtualenv step (success) 03:16:00 INFO - [mozharness: 2016-11-24 11:16:00.320495Z] Running install step. 03:16:00 INFO - Running pre-action listener: _resource_record_pre_action 03:16:00 INFO - Running main action method: install 03:16:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:16:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:16:00 INFO - Reading from file tmpfile_stdout 03:16:00 INFO - Detecting whether we're running mozinstall >=1.0... 03:16:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:16:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:16:00 INFO - Reading from file tmpfile_stdout 03:16:00 INFO - Output received: 03:16:00 INFO - Usage: mozinstall [options] installer 03:16:00 INFO - Options: 03:16:00 INFO - -h, --help show this help message and exit 03:16:00 INFO - -d DEST, --destination=DEST 03:16:00 INFO - Directory to install application into. [default: 03:16:00 INFO - "/builds/slave/test"] 03:16:00 INFO - --app=APP Application being installed. [default: firefox] 03:16:00 INFO - mkdir: /builds/slave/test/build/application 03:16:00 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:16:00 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:16:31 INFO - Reading from file tmpfile_stdout 03:16:31 INFO - Output received: 03:16:31 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:16:31 INFO - Running post-action listener: _resource_record_post_action 03:16:31 INFO - [mozharness: 2016-11-24 11:16:31.389838Z] Finished install step (success) 03:16:31 INFO - [mozharness: 2016-11-24 11:16:31.389993Z] Running run-tests step. 03:16:31 INFO - Running pre-action listener: _resource_record_pre_action 03:16:31 INFO - Running main action method: run_tests 03:16:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:16:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:16:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:16:31 INFO - 2016-11-24 03:16:31.403 screenresolution[1633:8048] starting screenresolution argv=screenresolution get 03:16:31 INFO - 2016-11-24 03:16:31.405 screenresolution[1633:8048] Display 0: 1600x1200x32@60 03:16:31 INFO - 2016-11-24 03:16:31.411 screenresolution[1634:8050] starting screenresolution argv=screenresolution list 03:16:31 INFO - Available Modes on Display 0 03:16:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:16:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:16:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:16:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:16:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:16:31 INFO - Intel Iris: 03:16:31 INFO - Chipset Model: Intel Iris 03:16:31 INFO - Type: GPU 03:16:31 INFO - Bus: Built-In 03:16:31 INFO - VRAM (Dynamic, Max): 1536 MB 03:16:31 INFO - Vendor: Intel (0x8086) 03:16:31 INFO - Device ID: 0x0a2e 03:16:31 INFO - Revision ID: 0x0009 03:16:31 INFO - Displays: 03:16:31 INFO - Display: 03:16:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:16:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:16:31 INFO - Display Serial Number: 1600x1200 60 03:16:31 INFO - Main Display: Yes 03:16:31 INFO - Mirror: Off 03:16:31 INFO - Online: Yes 03:16:31 INFO - Rotation: Supported 03:16:31 INFO - Return code: 0 03:16:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:16:31 INFO - Python 2.7.3 03:16:31 INFO - Return code: 0 03:16:31 INFO - grabbing minidump binary from tooltool 03:16:31 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:16:31 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 0x104551d30>, '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 0x1043b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104549b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:16:31 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:16:31 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:16:31 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:16:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:16:31 INFO - Return code: 0 03:16:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:16:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:16:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:16:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:16:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:16:31 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/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0210', '--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:16:31 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/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0210 --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:16:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lIm8b1BQ6j/Render', 03:16:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:16:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:16:31 INFO - 'HOME': '/Users/cltbld', 03:16:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:16:31 INFO - 'LOGNAME': 'cltbld', 03:16:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:16:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:31 INFO - 'MOZ_NO_REMOTE': '1', 03:16:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:16:31 INFO - 'NO_EM_RESTART': '1', 03:16:31 INFO - 'PAGER': '/bin/cat', 03:16:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:31 INFO - 'PWD': '/builds/slave/test', 03:16:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:16:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:16:31 INFO - 'SHELL': '/bin/bash', 03:16:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iiGhUfoHz4/Listeners', 03:16:31 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 03:16:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:16:31 INFO - 'USER': 'cltbld', 03:16:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:16:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:31 INFO - 'XPC_FLAGS': '0x0', 03:16:31 INFO - 'XPC_SERVICE_NAME': '0', 03:16:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:16:31 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/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0210', '--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:16:31 INFO - mozversion application_buildid: 20161124020338 03:16:31 INFO - mozversion application_changeset: 7f611a717df6bf178b2cab2da4ef8c005f80da8a 03:16:31 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:16:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:16:31 INFO - mozversion application_name: Firefox 03:16:31 INFO - mozversion application_remotingname: firefox-dev 03:16:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:16:31 INFO - mozversion application_vendor: Mozilla 03:16:31 INFO - mozversion application_version: 52.0a2 03:16:31 INFO - mozversion platform_buildid: 20161124020338 03:16:31 INFO - mozversion platform_changeset: 7f611a717df6bf178b2cab2da4ef8c005f80da8a 03:16:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:16:31 INFO - mozversion platform_version: 52.0a2 03:16:31 INFO - using testdate: 1479986191 03:16:31 INFO - actual date: 1479986191 03:16:31 INFO - starting webserver on 'localhost:49249' 03:16:31 INFO - SUITE-START | Running 2 tests 03:16:31 INFO - TEST-START | dromaeo_css 03:16:31 INFO - operating with platform_type : mac_ 03:16:31 INFO - Initialising browser for dromaeo_css test... 03:16:32 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmptLcF5w/profile http://localhost:49249/getInfo.html) 03:16:35 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 03:16:35 INFO - PROCESS | 1644 | colorDepth:24 03:16:35 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 03:16:35 INFO - PROCESS | 1644 | __metrics 03:16:35 ERROR - PROCESS | 1644 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 03:16:35 INFO - TEST-INFO | 1644: exit 0 03:16:35 INFO - Browser initialized. 03:16:35 INFO - Running cycle 1/1 for dromaeo_css test... 03:16:35 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmptLcF5w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:16:48 INFO - PROCESS | 1648 | RSS: Main: 229744640 03:16:48 INFO - PROCESS | 1648 | 03:17:37 INFO - PROCESS | 1648 | 1479986257847 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:19:49 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 03:19:52 INFO - PROCESS | 1648 | RSS: Main: 235569152 03:19:52 INFO - PROCESS | 1648 | 03:22:54 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 03:22:55 INFO - PROCESS | 1648 | RSS: Main: 243630080 03:22:55 INFO - PROCESS | 1648 | 03:25:57 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 03:25:58 INFO - PROCESS | 1648 | RSS: Main: 247607296 03:25:58 INFO - PROCESS | 1648 | 03:28:59 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 03:29:00 INFO - PROCESS | 1648 | RSS: Main: 246726656 03:29:00 INFO - PROCESS | 1648 | 03:32:03 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 03:32:04 INFO - PROCESS | 1648 | RSS: Main: 250150912 03:32:04 INFO - PROCESS | 1648 | 03:35:06 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 03:35:06 INFO - PROCESS | 1648 | RSS: Main: 251617280 03:35:06 INFO - PROCESS | 1648 | 03:35:06 INFO - PROCESS | 1648 | __start_tp_report 03:35:06 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 03:35:06 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 03:35:06 INFO - PROCESS | 1648 | |i|pagename|runs| 03:35:06 INFO - PROCESS | 1648 | |0;dojo.html;2738.8219765715226;2992.9551056734144;2993.2816124130186;3317.3696997570473;3337.349216902704;4201.4328065711115;5276.103062479383;5314.840554783358;5399.3790714067845;5450.04624190766;5376.166694162402;5409.215663728757;5421.186821976706;5423.322084739408;5437.700926449053;4983.476734942833;4985.1774457214515;5013.172826483626;5026.095302845486;5030.220315851049;4987.451380348159;5006.949930500703;5011.624128190382;5036.244563315496;5037.715382577438;4903.558679718824;4950.356453661019;5022.924656130161;5038.974805125969;5096.082705113078;4970.353853998975;4990.226514890198;5040.697558146523;5057.595392368638;5090.160123579642;5019.623528235397;5019.749012549358;5019.799208031674;5024.497550244946;5069.138246498104;5295.867603309909;5373.382061062989;5461.754221060062;5468.043092458804;5540.196671482612;5695.857603559944;5734.483896449339;5788.768449262025;5791.276090488689;5804.042333015047;5270.578353731692;5990.221271234712;6001.909971350433;6003.879922401571;6028.4272994065495;5081.18701007837;5511.035568775448;5511.834644960658;5524.8342549723575;5548.223248745179;4918.2376731606655;5869.4717475427415;6104.755809767648;6121.663308518075;6125.173101631222;5658.624306908453;5700.37295897451;5703.828885133453;5730.942690573124;5738.454846789548;4885.853424397274;5025.271335656309;5693.4875861172695;5921.02303119989;5932.288125424977;5836;5842.211301474332;5845.766169353178;5895.528357731371;5897.085951677411;5812.883742325214;5842.707864606753;5860.384659610765;5878.059510478303;5899.203607513016;4665.530357937307;4670.673052886265;4675.602573781199;4727.692699974491;4738.123447162286;4235.42821719064;4304.526502084768;4310.202612516632;4313.935290970638;4321.984333681582;5399.595030372738;5562.193481945095;5577.553795696334;5590.049691552445;5603.971980140073;5721.542276617861;5998.940010599926;6009.579329446984;6012.428819262163;6030.879382412327;5404.648697834628;5448.237246785453;5455.61810673257;5470.781168753205;5497.917531237034;5079;5126.359205099363;5131.7434128293435;5188.429272779991;5190.844274671766;6105.8473538161015;6118.418750218633;6130.356312587203;6131;6143.293521245114;4962.858542535309;5623.100303951348;5774.855628609318;5953.315368732651;5993.160957465871;5358.892822143614;5777.653340799566;5984.162217289496;6039.245094363205;6078.209832721718;5692.943070569407;5722.856928576819;5747.770089196385;5751.1085781703905;5777.3933736957015;5845.766169353348;5889.49939255151;5907.4683278505145;5914.201582786441;5983.222181116427;5605.131204663284;5689.061304884648;5696.886062278814;5704.885902282014;5719.48524632785;5512.5589952804485;5543.1685247213245;5565.582581306499;5633.6619802811965;5690.402507736628;4861.829835955866;5397.001554712391;5489.890202196014;5521.613487055867;5552.944470555404;5634.352049514358;5670.716464176692;5674.829755107354;5757.65454072757;5766.163906233656;5612.074007788669;5624.493795558419;5628.774849005994;5628.915566266427;5645.209670646195 03:35:06 INFO - PROCESS | 1648 | |1;ext.html;496.6548248966967;515.8504033830189;520.9322788037555;540.4055538907201;555.9833205003852;32399.352012959785;35561.93314200575;36131.63868361316;37725.43411848821;37731.62268377315;34765.652343476555;38863;38902.027449313784;38906.61093389065;38993.41509877347;9026.954865225665;9101.635934562626;9337.53312334384;9342.57958391874;9370.156685898268;9219.40073895198;9326.647636092763;9335.626574936994;9362.344635875492;9364.438133711965;9298.302627302946;9329.626814927395;9354.81290374192;9358.766030849216;9377;9255.167034966851;9294.395864268869;9327.113924177193;9341;9371;9277.721668349961;9333.766655833591;9341.439513629204;9343.953280233623;9346.345755797098;37247.81376093129;37414.62585374139;37519.24961500782;37591;37807.810960945295;3134.717875391226;3464.636213197603;3660.0666829958454;3788.6969042476535;3946.1318509928055;28959.13122606325;29358.119256422342;29574.964876229446;29639.70360296391;30158.698413016027;3090.7636945221866;3161.96838031619;3199.4720871056247;3262.429074911881;3267.9183020424443;487155.56422218017;487745;494942.8287161359;495681;495779;56223.43776562182;56511.15233271504;56664.43335566592;56667;56775.43224567784;56996.430035699945;58027;58111.12833307504;58239;58240.70879645574;12090.879091209152;15184.392624295126;15291.006084604465;15390.845686573552;15396.692066158614;11875;11982.88017119835;12058.914697677252;12066.276023438622;12076.637700868989;13515.459381624822;14630.26848657563;14695.632609184855;14699.92650036743;14872.70254594902;6746.76386326486;6791.592504449749;6818.817978247012;6911.9308806912295;6952.582845029315;851.3614788908319;927.0405130689704;944.17856464876;981.9754506137261;1102.9944850275697;25479.23562293134;26721;28126.85936570304;28917.710822891924;29572.112836614935;1021.4382089850552;1045.968620941373;1060.9363438193734;1062.8033813744482;1062.9043386095288;13135.737285254241;14781.778273325908;14967;14998.250087495582;15330.616734581501;646776;651746;657590;658939;660180;55276.72361638166;55299.723501382236;55389;55568;55919.44080559224;1336.251699048536;1342.2550484481012;1359.2184493916038;1362.4209710872642;1374.4021350712271;28544.286392840346;28630.85684571564;28907.42185156327;29268.853655731586;29684.851575742847;47293.76353118212;47579.76210118927;47587.33444329432;47703.52296476991;47774.283385749935;581366;584926;585103;585595;586067;37782.62217377791;38123;38287.42568861414;38481;38502.22995540129;44112.558874410846;44662.55337446584;44756.77621611871;45163.54836451593;45189;28997.855010724812;35776.82111589425;37048.44427333538;37072.70245541393;37135.07162320964;36619.81690091533;36690;37268.881933542034;37796.811015944746;37934.62065379311;35871.28257434889;36276.81861590675;36766.264674706894;37089.629103709696;38527.807360964136;27259;33944.15139621529;34065.31869362648;34115.14712132117;34132.658673412945;21765.673514897608;24900.750992490568;25209.495810084063;25473.66224267197;25495.36261593475 03:35:06 INFO - PROCESS | 1648 | |2;jquery.html;373.1454968122998;374.40282749015324;381.74423136498575;382.66516797801916;399.9220152070346;18235.179416926236;19593.902030489862;19605.313814016514;20410.979451027462;20608.793912060875;19146.521336966583;19450.51373715658;19677.90161049193;20509.897450512726;21343.932803359847;6226.408491193352;6443.967780161092;6736.922092465206;6747;7812.921870781304;7506.587137707424;7783.41624378171;7820.217978202163;7837.177096404881;7871.8425631487335;7763.495372800779;7771.533707977539;7845.607719614025;7852.685892564291;7880.684772609089;7536.547807131562;7804.2585954334245;7867.6852925882895;7948.046234451903;7949.801254968615;7408.035344745912;7581.469297149201;7584.54492730438;7680.11678656956;7686.231376862325;26985.595216071775;27461.31346716348;27835;27968.580471292946;28330.858345708344;2320.2923108451914;2331.1724337859987;2380.3811009137576;2837.404145129525;3016.0499442675487;16039.919800400923;16147.273372698257;16868.746968795476;17434.564135896577;17454.952702837745;1989.094961792381;2001.4596059063972;2044.7955204479433;2091.7489901211798;2093.706881036656;95068.61770421706;108927;137112;140458.99999999898;145856;21470.248541300978;21847.344579662637;22235.666465003036;22365.888170559043;22459.32622021342;34664.13339666478;34922.82538587291;35549.28901421957;35702.64297357045;35905.640943590755;10283.588656453807;10532.842007369896;10607.57569697219;10722.463876806129;10846.891531084746;8748.081451447633;8751.56242187888;8796.428232164888;8878.644854205888;8917;8383.455075420079;9828.361156524803;9929.503524823875;9972.451515166735;10043.849342259871;3372.7976321420792;3847.5190601174854;3949.9585319303974;3984.163325701635;3998.8800335989968;738.5015114797491;823.6993497373535;823.8475881961856;898.7977705016319;952.0479520479521;16661.416850410176;18664;20177.293794717116;20353;20667.689984650242;898.8022635020285;933.2533972821716;938.5354249646468;976.497103991445;1017.4607458047246;10165.644202452879;10794.946025269823;10797.514111864984;10818.972197641211;10853.294535855144;582925;588713;588744;595458;598182;118325;122661;122737;123546.38226808808;126441.36779316045;764.7629234937187;815.9143289954588;954.7326748510428;956.3257903178243;959.8800149981253;10842.945785271024;11439.313641181556;17693.823061769217;17913.92516449017;18508.629827403645;49595.50404495909;53370.73314633402;53980.19029714636;54598.45401546093;54856.72571637116;76799.61600191964;78258;78578.6071069641;79433.60283198547;79924;11421.543138274375;11873.762524749629;12005;12181.33002684861;12184.817227741414;12697.87302127004;13029.804552931524;13098.672533186747;13127;13503.39234734459;5303.257543943774;5331.733413329395;11042.227044106836;11198.608048718255;11310;6432.6140431572385;8294.106477178493;10909.454527273447;11336;11383.43082845871;6632.535722499571;7849.293563579306;7983;11377.4880130396;11677.941610291895;9740.172085372822;12015.339156346485;12070;12140.817887731515;12239.408876845953;7646.502977306457;11008.669739907815;11155.609553665583;11352.829707554229;11437.59968401102 03:35:06 INFO - PROCESS | 1648 | |3;mootools.html;1283.9743205135894;1312.7046414556723;1570.3247603530485;1743.5902562897709;1764.206107251737;7688.807779805507;7966.243206895336;8336.91663083369;8835.072317406677;8839.07189745077;8333.708275217336;8585.484870907734;8623.180797824196;8756.080611535792;8761.342899282563;2350.670906073151;2376;2383.1063351243283;2384.5707772600927;2388.59393903036;2290.1068583252545;2292.7592602776717;2303.9308820735323;2332.801711854486;2343.250159948817;2054.8767073975537;2299.4481324482085;2337.532493501298;2366.4736245121885;2368.6802281692007;2320.9419764506024;2329.743728189898;2347.9882600587057;2354.7645235476316;2356.4226764442737;1886.302068234762;2371.1700904683394;2385.1175065225802;2387.1048356866177;2393.3777217923466;8735.781605459852;8783.472991620523;8786.560671966376;8805.471671699719;8805.987311459201;5160.638755287131;5396.298481197458;5400.540954018913;5408.621396502246;5412.512873841334;3366.612839523462;3514.9648503514895;3622.3117607654463;3696.5009723687226;3713.0717320669833;878.8769572259889;1005.3465247589053;1047.4029803012286;1048.9213309001855;1101.0805977009206;9613.567389467491;9657.93762686111;10781.676549703521;10798.758142813598;11156.721081972935;4490.236659767847;4587.954120458753;4819.9759001205475;4823.638227132979;4889.9022019560125;8499.95750021246;9621.470819104876;9795.608175673036;9801.215902727765;9807.06832850878;5103.744812759347;5266.34170728659;5281.339832520935;5285.63000589955;5320.201969704498;4151.91696166075;4234.78826058702;4293.69944103916;4853.12643724126;4870.172070747982;3962.5641179470235;3978.9853587335047;4027.536833264212;4074.103697186614;4092.3247664135083;1182.5860948667898;1189.0309397840733;1190.9285442873456;1192.272713644676;1192.8628207756046;801.4750338528273;807.1283014344494;878.7539488942979;902.4855832175597;915.9816803663889;5660.782931669711;5729.799457019068;5765.711714414262;5833.154192642128;6014.338422773491;436.95193528711815;440.66289288694173;444.97775111244306;446.1434046630477;449.97525136117173;3820.617938206213;3825.158465137665;3831.4827498287373;3892.3966785148345;3986.720929534905;10258.794824103476;10556.47217639115;10674.73313167177;10685.83971240432;10723.14214862809;10616.991385818335;10635.840462393071;10639.361638301572;10639.41483218415;10700.571977120828;7869.763907082796;8136.308413784776;8144.144864789112;8263.834723305501;8265.876011859948;5491.450854914477;5615.129654903497;5661.801836935688;5688.658680479184;5779.913301300568;9673.806523869342;9777.41335519871;9794.314397992357;9833.655822046194;9884.950575247078;8587.82824343522;9676;9911.504424778588;9998.550065247227;10061.346012509164;5993.70031498432;6789.72841086351;6958.791236262718;7381.188069312264;7449.292317229854;8069.798255043436;8374.748757537283;8388.454750441202;8412.158784121539;8420.073791883011;7252.891206631799;7350.70597176107;7377.704891804267;7510.760724480399;7571.053618297713;7024.770665133683;7248.420126389981;7328.633568321456;7362.558246505227;7492.2133176017915;5942.881142377215;6760.93239067603;6819.2498825128205;6946.16646002483;6949.444044476329;4843.4430040545785;4885.169521181337;4900.8774780630765;4948.925766113583;5004.84985450437;4886.877828054327;5000.399952005782;5005.249212618136;5008.77460514285;5047.924281135859 03:35:06 INFO - PROCESS | 1648 | |4;prototype.html;256.27985361135205;296.76407256231295;308.1372157957718;309.9457594920888;311.6665168269952;3803.953912674009;5062.367204099488;5202;5256.973715131424;5392.955283537347;4069.552349241581;5325.508857519891;5359.651622644536;5430.239766432703;5509.1819699499165;3094.9226269343335;3611.404118320487;3894.435306880501;3955.1298714282884;4148.605882441163;3267.983660081708;3669.935718641604;4142.792860356985;4158.022864626811;4175.707700460969;3547.5565554305713;4051.635352818246;4086.8160932758096;4110.712250142491;4115.403266526352;3564.394053010981;4071.8778436646653;4104.87116043089;4107.034846810997;4111.5477297497255;2982.1659679297295;3132.6999295292426;3529.4000019996406;3529.6646818552194;3576.7317451191007;4314.352847072964;5225.764840582183;5233.476652334774;5297.576193904517;5364.785408583652;3653.8355773990234;4450.109978004412;4582.266837306049;4586.128635559233;4653.953460465385;4680.742559159245;5368.7383464885725;5635.71821408932;5649.971750141264;5674.474858687886;3381.5942086949476;3543.450765131409;3713.517242758451;3838.8848334550007;3880;5052.898942021139;5069.822556210515;5654.038813401731;5846.093855452412;5867.882642347172;3845.4616353710503;3899.4150877368056;3922.5881282465502;3939.3106206413763;3948.3485224937563;3371.359441706068;3441.397755392796;3821.3121638104853;3931.8820435386983;3963.841446342172;3520.383932811748;3684.7236457265813;3810.961890381116;4389.924468505236;4535.501094879561;5263.815017354593;5600.383957764642;5618.4662457066515;5620.887582248414;5679.886402271932;966.2944902247539;1082.929609575375;1158.5134243617701;1222.7615614955175;1261.6467389131058;712.8609921065446;725.5537844225869;755.4296506137831;790.7192946504023;890.7684002159356;4654.488006319302;5048.899022019613;5051.1815746331295;5132.2814805926455;5163.173892177234;5403.297571315704;5583.637063590935;5587.53572784876;5599.284347091657;5677.630953987978;4458.464984201852;4975.383000524844;5038.6976781392505;5114.590832733373;5141.845744627667;2632.697239817407;2888.942221155565;2926.6049083373896;2959.615250017484;2965.792394532362;5009.674371165863;5211.713355765393;5845.824625261249;5868.530517558585;5916.674582897896;4833.782479788418;5070.721110338966;5270.604704647167;5395.947790180877;5736.770529178786;6139.355367686418;6226.875462490815;6260.092286618324;6260.937390626126;6269.369963809442;4922.9907868886075;5121.923171152361;5679.649007085674;5865.648061116347;5934.821955341346;4939.431965324034;5038.84883453497;5136;5793.507551858139;5798.536117110705;5349.973250133725;5718.369550006877;6208.255009398901;6229;6233.189685340877;3334.5164951081474;3804.5434547854434;3878.321293773522;3902.746321489094;3964.5638979712785;4731.929021064756;5768.250127483569;5833.737481813413;5905.852353691193;5958.821235362772;4494.865154045384;5041.495850414929;5354.303940487712;5729.01833225904;5764;5064.772085256246;5457.918131227955;5689.008846903551;5750.763585829067;5774.653520788766;4263.637590804817;4451.554844515523;5347.625666203328;5630.8029218977135;5673.8297851064535;5633.718314084362;5673.3759286477625;5676.914846277226;5679.460451257124;5759.350698678655;5060.468650791761;5233.215017747369;5619.213310136666;5635.8591035224445;5638.126090455986 03:35:06 INFO - PROCESS | 1648 | |5;yui.html;671.9630420326883;708.1608294171406;721.310496808823;726.4079774983388;759.0208630866183;14075.57773266801;15306.005109667864;15323.463678771246;15352.232388380578;15374.538763837074;11532.365719885423;13768.24274664893;13811.309434528284;13827;13887.791683124737;644.6712176789836;666.9861810048723;793.896793416855;831.846108469935;836.1053672570353;820.9220124088232;834.3700506117873;835.5028757889077;836.5273620404452;837.4849467577426;840.1766269056328;841.4278290762279;867.4621734524603;880.8722735203391;886.2909672262197;837.6774941647449;841.7474757572734;868.2446271743561;878.3807415771865;880.3309484791572;630.8580569371854;649.9675016249216;650.8080116365684;651.876143532732;652.3509108437088;14119.223442710647;14199.219042952633;14347.641308967255;14406.711865762625;14645.267736613234;12224.14430989831;13806.240656763874;14124.717505649829;14180.290985450687;14261.215633140173;11754.764904701944;12139.453724582416;12408.255504669749;12469.189502682388;12623.116381853273;4333.349997500369;4362.498312694049;4444.2222611042935;4460.910781784346;4588.862334129982;72410.63794681046;74165;76732.61633691796;90715;92486.5375673124;10327.690169294783;10471.790564188826;10549.103326217357;10650.467476626138;10736.785264294671;15389;17473;19054.714179287323;19079.332223372116;20020;6363;7439.51643143194;7530.962345188349;7585.279398457138;7778.533288002738;3753.6246375362794;3877;4081.816318265626;4228.450301460852;4274.850380236739;3429.1255729788745;3511.4908338290807;3547.4324108142573;3554.2358392945125;3559.537260156163;552.5165480204821;639.2999665366416;643.7296335539085;656.0421784195033;669.6183175589964;605.7153138025121;615.8737458820931;627.6077451592755;648.5492582655104;654.3718030690495;8483.872741908875;8815.515146666994;8975.730728078168;9007.459552426875;9012.053734357798;553.1702446330504;569.177538456934;619.5910698938679;622.9221347331584;655.1810237203496;4092.140650463436;4278.614924656796;4303.526612072669;4451.132029254264;4576.336431217522;104981;109498.45250773855;109691;113215;117715;94704;97550;100671.49664251631;101217.49391253144;101906.98093019123;84337;86553;87338.56330718433;87689;87911.56044219866;8477;8749.1250874912;8769.473831570127;8776.16626420489;8984;49614.00771984612;53516.929661405775;53521.929561409335;53926.651833702905;55400;72928.27071729215;77351.83972240533;80090.59954700423;80587;81219;10664.360138391721;10698.465076746288;10739.14086873064;10868.836967445653;11188.72028199269;12780.424880880197;13092.934535327262;13094.083414160918;13219.933900330438;13447.932760336136;12120;12443.626691199279;12480.812787808372;12546.62360129161;12753.681157971125;12314.876851231731;12784.936075319563;12849.614511564294;13035.608931732064;13122.934385328012;12283.324417157142;12387.62837114888;12422.440990155284;12690.619281421572;12879.871201287868;10673.57305707794;10725.41010244444;10753.354798712102;10857.18571107186;11024.779504410028;10801.945990269998;10904.040050763146;11000.22998390105;11001.779964400826;11030.724231894266 03:35:06 INFO - PROCESS | 1648 | __end_tp_report 03:35:06 INFO - PROCESS | 1648 | __start_cc_report 03:35:06 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,368 03:35:06 INFO - PROCESS | 1648 | __end_cc_report 03:35:06 INFO - PROCESS | 1648 | __startTimestamp1479987306941__endTimestamp 03:35:06 INFO - PROCESS | 1648 | 03:35:06 INFO - PROCESS | 1648 | ------- Summary: start ------- 03:35:06 INFO - PROCESS | 1648 | Number of tests: 6 03:35:06 INFO - PROCESS | 1648 | 03:35:06 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5402.33 Median:5548.22 stddev:603.25 (10.9%) stddev-sans-first:567.76 03:35:06 INFO - PROCESS | 1648 | Values: 2738.8 2993.0 2993.3 3317.4 3337.3 4201.4 5276.1 5314.8 5399.4 5450.0 5376.2 5409.2 5421.2 5423.3 5437.7 4983.5 4985.2 5013.2 5026.1 5030.2 4987.5 5006.9 5011.6 5036.2 5037.7 4903.6 4950.4 5022.9 5039.0 5096.1 4970.4 4990.2 5040.7 5057.6 5090.2 5019.6 5019.7 5019.8 5024.5 5069.1 5295.9 5373.4 5461.8 5468.0 5540.2 5695.9 5734.5 5788.8 5791.3 5804.0 5270.6 5990.2 6001.9 6003.9 6028.4 5081.2 5511.0 5511.8 5524.8 5548.2 4918.2 5869.5 6104.8 6121.7 6125.2 5658.6 5700.4 5703.8 5730.9 5738.5 4885.9 5025.3 5693.5 5921.0 5932.3 5836.0 5842.2 5845.8 5895.5 5897.1 5812.9 5842.7 5860.4 5878.1 5899.2 4665.5 4670.7 4675.6 4727.7 4738.1 4235.4 4304.5 4310.2 4313.9 4322.0 5399.6 5562.2 5577.6 5590.0 5604.0 5721.5 5998.9 6009.6 6012.4 6030.9 5404.6 5448.2 5455.6 5470.8 5497.9 5079.0 5126.4 5131.7 5188.4 5190.8 6105.8 6118.4 6130.4 6131.0 6143.3 4962.9 5623.1 5774.9 5953.3 5993.2 5358.9 5777.7 5984.2 6039.2 6078.2 5692.9 5722.9 5747.8 5751.1 5777.4 5845.8 5889.5 5907.5 5914.2 5983.2 5605.1 5689.1 5696.9 5704.9 5719.5 5512.6 5543.2 5565.6 5633.7 5690.4 4861.8 5397.0 5489.9 5521.6 5552.9 5634.4 5670.7 5674.8 5757.7 5766.2 5612.1 5624.5 5628.8 5628.9 5645.2 03:35:06 INFO - PROCESS | 1648 | 03:35:06 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:69912.68 Median:38028.81 stddev:155553.20 (409.0%) stddev-sans-first:155902.25 03:35:06 INFO - PROCESS | 1648 | Values: 496.7 515.9 520.9 540.4 556.0 32399.4 35561.9 36131.6 37725.4 37731.6 34765.7 38863.0 38902.0 38906.6 38993.4 9027.0 9101.6 9337.5 9342.6 9370.2 9219.4 9326.6 9335.6 9362.3 9364.4 9298.3 9329.6 9354.8 9358.8 9377.0 9255.2 9294.4 9327.1 9341.0 9371.0 9277.7 9333.8 9341.4 9344.0 9346.3 37247.8 37414.6 37519.2 37591.0 37807.8 3134.7 3464.6 3660.1 3788.7 3946.1 28959.1 29358.1 29575.0 29639.7 30158.7 3090.8 3162.0 3199.5 3262.4 3267.9 487155.6 487745.0 494942.8 495681.0 495779.0 56223.4 56511.2 56664.4 56667.0 56775.4 56996.4 58027.0 58111.1 58239.0 58240.7 12090.9 15184.4 15291.0 15390.8 15396.7 11875.0 11982.9 12058.9 12066.3 12076.6 13515.5 14630.3 14695.6 14699.9 14872.7 6746.8 6791.6 6818.8 6911.9 6952.6 851.4 927.0 944.2 982.0 1103.0 25479.2 26721.0 28126.9 28917.7 29572.1 1021.4 1046.0 1060.9 1062.8 1062.9 13135.7 14781.8 14967.0 14998.3 15330.6 646776.0 651746.0 657590.0 658939.0 660180.0 55276.7 55299.7 55389.0 55568.0 55919.4 1336.3 1342.3 1359.2 1362.4 1374.4 28544.3 28630.9 28907.4 29268.9 29684.9 47293.8 47579.8 47587.3 47703.5 47774.3 581366.0 584926.0 585103.0 585595.0 586067.0 37782.6 38123.0 38287.4 38481.0 38502.2 44112.6 44662.6 44756.8 45163.5 45189.0 28997.9 35776.8 37048.4 37072.7 37135.1 36619.8 36690.0 37268.9 37796.8 37934.6 35871.3 36276.8 36766.3 37089.6 38527.8 27259.0 33944.2 34065.3 34115.1 34132.7 21765.7 24900.8 25209.5 25473.7 25495.4 03:35:06 INFO - PROCESS | 1648 | 03:35:06 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:36416.34 Median:2926.73 stddev:98496.66 (3365.4%) stddev-sans-first:98735.79 03:35:06 INFO - PROCESS | 1648 | Values: 373.1 374.4 381.7 382.7 399.9 18235.2 19593.9 19605.3 20411.0 20608.8 19146.5 19450.5 19677.9 20509.9 21343.9 6226.4 6444.0 6736.9 6747.0 7812.9 7506.6 7783.4 7820.2 7837.2 7871.8 7763.5 7771.5 7845.6 7852.7 7880.7 7536.5 7804.3 7867.7 7948.0 7949.8 7408.0 7581.5 7584.5 7680.1 7686.2 26985.6 27461.3 27835.0 27968.6 28330.9 2320.3 2331.2 2380.4 2837.4 3016.0 16039.9 16147.3 16868.7 17434.6 17455.0 1989.1 2001.5 2044.8 2091.7 2093.7 95068.6 108927.0 137112.0 140459.0 145856.0 21470.2 21847.3 22235.7 22365.9 22459.3 34664.1 34922.8 35549.3 35702.6 35905.6 10283.6 10532.8 10607.6 10722.5 10846.9 8748.1 8751.6 8796.4 8878.6 8917.0 8383.5 9828.4 9929.5 9972.5 10043.8 3372.8 3847.5 3950.0 3984.2 3998.9 738.5 823.7 823.8 898.8 952.0 16661.4 18664.0 20177.3 20353.0 20667.7 898.8 933.3 938.5 976.5 1017.5 10165.6 10794.9 10797.5 10819.0 10853.3 582925.0 588713.0 588744.0 595458.0 598182.0 118325.0 122661.0 122737.0 123546.4 126441.4 764.8 815.9 954.7 956.3 959.9 10842.9 11439.3 17693.8 17913.9 18508.6 49595.5 53370.7 53980.2 54598.5 54856.7 76799.6 78258.0 78578.6 79433.6 79924.0 11421.5 11873.8 12005.0 12181.3 12184.8 12697.9 13029.8 13098.7 13127.0 13503.4 5303.3 5331.7 11042.2 11198.6 11310.0 6432.6 8294.1 10909.5 11336.0 11383.4 6632.5 7849.3 7983.0 11377.5 11677.9 9740.2 12015.3 12070.0 12140.8 12239.4 7646.5 11008.7 11155.6 11352.8 11437.6 03:35:06 INFO - PROCESS | 1648 | 03:35:06 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5565.71 Median:5007.01 stddev:3111.41 (62.1%) stddev-sans-first:3103.49 03:35:06 INFO - PROCESS | 1648 | Values: 1284.0 1312.7 1570.3 1743.6 1764.2 7688.8 7966.2 8336.9 8835.1 8839.1 8333.7 8585.5 8623.2 8756.1 8761.3 2350.7 2376.0 2383.1 2384.6 2388.6 2290.1 2292.8 2303.9 2332.8 2343.3 2054.9 2299.4 2337.5 2366.5 2368.7 2320.9 2329.7 2348.0 2354.8 2356.4 1886.3 2371.2 2385.1 2387.1 2393.4 8735.8 8783.5 8786.6 8805.5 8806.0 5160.6 5396.3 5400.5 5408.6 5412.5 3366.6 3515.0 3622.3 3696.5 3713.1 878.9 1005.3 1047.4 1048.9 1101.1 9613.6 9657.9 10781.7 10798.8 11156.7 4490.2 4588.0 4820.0 4823.6 4889.9 8500.0 9621.5 9795.6 9801.2 9807.1 5103.7 5266.3 5281.3 5285.6 5320.2 4151.9 4234.8 4293.7 4853.1 4870.2 3962.6 3979.0 4027.5 4074.1 4092.3 1182.6 1189.0 1190.9 1192.3 1192.9 801.5 807.1 878.8 902.5 916.0 5660.8 5729.8 5765.7 5833.2 6014.3 437.0 440.7 445.0 446.1 450.0 3820.6 3825.2 3831.5 3892.4 3986.7 10258.8 10556.5 10674.7 10685.8 10723.1 10617.0 10635.8 10639.4 10639.4 10700.6 7869.8 8136.3 8144.1 8263.8 8265.9 5491.5 5615.1 5661.8 5688.7 5779.9 9673.8 9777.4 9794.3 9833.7 9885.0 8587.8 9676.0 9911.5 9998.6 10061.3 5993.7 6789.7 6958.8 7381.2 7449.3 8069.8 8374.7 8388.5 8412.2 8420.1 7252.9 7350.7 7377.7 7510.8 7571.1 7024.8 7248.4 7328.6 7362.6 7492.2 5942.9 6760.9 6819.2 6946.2 6949.4 4843.4 4885.2 4900.9 4948.9 5004.8 4886.9 5000.4 5005.2 5008.8 5047.9 03:35:06 INFO - PROCESS | 1648 | 03:35:06 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4476.15 Median:5061.42 stddev:1438.07 (28.4%) stddev-sans-first:1406.79 03:35:06 INFO - PROCESS | 1648 | Values: 256.3 296.8 308.1 309.9 311.7 3804.0 5062.4 5202.0 5257.0 5393.0 4069.6 5325.5 5359.7 5430.2 5509.2 3094.9 3611.4 3894.4 3955.1 4148.6 3268.0 3669.9 4142.8 4158.0 4175.7 3547.6 4051.6 4086.8 4110.7 4115.4 3564.4 4071.9 4104.9 4107.0 4111.5 2982.2 3132.7 3529.4 3529.7 3576.7 4314.4 5225.8 5233.5 5297.6 5364.8 3653.8 4450.1 4582.3 4586.1 4654.0 4680.7 5368.7 5635.7 5650.0 5674.5 3381.6 3543.5 3713.5 3838.9 3880.0 5052.9 5069.8 5654.0 5846.1 5867.9 3845.5 3899.4 3922.6 3939.3 3948.3 3371.4 3441.4 3821.3 3931.9 3963.8 3520.4 3684.7 3811.0 4389.9 4535.5 5263.8 5600.4 5618.5 5620.9 5679.9 966.3 1082.9 1158.5 1222.8 1261.6 712.9 725.6 755.4 790.7 890.8 4654.5 5048.9 5051.2 5132.3 5163.2 5403.3 5583.6 5587.5 5599.3 5677.6 4458.5 4975.4 5038.7 5114.6 5141.8 2632.7 2888.9 2926.6 2959.6 2965.8 5009.7 5211.7 5845.8 5868.5 5916.7 4833.8 5070.7 5270.6 5395.9 5736.8 6139.4 6226.9 6260.1 6260.9 6269.4 4923.0 5121.9 5679.6 5865.6 5934.8 4939.4 5038.8 5136.0 5793.5 5798.5 5350.0 5718.4 6208.3 6229.0 6233.2 3334.5 3804.5 3878.3 3902.7 3964.6 4731.9 5768.3 5833.7 5905.9 5958.8 4494.9 5041.5 5354.3 5729.0 5764.0 5064.8 5457.9 5689.0 5750.8 5774.7 4263.6 4451.6 5347.6 5630.8 5673.8 5633.7 5673.4 5676.9 5679.5 5759.4 5060.5 5233.2 5619.2 5635.9 5638.1 03:35:06 INFO - PROCESS | 1648 | 03:35:06 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:20523.47 Median:4318.44 stddev:30324.41 (702.2%) stddev-sans-first:30372.85 03:35:06 INFO - PROCESS | 1648 | Values: 672.0 708.2 721.3 726.4 759.0 14075.6 15306.0 15323.5 15352.2 15374.5 11532.4 13768.2 13811.3 13827.0 13887.8 644.7 667.0 793.9 831.8 836.1 820.9 834.4 835.5 836.5 837.5 840.2 841.4 867.5 880.9 886.3 837.7 841.7 868.2 878.4 880.3 630.9 650.0 650.8 651.9 652.4 14119.2 14199.2 14347.6 14406.7 14645.3 12224.1 13806.2 14124.7 14180.3 14261.2 11754.8 12139.5 12408.3 12469.2 12623.1 4333.3 4362.5 4444.2 4460.9 4588.9 72410.6 74165.0 76732.6 90715.0 92486.5 10327.7 10471.8 10549.1 10650.5 10736.8 15389.0 17473.0 19054.7 19079.3 20020.0 6363.0 7439.5 7531.0 7585.3 7778.5 3753.6 3877.0 4081.8 4228.5 4274.9 3429.1 3511.5 3547.4 3554.2 3559.5 552.5 639.3 643.7 656.0 669.6 605.7 615.9 627.6 648.5 654.4 8483.9 8815.5 8975.7 9007.5 9012.1 553.2 569.2 619.6 622.9 655.2 4092.1 4278.6 4303.5 4451.1 4576.3 104981.0 109498.5 109691.0 113215.0 117715.0 94704.0 97550.0 100671.5 101217.5 101907.0 84337.0 86553.0 87338.6 87689.0 87911.6 8477.0 8749.1 8769.5 8776.2 8984.0 49614.0 53516.9 53521.9 53926.7 55400.0 72928.3 77351.8 80090.6 80587.0 81219.0 10664.4 10698.5 10739.1 10868.8 11188.7 12780.4 13092.9 13094.1 13219.9 13447.9 12120.0 12443.6 12480.8 12546.6 12753.7 12314.9 12784.9 12849.6 13035.6 13122.9 12283.3 12387.6 12422.4 12690.6 12879.9 10673.6 10725.4 10753.4 10857.2 11024.8 10801.9 10904.0 11000.2 11001.8 11030.7 03:35:06 INFO - PROCESS | 1648 | -------- Summary: end -------- 03:35:06 INFO - PROCESS | 1648 | 03:35:07 INFO - TEST-INFO | 1648: exit 0 03:35:07 INFO - TEST-OK | dromaeo_css | took 1115614ms 03:35:07 INFO - TEST-START | kraken 03:35:07 INFO - operating with platform_type : mac_ 03:35:07 INFO - Initialising browser for kraken test... 03:35:07 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmpANGmCA/profile http://localhost:49249/getInfo.html) 03:35:10 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 03:35:10 INFO - PROCESS | 1678 | colorDepth:24 03:35:10 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 03:35:10 INFO - PROCESS | 1678 | __metrics 03:35:10 ERROR - PROCESS | 1678 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 03:35:11 INFO - TEST-INFO | 1678: exit 0 03:35:11 INFO - Browser initialized. 03:35:11 INFO - Running cycle 1/1 for kraken test... 03:35:11 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmpANGmCA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:35:23 INFO - PROCESS | 1680 | RSS: Main: 229896192 03:35:23 INFO - PROCESS | 1680 | 03:35:26 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 03:35:27 INFO - PROCESS | 1680 | RSS: Main: 242073600 03:35:27 INFO - PROCESS | 1680 | 03:35:30 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 03:35:30 INFO - PROCESS | 1680 | RSS: Main: 249511936 03:35:30 INFO - PROCESS | 1680 | 03:35:34 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 03:35:34 INFO - PROCESS | 1680 | RSS: Main: 251396096 03:35:34 INFO - PROCESS | 1680 | 03:35:36 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 03:35:37 INFO - PROCESS | 1680 | RSS: Main: 252932096 03:35:37 INFO - PROCESS | 1680 | 03:35:39 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:35:40 INFO - PROCESS | 1680 | RSS: Main: 254627840 03:35:40 INFO - PROCESS | 1680 | 03:35:42 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 03:35:43 INFO - PROCESS | 1680 | RSS: Main: 258535424 03:35:43 INFO - PROCESS | 1680 | 03:35:46 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 03:35:47 INFO - PROCESS | 1680 | RSS: Main: 258113536 03:35:47 INFO - PROCESS | 1680 | 03:35:50 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 03:35:50 INFO - PROCESS | 1680 | RSS: Main: 258265088 03:35:50 INFO - PROCESS | 1680 | 03:35:52 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:35:53 INFO - PROCESS | 1680 | RSS: Main: 257261568 03:35:53 INFO - PROCESS | 1680 | 03:35:55 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:35:55 INFO - PROCESS | 1680 | RSS: Main: 257720320 03:35:55 INFO - PROCESS | 1680 | 03:35:58 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:35:58 INFO - PROCESS | 1680 | RSS: Main: 257249280 03:35:58 INFO - PROCESS | 1680 | 03:36:01 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:36:02 INFO - PROCESS | 1680 | RSS: Main: 258007040 03:36:02 INFO - PROCESS | 1680 | 03:36:05 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:36:06 INFO - PROCESS | 1680 | RSS: Main: 257335296 03:36:06 INFO - PROCESS | 1680 | 03:36:07 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 03:36:08 INFO - PROCESS | 1680 | RSS: Main: 259084288 03:36:08 INFO - PROCESS | 1680 | 03:36:08 INFO - PROCESS | 1680 | __start_tp_report 03:36:08 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 03:36:08 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 03:36:08 INFO - PROCESS | 1680 | |i|pagename|runs| 03:36:08 INFO - PROCESS | 1680 | |0;ai-astar;106;118;119;120;117;118;119;117;119;118 03:36:08 INFO - PROCESS | 1680 | |1;audio-beat-detection;171;137;132;134;138;144;145;129;146;145 03:36:08 INFO - PROCESS | 1680 | |2;audio-dft;219;247;199;268;273;201;197;199;194;197 03:36:08 INFO - PROCESS | 1680 | |3;audio-fft;72;64;58;84;59;64;81;83;93;60 03:36:08 INFO - PROCESS | 1680 | |4;audio-oscillator;88;68;89;71;70;71;87;98;59;76 03:36:08 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;91;88;77;131;129;91;89;87;92;86 03:36:08 INFO - PROCESS | 1680 | |6;imaging-darkroom;96;98;96;152;153;97;96;115;96;97 03:36:08 INFO - PROCESS | 1680 | |7;imaging-desaturate;86;98;83;89;90;87;104;92;128;82 03:36:08 INFO - PROCESS | 1680 | |8;json-parse-financial;55;56;56;59;61;76;93;87;93;94 03:36:08 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;78;107;102;108;85;70;69;74;70;70 03:36:08 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;87;102;87;108;105;107;87;105;107;107 03:36:08 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;146;152;153;168;142;138;158;165;199;188 03:36:08 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;164;170;169;230;230;178;182;171;227;181 03:36:08 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;71;67;67;68;69;66;68;65;65;79 03:36:08 INFO - PROCESS | 1680 | __end_tp_report 03:36:08 INFO - PROCESS | 1680 | __start_cc_report 03:36:08 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,966 03:36:08 INFO - PROCESS | 1680 | __end_cc_report 03:36:08 INFO - PROCESS | 1680 | __startTimestamp1479987368227__endTimestamp 03:36:08 INFO - PROCESS | 1680 | 03:36:08 INFO - PROCESS | 1680 | ------- Summary: start ------- 03:36:08 INFO - PROCESS | 1680 | Number of tests: 14 03:36:08 INFO - PROCESS | 1680 | 03:36:08 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:117.10 Median:118.50 stddev:4.01 (3.4%) stddev-sans-first:1.00 03:36:08 INFO - PROCESS | 1680 | Values: 106.0 118.0 119.0 120.0 117.0 118.0 119.0 117.0 119.0 118.0 03:36:08 INFO - PROCESS | 1680 | 03:36:08 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:142.10 Median:144.50 stddev:11.80 (8.2%) stddev-sans-first:6.37 03:36:08 INFO - PROCESS | 1680 | Values: 171.0 137.0 132.0 134.0 138.0 144.0 145.0 129.0 146.0 145.0 03:36:08 INFO - PROCESS | 1680 | 03:36:08 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:219.40 Median:210.00 stddev:31.30 (14.9%) stddev-sans-first:33.20 03:36:08 INFO - PROCESS | 1680 | Values: 219.0 247.0 199.0 268.0 273.0 201.0 197.0 199.0 194.0 197.0 03:36:08 INFO - PROCESS | 1680 | 03:36:08 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:71.80 Median:76.50 stddev:12.58 (16.4%) stddev-sans-first:13.34 03:36:08 INFO - PROCESS | 1680 | Values: 72.0 64.0 58.0 84.0 59.0 64.0 81.0 83.0 93.0 60.0 03:36:08 INFO - PROCESS | 1680 | 03:36:08 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:81.50 stddev:12.15 (14.9%) stddev-sans-first:12.30 03:36:08 INFO - PROCESS | 1680 | Values: 88.0 68.0 89.0 71.0 70.0 71.0 87.0 98.0 59.0 76.0 03:36:08 INFO - PROCESS | 1680 | 03:36:08 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:96.10 Median:88.50 stddev:18.36 (20.7%) stddev-sans-first:19.38 03:36:08 INFO - PROCESS | 1680 | Values: 91.0 88.0 77.0 131.0 129.0 91.0 89.0 87.0 92.0 86.0 03:36:08 INFO - PROCESS | 1680 | 03:36:08 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:109.60 Median:96.00 stddev:23.34 (24.3%) stddev-sans-first:24.23 03:36:08 INFO - PROCESS | 1680 | Values: 96.0 98.0 96.0 152.0 153.0 97.0 96.0 115.0 96.0 97.0 03:36:08 INFO - PROCESS | 1680 | 03:36:08 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:88.00 stddev:13.72 (15.6%) stddev-sans-first:14.25 03:36:08 INFO - PROCESS | 1680 | Values: 86.0 98.0 83.0 89.0 90.0 87.0 104.0 92.0 128.0 82.0 03:36:08 INFO - PROCESS | 1680 | 03:36:08 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:73.00 Median:81.50 stddev:17.28 (21.2%) stddev-sans-first:17.06 03:36:08 INFO - PROCESS | 1680 | Values: 55.0 56.0 56.0 59.0 61.0 76.0 93.0 87.0 93.0 94.0 03:36:08 INFO - PROCESS | 1680 | 03:36:08 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:83.30 Median:70.00 stddev:16.24 (23.2%) stddev-sans-first:17.11 03:36:08 INFO - PROCESS | 1680 | Values: 78.0 107.0 102.0 108.0 85.0 70.0 69.0 74.0 70.0 70.0 03:36:08 INFO - PROCESS | 1680 | 03:36:08 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:100.20 Median:107.50 stddev:9.26 (8.6%) stddev-sans-first:8.50 03:36:08 INFO - PROCESS | 1680 | Values: 87.0 102.0 87.0 108.0 105.0 107.0 87.0 105.0 107.0 107.0 03:36:08 INFO - PROCESS | 1680 | 03:36:08 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:160.90 Median:161.50 stddev:19.74 (12.2%) stddev-sans-first:20.19 03:36:08 INFO - PROCESS | 1680 | Values: 146.0 152.0 153.0 168.0 142.0 138.0 158.0 165.0 199.0 188.0 03:36:08 INFO - PROCESS | 1680 | 03:36:08 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.20 Median:181.50 stddev:27.36 (15.1%) stddev-sans-first:27.32 03:36:08 INFO - PROCESS | 1680 | Values: 164.0 170.0 169.0 230.0 230.0 178.0 182.0 171.0 227.0 181.0 03:36:08 INFO - PROCESS | 1680 | 03:36:08 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.00 stddev:4.12 (6.1%) stddev-sans-first:4.27 03:36:08 INFO - PROCESS | 1680 | Values: 71.0 67.0 67.0 68.0 69.0 66.0 68.0 65.0 65.0 79.0 03:36:08 INFO - PROCESS | 1680 | -------- Summary: end -------- 03:36:08 INFO - PROCESS | 1680 | 03:36:09 INFO - TEST-INFO | 1680: exit 0 03:36:09 INFO - TEST-OK | kraken | took 61623ms 03:36:09 INFO - SUITE-END | took 1177s 03:36:09 INFO - Completed test suite (00:19:37) 03:36:09 INFO - javascript benchmark 03:36:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7250.655389183074, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2738.8219765715226, 2992.9551056734144, 2993.2816124130186, 3317.3696997570473, 3337.349216902704, 4201.4328065711115, 5276.103062479383, 5314.840554783358, 5399.3790714067845, 5450.04624190766, 5376.166694162402, 5409.215663728757, 5421.186821976706, 5423.322084739408, 5437.700926449053, 4983.476734942833, 4985.1774457214515, 5013.172826483626, 5026.095302845486, 5030.220315851049, 4987.451380348159, 5006.949930500703, 5011.624128190382, 5036.244563315496, 5037.715382577438, 4903.558679718824, 4950.356453661019, 5022.924656130161, 5038.974805125969, 5096.082705113078, 4970.353853998975, 4990.226514890198, 5040.697558146523, 5057.595392368638, 5090.160123579642, 5019.623528235397, 5019.749012549358, 5019.799208031674, 5024.497550244946, 5069.138246498104, 5295.867603309909, 5373.382061062989, 5461.754221060062, 5468.043092458804, 5540.196671482612, 5695.857603559944, 5734.483896449339, 5788.768449262025, 5791.276090488689, 5804.042333015047, 5270.578353731692, 5990.221271234712, 6001.909971350433, 6003.879922401571, 6028.4272994065495, 5081.18701007837, 5511.035568775448, 5511.834644960658, 5524.8342549723575, 5548.223248745179, 4918.2376731606655, 5869.4717475427415, 6104.755809767648, 6121.663308518075, 6125.173101631222, 5658.624306908453, 5700.37295897451, 5703.828885133453, 5730.942690573124, 5738.454846789548, 4885.853424397274, 5025.271335656309, 5693.4875861172695, 5921.02303119989, 5932.288125424977, 5836.0, 5842.211301474332, 5845.766169353178, 5895.528357731371, 5897.085951677411, 5812.883742325214, 5842.707864606753, 5860.384659610765, 5878.059510478303, 5899.203607513016, 4665.530357937307, 4670.673052886265, 4675.602573781199, 4727.692699974491, 4738.123447162286, 4235.42821719064, 4304.526502084768, 4310.202612516632, 4313.935290970638, 4321.984333681582, 5399.595030372738, 5562.193481945095, 5577.553795696334, 5590.049691552445, 5603.971980140073, 5721.542276617861, 5998.940010599926, 6009.579329446984, 6012.428819262163, 6030.879382412327, 5404.648697834628, 5448.237246785453, 5455.61810673257, 5470.781168753205, 5497.917531237034, 5079.0, 5126.359205099363, 5131.7434128293435, 5188.429272779991, 5190.844274671766, 6105.8473538161015, 6118.418750218633, 6130.356312587203, 6131.0, 6143.293521245114, 4962.858542535309, 5623.100303951348, 5774.855628609318, 5953.315368732651, 5993.160957465871, 5358.892822143614, 5777.653340799566, 5984.162217289496, 6039.245094363205, 6078.209832721718, 5692.943070569407, 5722.856928576819, 5747.770089196385, 5751.1085781703905, 5777.3933736957015, 5845.766169353348, 5889.49939255151, 5907.4683278505145, 5914.201582786441, 5983.222181116427, 5605.131204663284, 5689.061304884648, 5696.886062278814, 5704.885902282014, 5719.48524632785, 5512.5589952804485, 5543.1685247213245, 5565.582581306499, 5633.6619802811965, 5690.402507736628, 4861.829835955866, 5397.001554712391, 5489.890202196014, 5521.613487055867, 5552.944470555404, 5634.352049514358, 5670.716464176692, 5674.829755107354, 5757.65454072757, 5766.163906233656, 5612.074007788669, 5624.493795558419, 5628.774849005994, 5628.915566266427, 5645.209670646195], "value": 5365.2666292918375, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.6548248966967, 515.8504033830189, 520.9322788037555, 540.4055538907201, 555.9833205003852, 32399.352012959785, 35561.93314200575, 36131.63868361316, 37725.43411848821, 37731.62268377315, 34765.652343476555, 38863.0, 38902.027449313784, 38906.61093389065, 38993.41509877347, 9026.954865225665, 9101.635934562626, 9337.53312334384, 9342.57958391874, 9370.156685898268, 9219.40073895198, 9326.647636092763, 9335.626574936994, 9362.344635875492, 9364.438133711965, 9298.302627302946, 9329.626814927395, 9354.81290374192, 9358.766030849216, 9377.0, 9255.167034966851, 9294.395864268869, 9327.113924177193, 9341.0, 9371.0, 9277.721668349961, 9333.766655833591, 9341.439513629204, 9343.953280233623, 9346.345755797098, 37247.81376093129, 37414.62585374139, 37519.24961500782, 37591.0, 37807.810960945295, 3134.717875391226, 3464.636213197603, 3660.0666829958454, 3788.6969042476535, 3946.1318509928055, 28959.13122606325, 29358.119256422342, 29574.964876229446, 29639.70360296391, 30158.698413016027, 3090.7636945221866, 3161.96838031619, 3199.4720871056247, 3262.429074911881, 3267.9183020424443, 487155.56422218017, 487745.0, 494942.8287161359, 495681.0, 495779.0, 56223.43776562182, 56511.15233271504, 56664.43335566592, 56667.0, 56775.43224567784, 56996.430035699945, 58027.0, 58111.12833307504, 58239.0, 58240.70879645574, 12090.879091209152, 15184.392624295126, 15291.006084604465, 15390.845686573552, 15396.692066158614, 11875.0, 11982.88017119835, 12058.914697677252, 12066.276023438622, 12076.637700868989, 13515.459381624822, 14630.26848657563, 14695.632609184855, 14699.92650036743, 14872.70254594902, 6746.76386326486, 6791.592504449749, 6818.817978247012, 6911.9308806912295, 6952.582845029315, 851.3614788908319, 927.0405130689704, 944.17856464876, 981.9754506137261, 1102.9944850275697, 25479.23562293134, 26721.0, 28126.85936570304, 28917.710822891924, 29572.112836614935, 1021.4382089850552, 1045.968620941373, 1060.9363438193734, 1062.8033813744482, 1062.9043386095288, 13135.737285254241, 14781.778273325908, 14967.0, 14998.250087495582, 15330.616734581501, 646776.0, 651746.0, 657590.0, 658939.0, 660180.0, 55276.72361638166, 55299.723501382236, 55389.0, 55568.0, 55919.44080559224, 1336.251699048536, 1342.2550484481012, 1359.2184493916038, 1362.4209710872642, 1374.4021350712271, 28544.286392840346, 28630.85684571564, 28907.42185156327, 29268.853655731586, 29684.851575742847, 47293.76353118212, 47579.76210118927, 47587.33444329432, 47703.52296476991, 47774.283385749935, 581366.0, 584926.0, 585103.0, 585595.0, 586067.0, 37782.62217377791, 38123.0, 38287.42568861414, 38481.0, 38502.22995540129, 44112.558874410846, 44662.55337446584, 44756.77621611871, 45163.54836451593, 45189.0, 28997.855010724812, 35776.82111589425, 37048.44427333538, 37072.70245541393, 37135.07162320964, 36619.81690091533, 36690.0, 37268.881933542034, 37796.811015944746, 37934.62065379311, 35871.28257434889, 36276.81861590675, 36766.264674706894, 37089.629103709696, 38527.807360964136, 27259.0, 33944.15139621529, 34065.31869362648, 34115.14712132117, 34132.658673412945, 21765.673514897608, 24900.750992490568, 25209.495810084063, 25473.66224267197, 25495.36261593475], "value": 19318.780634714334, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.1454968122998, 374.40282749015324, 381.74423136498575, 382.66516797801916, 399.9220152070346, 18235.179416926236, 19593.902030489862, 19605.313814016514, 20410.979451027462, 20608.793912060875, 19146.521336966583, 19450.51373715658, 19677.90161049193, 20509.897450512726, 21343.932803359847, 6226.408491193352, 6443.967780161092, 6736.922092465206, 6747.0, 7812.921870781304, 7506.587137707424, 7783.41624378171, 7820.217978202163, 7837.177096404881, 7871.8425631487335, 7763.495372800779, 7771.533707977539, 7845.607719614025, 7852.685892564291, 7880.684772609089, 7536.547807131562, 7804.2585954334245, 7867.6852925882895, 7948.046234451903, 7949.801254968615, 7408.035344745912, 7581.469297149201, 7584.54492730438, 7680.11678656956, 7686.231376862325, 26985.595216071775, 27461.31346716348, 27835.0, 27968.580471292946, 28330.858345708344, 2320.2923108451914, 2331.1724337859987, 2380.3811009137576, 2837.404145129525, 3016.0499442675487, 16039.919800400923, 16147.273372698257, 16868.746968795476, 17434.564135896577, 17454.952702837745, 1989.094961792381, 2001.4596059063972, 2044.7955204479433, 2091.7489901211798, 2093.706881036656, 95068.61770421706, 108927.0, 137112.0, 140458.99999999898, 145856.0, 21470.248541300978, 21847.344579662637, 22235.666465003036, 22365.888170559043, 22459.32622021342, 34664.13339666478, 34922.82538587291, 35549.28901421957, 35702.64297357045, 35905.640943590755, 10283.588656453807, 10532.842007369896, 10607.57569697219, 10722.463876806129, 10846.891531084746, 8748.081451447633, 8751.56242187888, 8796.428232164888, 8878.644854205888, 8917.0, 8383.455075420079, 9828.361156524803, 9929.503524823875, 9972.451515166735, 10043.849342259871, 3372.7976321420792, 3847.5190601174854, 3949.9585319303974, 3984.163325701635, 3998.8800335989968, 738.5015114797491, 823.6993497373535, 823.8475881961856, 898.7977705016319, 952.0479520479521, 16661.416850410176, 18664.0, 20177.293794717116, 20353.0, 20667.689984650242, 898.8022635020285, 933.2533972821716, 938.5354249646468, 976.497103991445, 1017.4607458047246, 10165.644202452879, 10794.946025269823, 10797.514111864984, 10818.972197641211, 10853.294535855144, 582925.0, 588713.0, 588744.0, 595458.0, 598182.0, 118325.0, 122661.0, 122737.0, 123546.38226808808, 126441.36779316045, 764.7629234937187, 815.9143289954588, 954.7326748510428, 956.3257903178243, 959.8800149981253, 10842.945785271024, 11439.313641181556, 17693.823061769217, 17913.92516449017, 18508.629827403645, 49595.50404495909, 53370.73314633402, 53980.19029714636, 54598.45401546093, 54856.72571637116, 76799.61600191964, 78258.0, 78578.6071069641, 79433.60283198547, 79924.0, 11421.543138274375, 11873.762524749629, 12005.0, 12181.33002684861, 12184.817227741414, 12697.87302127004, 13029.804552931524, 13098.672533186747, 13127.0, 13503.39234734459, 5303.257543943774, 5331.733413329395, 11042.227044106836, 11198.608048718255, 11310.0, 6432.6140431572385, 8294.106477178493, 10909.454527273447, 11336.0, 11383.43082845871, 6632.535722499571, 7849.293563579306, 7983.0, 11377.4880130396, 11677.941610291895, 9740.172085372822, 12015.339156346485, 12070.0, 12140.817887731515, 12239.408876845953, 7646.502977306457, 11008.669739907815, 11155.609553665583, 11352.829707554229, 11437.59968401102], "value": 11005.718590132476, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1283.9743205135894, 1312.7046414556723, 1570.3247603530485, 1743.5902562897709, 1764.206107251737, 7688.807779805507, 7966.243206895336, 8336.91663083369, 8835.072317406677, 8839.07189745077, 8333.708275217336, 8585.484870907734, 8623.180797824196, 8756.080611535792, 8761.342899282563, 2350.670906073151, 2376.0, 2383.1063351243283, 2384.5707772600927, 2388.59393903036, 2290.1068583252545, 2292.7592602776717, 2303.9308820735323, 2332.801711854486, 2343.250159948817, 2054.8767073975537, 2299.4481324482085, 2337.532493501298, 2366.4736245121885, 2368.6802281692007, 2320.9419764506024, 2329.743728189898, 2347.9882600587057, 2354.7645235476316, 2356.4226764442737, 1886.302068234762, 2371.1700904683394, 2385.1175065225802, 2387.1048356866177, 2393.3777217923466, 8735.781605459852, 8783.472991620523, 8786.560671966376, 8805.471671699719, 8805.987311459201, 5160.638755287131, 5396.298481197458, 5400.540954018913, 5408.621396502246, 5412.512873841334, 3366.612839523462, 3514.9648503514895, 3622.3117607654463, 3696.5009723687226, 3713.0717320669833, 878.8769572259889, 1005.3465247589053, 1047.4029803012286, 1048.9213309001855, 1101.0805977009206, 9613.567389467491, 9657.93762686111, 10781.676549703521, 10798.758142813598, 11156.721081972935, 4490.236659767847, 4587.954120458753, 4819.9759001205475, 4823.638227132979, 4889.9022019560125, 8499.95750021246, 9621.470819104876, 9795.608175673036, 9801.215902727765, 9807.06832850878, 5103.744812759347, 5266.34170728659, 5281.339832520935, 5285.63000589955, 5320.201969704498, 4151.91696166075, 4234.78826058702, 4293.69944103916, 4853.12643724126, 4870.172070747982, 3962.5641179470235, 3978.9853587335047, 4027.536833264212, 4074.103697186614, 4092.3247664135083, 1182.5860948667898, 1189.0309397840733, 1190.9285442873456, 1192.272713644676, 1192.8628207756046, 801.4750338528273, 807.1283014344494, 878.7539488942979, 902.4855832175597, 915.9816803663889, 5660.782931669711, 5729.799457019068, 5765.711714414262, 5833.154192642128, 6014.338422773491, 436.95193528711815, 440.66289288694173, 444.97775111244306, 446.1434046630477, 449.97525136117173, 3820.617938206213, 3825.158465137665, 3831.4827498287373, 3892.3966785148345, 3986.720929534905, 10258.794824103476, 10556.47217639115, 10674.73313167177, 10685.83971240432, 10723.14214862809, 10616.991385818335, 10635.840462393071, 10639.361638301572, 10639.41483218415, 10700.571977120828, 7869.763907082796, 8136.308413784776, 8144.144864789112, 8263.834723305501, 8265.876011859948, 5491.450854914477, 5615.129654903497, 5661.801836935688, 5688.658680479184, 5779.913301300568, 9673.806523869342, 9777.41335519871, 9794.314397992357, 9833.655822046194, 9884.950575247078, 8587.82824343522, 9676.0, 9911.504424778588, 9998.550065247227, 10061.346012509164, 5993.70031498432, 6789.72841086351, 6958.791236262718, 7381.188069312264, 7449.292317229854, 8069.798255043436, 8374.748757537283, 8388.454750441202, 8412.158784121539, 8420.073791883011, 7252.891206631799, 7350.70597176107, 7377.704891804267, 7510.760724480399, 7571.053618297713, 7024.770665133683, 7248.420126389981, 7328.633568321456, 7362.558246505227, 7492.2133176017915, 5942.881142377215, 6760.93239067603, 6819.2498825128205, 6946.16646002483, 6949.444044476329, 4843.4430040545785, 4885.169521181337, 4900.8774780630765, 4948.925766113583, 5004.84985450437, 4886.877828054327, 5000.399952005782, 5005.249212618136, 5008.77460514285, 5047.924281135859], "value": 4397.947493083002, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.27985361135205, 296.76407256231295, 308.1372157957718, 309.9457594920888, 311.6665168269952, 3803.953912674009, 5062.367204099488, 5202.0, 5256.973715131424, 5392.955283537347, 4069.552349241581, 5325.508857519891, 5359.651622644536, 5430.239766432703, 5509.1819699499165, 3094.9226269343335, 3611.404118320487, 3894.435306880501, 3955.1298714282884, 4148.605882441163, 3267.983660081708, 3669.935718641604, 4142.792860356985, 4158.022864626811, 4175.707700460969, 3547.5565554305713, 4051.635352818246, 4086.8160932758096, 4110.712250142491, 4115.403266526352, 3564.394053010981, 4071.8778436646653, 4104.87116043089, 4107.034846810997, 4111.5477297497255, 2982.1659679297295, 3132.6999295292426, 3529.4000019996406, 3529.6646818552194, 3576.7317451191007, 4314.352847072964, 5225.764840582183, 5233.476652334774, 5297.576193904517, 5364.785408583652, 3653.8355773990234, 4450.109978004412, 4582.266837306049, 4586.128635559233, 4653.953460465385, 4680.742559159245, 5368.7383464885725, 5635.71821408932, 5649.971750141264, 5674.474858687886, 3381.5942086949476, 3543.450765131409, 3713.517242758451, 3838.8848334550007, 3880.0, 5052.898942021139, 5069.822556210515, 5654.038813401731, 5846.093855452412, 5867.882642347172, 3845.4616353710503, 3899.4150877368056, 3922.5881282465502, 3939.3106206413763, 3948.3485224937563, 3371.359441706068, 3441.397755392796, 3821.3121638104853, 3931.8820435386983, 3963.841446342172, 3520.383932811748, 3684.7236457265813, 3810.961890381116, 4389.924468505236, 4535.501094879561, 5263.815017354593, 5600.383957764642, 5618.4662457066515, 5620.887582248414, 5679.886402271932, 966.2944902247539, 1082.929609575375, 1158.5134243617701, 1222.7615614955175, 1261.6467389131058, 712.8609921065446, 725.5537844225869, 755.4296506137831, 790.7192946504023, 890.7684002159356, 4654.488006319302, 5048.899022019613, 5051.1815746331295, 5132.2814805926455, 5163.173892177234, 5403.297571315704, 5583.637063590935, 5587.53572784876, 5599.284347091657, 5677.630953987978, 4458.464984201852, 4975.383000524844, 5038.6976781392505, 5114.590832733373, 5141.845744627667, 2632.697239817407, 2888.942221155565, 2926.6049083373896, 2959.615250017484, 2965.792394532362, 5009.674371165863, 5211.713355765393, 5845.824625261249, 5868.530517558585, 5916.674582897896, 4833.782479788418, 5070.721110338966, 5270.604704647167, 5395.947790180877, 5736.770529178786, 6139.355367686418, 6226.875462490815, 6260.092286618324, 6260.937390626126, 6269.369963809442, 4922.9907868886075, 5121.923171152361, 5679.649007085674, 5865.648061116347, 5934.821955341346, 4939.431965324034, 5038.84883453497, 5136.0, 5793.507551858139, 5798.536117110705, 5349.973250133725, 5718.369550006877, 6208.255009398901, 6229.0, 6233.189685340877, 3334.5164951081474, 3804.5434547854434, 3878.321293773522, 3902.746321489094, 3964.5638979712785, 4731.929021064756, 5768.250127483569, 5833.737481813413, 5905.852353691193, 5958.821235362772, 4494.865154045384, 5041.495850414929, 5354.303940487712, 5729.01833225904, 5764.0, 5064.772085256246, 5457.918131227955, 5689.008846903551, 5750.763585829067, 5774.653520788766, 4263.637590804817, 4451.554844515523, 5347.625666203328, 5630.8029218977135, 5673.8297851064535, 5633.718314084362, 5673.3759286477625, 5676.914846277226, 5679.460451257124, 5759.350698678655, 5060.468650791761, 5233.215017747369, 5619.213310136666, 5635.8591035224445, 5638.126090455986], "value": 4006.307677078441, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [671.9630420326883, 708.1608294171406, 721.310496808823, 726.4079774983388, 759.0208630866183, 14075.57773266801, 15306.005109667864, 15323.463678771246, 15352.232388380578, 15374.538763837074, 11532.365719885423, 13768.24274664893, 13811.309434528284, 13827.0, 13887.791683124737, 644.6712176789836, 666.9861810048723, 793.896793416855, 831.846108469935, 836.1053672570353, 820.9220124088232, 834.3700506117873, 835.5028757889077, 836.5273620404452, 837.4849467577426, 840.1766269056328, 841.4278290762279, 867.4621734524603, 880.8722735203391, 886.2909672262197, 837.6774941647449, 841.7474757572734, 868.2446271743561, 878.3807415771865, 880.3309484791572, 630.8580569371854, 649.9675016249216, 650.8080116365684, 651.876143532732, 652.3509108437088, 14119.223442710647, 14199.219042952633, 14347.641308967255, 14406.711865762625, 14645.267736613234, 12224.14430989831, 13806.240656763874, 14124.717505649829, 14180.290985450687, 14261.215633140173, 11754.764904701944, 12139.453724582416, 12408.255504669749, 12469.189502682388, 12623.116381853273, 4333.349997500369, 4362.498312694049, 4444.2222611042935, 4460.910781784346, 4588.862334129982, 72410.63794681046, 74165.0, 76732.61633691796, 90715.0, 92486.5375673124, 10327.690169294783, 10471.790564188826, 10549.103326217357, 10650.467476626138, 10736.785264294671, 15389.0, 17473.0, 19054.714179287323, 19079.332223372116, 20020.0, 6363.0, 7439.51643143194, 7530.962345188349, 7585.279398457138, 7778.533288002738, 3753.6246375362794, 3877.0, 4081.816318265626, 4228.450301460852, 4274.850380236739, 3429.1255729788745, 3511.4908338290807, 3547.4324108142573, 3554.2358392945125, 3559.537260156163, 552.5165480204821, 639.2999665366416, 643.7296335539085, 656.0421784195033, 669.6183175589964, 605.7153138025121, 615.8737458820931, 627.6077451592755, 648.5492582655104, 654.3718030690495, 8483.872741908875, 8815.515146666994, 8975.730728078168, 9007.459552426875, 9012.053734357798, 553.1702446330504, 569.177538456934, 619.5910698938679, 622.9221347331584, 655.1810237203496, 4092.140650463436, 4278.614924656796, 4303.526612072669, 4451.132029254264, 4576.336431217522, 104981.0, 109498.45250773855, 109691.0, 113215.0, 117715.0, 94704.0, 97550.0, 100671.49664251631, 101217.49391253144, 101906.98093019123, 84337.0, 86553.0, 87338.56330718433, 87689.0, 87911.56044219866, 8477.0, 8749.1250874912, 8769.473831570127, 8776.16626420489, 8984.0, 49614.00771984612, 53516.929661405775, 53521.929561409335, 53926.651833702905, 55400.0, 72928.27071729215, 77351.83972240533, 80090.59954700423, 80587.0, 81219.0, 10664.360138391721, 10698.465076746288, 10739.14086873064, 10868.836967445653, 11188.72028199269, 12780.424880880197, 13092.934535327262, 13094.083414160918, 13219.933900330438, 13447.932760336136, 12120.0, 12443.626691199279, 12480.812787808372, 12546.62360129161, 12753.681157971125, 12314.876851231731, 12784.936075319563, 12849.614511564294, 13035.608931732064, 13122.934385328012, 12283.324417157142, 12387.62837114888, 12422.440990155284, 12690.619281421572, 12879.871201287868, 10673.57305707794, 10725.41010244444, 10753.354798712102, 10857.18571107186, 11024.779504410028, 10801.945990269998, 10904.040050763146, 11000.22998390105, 11001.779964400826, 11030.724231894266], "value": 7228.182311652082, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1603.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 119.0, 120.0, 117.0, 118.0, 119.0, 117.0, 119.0, 118.0], "value": 117.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 137.0, 132.0, 134.0, 138.0, 144.0, 145.0, 129.0, 146.0, 145.0], "value": 142.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [219.0, 247.0, 199.0, 268.0, 273.0, 201.0, 197.0, 199.0, 194.0, 197.0], "value": 219.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 64.0, 58.0, 84.0, 59.0, 64.0, 81.0, 83.0, 93.0, 60.0], "value": 71.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 68.0, 89.0, 71.0, 70.0, 71.0, 87.0, 98.0, 59.0, 76.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 77.0, 131.0, 129.0, 91.0, 89.0, 87.0, 92.0, 86.0], "value": 96.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 96.0, 152.0, 153.0, 97.0, 96.0, 115.0, 96.0, 97.0], "value": 109.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 98.0, 83.0, 89.0, 90.0, 87.0, 104.0, 92.0, 128.0, 82.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 56.0, 59.0, 61.0, 76.0, 93.0, 87.0, 93.0, 94.0], "value": 73.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 107.0, 102.0, 108.0, 85.0, 70.0, 69.0, 74.0, 70.0, 70.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 102.0, 87.0, 108.0, 105.0, 107.0, 87.0, 105.0, 107.0, 107.0], "value": 100.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 152.0, 153.0, 168.0, 142.0, 138.0, 158.0, 165.0, 199.0, 188.0], "value": 160.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 170.0, 169.0, 230.0, 230.0, 178.0, 182.0, 171.0, 227.0, 181.0], "value": 190.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 67.0, 68.0, 69.0, 66.0, 68.0, 65.0, 65.0, 79.0], "value": 68.5, "unit": "score"}]}]} 03:36:09 INFO - Return code: 0 03:36:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:36:09 ERROR - # TBPL SUCCESS # 03:36:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:36:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:36:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:36:09 INFO - 2016-11-24 03:36:09.561 screenresolution[1685:11024] starting screenresolution argv=screenresolution get 03:36:09 INFO - 2016-11-24 03:36:09.565 screenresolution[1685:11024] Display 0: 1600x1200x32@60 03:36:09 INFO - 2016-11-24 03:36:09.571 screenresolution[1686:11027] starting screenresolution argv=screenresolution list 03:36:09 INFO - Available Modes on Display 0 03:36:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:36:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:36:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:36:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:36:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:36:09 INFO - Intel Iris: 03:36:09 INFO - Chipset Model: Intel Iris 03:36:09 INFO - Type: GPU 03:36:09 INFO - Bus: Built-In 03:36:09 INFO - VRAM (Dynamic, Max): 1536 MB 03:36:09 INFO - Vendor: Intel (0x8086) 03:36:09 INFO - Device ID: 0x0a2e 03:36:09 INFO - Revision ID: 0x0009 03:36:09 INFO - Displays: 03:36:09 INFO - Display: 03:36:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:36:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:36:09 INFO - Display Serial Number: 1600x1200 60 03:36:09 INFO - Main Display: Yes 03:36:09 INFO - Mirror: Off 03:36:09 INFO - Online: Yes 03:36:09 INFO - Rotation: Supported 03:36:09 INFO - Return code: 0 03:36:09 INFO - Running post-action listener: _resource_record_post_action 03:36:09 INFO - [mozharness: 2016-11-24 11:36:09.812374Z] Finished run-tests step (success) 03:36:09 INFO - Running post-run listener: _resource_record_post_run 03:36:09 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 32267264; Write bytes: 660180992; Read time: 195; Write time: 1906 03:36:09 INFO - TinderboxPrint: CPU usage
54.3% 03:36:09 INFO - TinderboxPrint: I/O read bytes / time
32,267,264 / 195 03:36:09 INFO - TinderboxPrint: I/O write bytes / time
660,180,992 / 1,906 03:36:09 INFO - TinderboxPrint: CPU idle
2,209.0 (45.7%) 03:36:09 INFO - TinderboxPrint: CPU system
977.2 (20.2%) 03:36:09 INFO - TinderboxPrint: CPU user
1,651.6 (34.1%) 03:36:09 INFO - TinderboxPrint: Swap in / out
376,750,080 / 0 03:36:09 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 349403648; Write bytes: 351008768; Read time: 26806; Write time: 728 03:36:09 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 29109248; Write bytes: 307832832; Read time: 174; Write time: 1172 03:36:10 INFO - Running post-run listener: _upload_blobber_files 03:36:10 INFO - Blob upload gear active. 03:36:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:36:10 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:36:10 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:36:10 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:36:10 INFO - (blobuploader) - INFO - Open directory for files ... 03:36:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:36:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:36:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:11 INFO - (blobuploader) - INFO - Done attempting. 03:36:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:36:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:36:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:13 INFO - (blobuploader) - INFO - Done attempting. 03:36:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:36:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:36:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:15 INFO - (blobuploader) - INFO - Done attempting. 03:36:15 INFO - (blobuploader) - INFO - Iteration through files over. 03:36:15 INFO - Return code: 0 03:36:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:36:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:36:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bbc0c3227a3b548dc94cdcf0f57a7d758287696e15101ec3f24be82e7e225ef7b75332653bae1d18303076c51db1469040de42dc45404914821902a4baf0679d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/651c74b1af8a8d16feb95bb6d4e8d738791f476d9e5502e47e5dbbe74dc515c414b0229deaec19156f8af33e0dacdd975c48532a865efba038d86f79467f7855", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bc18a6aab2208bb05f59aa7ee8915e0a34e26a5788448452b899d0ba64b5471140d66bd2d6fa08e2d79fa3768e3a0eac7971d99c55ef9fea19207e9840f04423"} 03:36:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:36:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:36:15 INFO - Contents: 03:36:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bbc0c3227a3b548dc94cdcf0f57a7d758287696e15101ec3f24be82e7e225ef7b75332653bae1d18303076c51db1469040de42dc45404914821902a4baf0679d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/651c74b1af8a8d16feb95bb6d4e8d738791f476d9e5502e47e5dbbe74dc515c414b0229deaec19156f8af33e0dacdd975c48532a865efba038d86f79467f7855", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bc18a6aab2208bb05f59aa7ee8915e0a34e26a5788448452b899d0ba64b5471140d66bd2d6fa08e2d79fa3768e3a0eac7971d99c55ef9fea19207e9840f04423"} 03:36:15 INFO - Running post-run listener: copy_logs_to_upload_dir 03:36:15 INFO - Copying logs to upload dir... 03:36:15 INFO - mkdir: /builds/slave/test/build/upload/logs 03:36:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.354453 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-11-24 03:36:15.790937) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-24 03:36:15.794659) ========= 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.lIm8b1BQ6j/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.iiGhUfoHz4/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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/bbc0c3227a3b548dc94cdcf0f57a7d758287696e15101ec3f24be82e7e225ef7b75332653bae1d18303076c51db1469040de42dc45404914821902a4baf0679d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/651c74b1af8a8d16feb95bb6d4e8d738791f476d9e5502e47e5dbbe74dc515c414b0229deaec19156f8af33e0dacdd975c48532a865efba038d86f79467f7855", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bc18a6aab2208bb05f59aa7ee8915e0a34e26a5788448452b899d0ba64b5471140d66bd2d6fa08e2d79fa3768e3a0eac7971d99c55ef9fea19207e9840f04423"} build_url:https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011118 build_url: 'https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/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/bbc0c3227a3b548dc94cdcf0f57a7d758287696e15101ec3f24be82e7e225ef7b75332653bae1d18303076c51db1469040de42dc45404914821902a4baf0679d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/651c74b1af8a8d16feb95bb6d4e8d738791f476d9e5502e47e5dbbe74dc515c414b0229deaec19156f8af33e0dacdd975c48532a865efba038d86f79467f7855", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bc18a6aab2208bb05f59aa7ee8915e0a34e26a5788448452b899d0ba64b5471140d66bd2d6fa08e2d79fa3768e3a0eac7971d99c55ef9fea19207e9840f04423"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-24 03:36:15.830194) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 03:36:15.830530) ========= 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.lIm8b1BQ6j/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.iiGhUfoHz4/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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.005390 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 03:36:15.879089) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-24 03:36:15.879403) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-24 03:36:15.879796) ========= ========= Total master_lag: 0.19 =========