builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0111 starttime: 1482082369.79 results: success (0) buildid: 20161218064735 builduid: a5e8dea1a2b441338cc8342447aab87f revision: 2f7c683f7d481c00b9f2664956c559235cdbb96a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-18 09:32:49.794846) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-18 09:32:49.795345) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-18 09:32:49.795639) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-18 09:32:49.821543) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 09:32:49.821977) ========= 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.UWMb3M8wb4/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.FYusncHrbF/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-18 09:32:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 53.8M=0s 2016-12-18 09:32:50 (53.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.570571 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 09:32:50.416408) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 09:32:50.416763) ========= 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.UWMb3M8wb4/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.FYusncHrbF/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.020588 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 09:32:50.466019) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-18 09:32:50.466569) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2f7c683f7d481c00b9f2664956c559235cdbb96a --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 2f7c683f7d481c00b9f2664956c559235cdbb96a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UWMb3M8wb4/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.FYusncHrbF/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-18 09:32:50,541 truncating revision to first 12 chars 2016-12-18 09:32:50,541 Setting DEBUG logging. 2016-12-18 09:32:50,541 attempt 1/10 2016-12-18 09:32:50,542 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2f7c683f7d48?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-18 09:32:51,788 unpacking tar archive at: mozilla-aurora-2f7c683f7d48/testing/mozharness/ program finished with exit code 0 elapsedTime=1.506208 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-18 09:32:51.998134) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-18 09:32:51.998589) ========= script_repo_revision: 2f7c683f7d481c00b9f2664956c559235cdbb96a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-18 09:32:51.999153) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-18 09:32:51.999527) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-18 09:32:52.020779) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-18 09:32:52.021262) ========= /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.UWMb3M8wb4/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.FYusncHrbF/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 09:32:52 INFO - MultiFileLogger online at 20161218 09:32:52 in /builds/slave/test 09:32:52 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 09:32:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:32:52 INFO - {'append_to_log': False, 09:32:52 INFO - 'base_work_dir': '/builds/slave/test', 09:32:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:32:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:32:52 INFO - 'branch': 'Mozilla-Aurora', 09:32:52 INFO - 'buildbot_json_path': 'buildprops.json', 09:32:52 INFO - 'config_files': ('talos/mac_config.py',), 09:32:52 INFO - 'default_actions': ('clobber', 09:32:52 INFO - 'read-buildbot-config', 09:32:52 INFO - 'download-and-extract', 09:32:52 INFO - 'populate-webroot', 09:32:52 INFO - 'create-virtualenv', 09:32:52 INFO - 'install', 09:32:52 INFO - 'run-tests'), 09:32:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:32:52 INFO - 'download_minidump_stackwalk': True, 09:32:52 INFO - 'download_symbols': 'ondemand', 09:32:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:32:52 INFO - 'tooltool.py': '/tools/tooltool.py', 09:32:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:32:52 INFO - '/tools/misc-python/virtualenv.py')}, 09:32:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:32:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:32:52 INFO - 'installer_path': 'installer.exe', 09:32:52 INFO - 'log_level': 'info', 09:32:52 INFO - 'log_name': 'talos', 09:32:52 INFO - 'log_to_console': True, 09:32:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:32:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:32:52 INFO - 'opt_config_files': (), 09:32:52 INFO - 'pip_index': False, 09:32:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:32:52 INFO - 'cmd': ('bash', 09:32:52 INFO - '-c', 09:32:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:32:52 INFO - 'enabled': True, 09:32:52 INFO - 'halt_on_failure': False, 09:32:52 INFO - 'name': 'check_screen_resolution'},), 09:32:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:32:52 INFO - 'cmd': ('bash', 09:32:52 INFO - '-c', 09:32:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:32:52 INFO - 'enabled': True, 09:32:52 INFO - 'halt_on_failure': False, 09:32:52 INFO - 'name': 'check_screen_resolution'},), 09:32:52 INFO - 'run_cmd_checks_enabled': True, 09:32:52 INFO - 'sps_profile': False, 09:32:52 INFO - 'sps_profile_interval': 0, 09:32:52 INFO - 'suite': 'dromaeojs-e10s', 09:32:52 INFO - 'system_bits': '32', 09:32:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:32:52 INFO - 'title': 't-yosemite-r7-0111', 09:32:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:32:52 INFO - 'use_talos_json': True, 09:32:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:32:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:32:52 INFO - 'work_dir': 'build'} 09:32:52 INFO - [mozharness: 2016-12-18 17:32:52.222113Z] Running clobber step. 09:32:52 INFO - Running pre-action listener: _resource_record_pre_action 09:32:52 INFO - Running main action method: clobber 09:32:52 INFO - rmtree: /builds/slave/test/build 09:32:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:32:53 INFO - Running post-action listener: _resource_record_post_action 09:32:53 INFO - [mozharness: 2016-12-18 17:32:53.787112Z] Finished clobber step (success) 09:32:53 INFO - [mozharness: 2016-12-18 17:32:53.787248Z] Running read-buildbot-config step. 09:32:53 INFO - Running pre-action listener: _resource_record_pre_action 09:32:53 INFO - Running main action method: read_buildbot_config 09:32:53 INFO - Using buildbot properties: 09:32:53 INFO - { 09:32:53 INFO - "project": "", 09:32:53 INFO - "product": "firefox", 09:32:53 INFO - "script_repo_revision": "production", 09:32:53 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:32:53 INFO - "repository": "", 09:32:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:32:53 INFO - "buildid": "20161218064735", 09:32:53 INFO - "pgo_build": "False", 09:32:53 INFO - "basedir": "/builds/slave/test", 09:32:53 INFO - "buildnumber": 66, 09:32:53 INFO - "slavename": "t-yosemite-r7-0111", 09:32:53 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:32:53 INFO - "platform": "yosemite_r7", 09:32:53 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:32:53 INFO - "branch": "mozilla-aurora", 09:32:53 INFO - "repo_path": "releases/mozilla-aurora", 09:32:53 INFO - "revision": "2f7c683f7d481c00b9f2664956c559235cdbb96a", 09:32:53 INFO - "stage_platform": "macosx64", 09:32:53 INFO - "builduid": "a5e8dea1a2b441338cc8342447aab87f", 09:32:53 INFO - "slavebuilddir": "test" 09:32:53 INFO - } 09:32:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:32:53 INFO - Running post-action listener: _resource_record_post_action 09:32:53 INFO - [mozharness: 2016-12-18 17:32:53.790609Z] Finished read-buildbot-config step (success) 09:32:53 INFO - [mozharness: 2016-12-18 17:32:53.790737Z] Running download-and-extract step. 09:32:53 INFO - Running pre-action listener: _resource_record_pre_action 09:32:53 INFO - Running main action method: download_and_extract 09:32:53 INFO - mkdir: /builds/slave/test/build/tests 09:32:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:53 INFO - trying https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:32:53 INFO - Downloading https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/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 09:32:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/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 09:32:55 INFO - Downloaded 1199 bytes. 09:32:55 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:32:55 INFO - Using the following test package requirements: 09:32:55 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:32:55 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:32:55 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:32:55 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:32:55 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:32:55 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:32:55 INFO - u'jsshell-mac.zip'], 09:32:55 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:32:55 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:32:55 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:32:55 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:32:55 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:32:55 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:32:55 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:32:55 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:32:55 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:32:55 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:32:55 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:32:55 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:32:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:32:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:32:55 INFO - Fetch https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:32:55 INFO - Http code: 200 09:32:55 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 09:32:55 INFO - x-amz-cf-id: V5bKVLCSHo0UFubbgJNUyyI_ejcngLsGL0KLO7AwPugBv_xqSWr_pg== 09:32:55 INFO - x-amz-version-id: xMHE4E68KHPqv2dvDMuq8YHiAZUWQsp7 09:32:56 INFO - Expected file size: 16280471 09:32:56 INFO - Obtained file size: 16280471 09:33:02 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 09:33:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:33:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:33:02 INFO - Fetch https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:33:03 INFO - Http code: 200 09:33:03 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 09:33:03 INFO - x-amz-cf-id: YbLi6Oppi90baQTI4ybHvRI1X64Z6na_NsR4DTw_BjuEjLLQk2O1rw== 09:33:03 INFO - x-amz-version-id: xMHE4E68KHPqv2dvDMuq8YHiAZUWQsp7 09:33:04 INFO - Expected file size: 16280471 09:33:04 INFO - Obtained file size: 16280471 09:33:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:33:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:33:10 INFO - Fetch https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:33:10 INFO - Http code: 200 09:33:10 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 09:33:10 INFO - x-amz-cf-id: yITZp4G-YwG_RyjqA7vozqRemO2Ek4Xvj2kpjI1hQAV_MBUmhJ7nXA== 09:33:10 INFO - x-amz-version-id: L40Ck5kmQWBxUWAoG1B0IEnbiLXBDx8n 09:33:11 INFO - Expected file size: 14409038 09:33:11 INFO - Obtained file size: 14409038 09:33:11 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')]} 09:33:11 INFO - trying https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:33:11 INFO - Downloading https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:33:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/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 09:33:14 INFO - Downloaded 109665183 bytes. 09:33:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:33:14 INFO - mkdir: /builds/slave/test/properties 09:33:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:33:14 INFO - Writing to file /builds/slave/test/properties/build_url 09:33:14 INFO - Contents: 09:33:14 INFO - build_url:https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:33:15 INFO - Running post-action listener: _resource_record_post_action 09:33:15 INFO - Running post-action listener: set_extra_try_arguments 09:33:15 INFO - [mozharness: 2016-12-18 17:33:15.521974Z] Finished download-and-extract step (success) 09:33:15 INFO - [mozharness: 2016-12-18 17:33:15.522105Z] Running populate-webroot step. 09:33:15 INFO - Running pre-action listener: _resource_record_pre_action 09:33:15 INFO - Running main action method: populate_webroot 09:33:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:33:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:33:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:33:15 INFO - '--tppagecycles', 09:33:15 INFO - '7'], 09:33:15 INFO - 'tests': ['tsvgm']}}, 09:33:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:33:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:33:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:33:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:33:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:33:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:33:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:15 INFO - 'talos_options': ['--disable-e10s'], 09:33:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:33:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:33:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:15 INFO - 'talos_options': ['--disable-e10s'], 09:33:15 INFO - 'tests': ['damp', 'tps']}, 09:33:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:15 INFO - 'tests': ['damp', 'tps']}, 09:33:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:33:15 INFO - 'tests': ['dromaeo_dom']}, 09:33:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:33:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:33:15 INFO - 'tests': ['basic_compositor_video']}, 09:33:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:33:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:33:15 INFO - 'tests': ['a11yr', 09:33:15 INFO - 'ts_paint', 09:33:15 INFO - 'tpaint', 09:33:15 INFO - 'sessionrestore', 09:33:15 INFO - 'sessionrestore_no_auto_restore', 09:33:15 INFO - 'tabpaint']}, 09:33:15 INFO - 'other-e10s': {'tests': ['a11yr', 09:33:15 INFO - 'ts_paint', 09:33:15 INFO - 'tpaint', 09:33:15 INFO - 'sessionrestore', 09:33:15 INFO - 'sessionrestore_no_auto_restore', 09:33:15 INFO - 'tabpaint']}, 09:33:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:33:15 INFO - 'ts_paint', 09:33:15 INFO - 'tpaint', 09:33:15 INFO - 'sessionrestore', 09:33:15 INFO - 'sessionrestore_no_auto_restore', 09:33:15 INFO - 'tabpaint']}, 09:33:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:33:15 INFO - 'ts_paint', 09:33:15 INFO - 'tpaint', 09:33:15 INFO - 'sessionrestore', 09:33:15 INFO - 'sessionrestore_no_auto_restore', 09:33:15 INFO - 'tabpaint']}, 09:33:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:33:15 INFO - 'tests': ['a11yr', 09:33:15 INFO - 'ts_paint', 09:33:15 INFO - 'tpaint', 09:33:15 INFO - 'sessionrestore', 09:33:15 INFO - 'sessionrestore_no_auto_restore', 09:33:15 INFO - 'tabpaint']}, 09:33:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:33:15 INFO - 'tests': ['a11yr', 09:33:15 INFO - 'ts_paint', 09:33:15 INFO - 'tpaint', 09:33:15 INFO - 'sessionrestore', 09:33:15 INFO - 'sessionrestore_no_auto_restore', 09:33:15 INFO - 'tabpaint']}, 09:33:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:33:15 INFO - 'tests': ['tsvgx', 09:33:15 INFO - 'tsvgr_opacity', 09:33:15 INFO - 'tart', 09:33:15 INFO - 'tscrollx', 09:33:15 INFO - 'cart']}, 09:33:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:33:15 INFO - 'tsvgr_opacity', 09:33:15 INFO - 'tart', 09:33:15 INFO - 'tscrollx', 09:33:15 INFO - 'cart']}, 09:33:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:15 INFO - 'talos_options': ['--disable-e10s'], 09:33:15 INFO - 'tests': ['tp5o']}, 09:33:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:15 INFO - 'tests': ['tp5o']}, 09:33:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:15 INFO - 'talos_options': ['--disable-e10s', 09:33:15 INFO - '--xperf_path', 09:33:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:33:15 INFO - 'tests': ['tp5n']}, 09:33:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:15 INFO - 'talos_options': ['--xperf_path', 09:33:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:33:15 INFO - 'tests': ['tp5n']}}, 09:33:15 INFO - 'talos.zip': {'path': '', 09:33:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:33:15 INFO - Running post-action listener: _resource_record_post_action 09:33:15 INFO - [mozharness: 2016-12-18 17:33:15.540863Z] Finished populate-webroot step (success) 09:33:15 INFO - [mozharness: 2016-12-18 17:33:15.540987Z] Running create-virtualenv step. 09:33:15 INFO - Running pre-action listener: _resource_record_pre_action 09:33:15 INFO - Running main action method: create_virtualenv 09:33:15 INFO - Creating virtualenv /builds/slave/test/build/venv 09:33:15 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 09:33:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:33:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:33:15 INFO - Using real prefix '/tools/python27' 09:33:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:33:16 INFO - Installing distribute.............................................................................................................................................................................................done. 09:33:19 INFO - Installing pip.................done. 09:33:19 INFO - Return code: 0 09:33:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:33:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:33:19 INFO - Reading from file tmpfile_stdout 09:33:19 INFO - Output received: 09:33:19 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) 09:33:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:33:19 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')]} 09:33:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:19 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')]} 09:33:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:19 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 0x10cd74490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbeb4aa170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb88d30>, '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 0x10c9eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb80b20>, '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.UWMb3M8wb4/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.FYusncHrbF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 09:33:19 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 09:33:19 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 09:33:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UWMb3M8wb4/Render', 09:33:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:19 INFO - 'HOME': '/Users/cltbld', 09:33:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:19 INFO - 'LOGNAME': 'cltbld', 09:33:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:19 INFO - 'MOZ_NO_REMOTE': '1', 09:33:19 INFO - 'NO_EM_RESTART': '1', 09:33:19 INFO - 'PAGER': '/bin/cat', 09:33:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:19 INFO - 'PWD': '/builds/slave/test', 09:33:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:19 INFO - 'SHELL': '/bin/bash', 09:33:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FYusncHrbF/Listeners', 09:33:19 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:33:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:19 INFO - 'USER': 'cltbld', 09:33:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:19 INFO - 'XPC_FLAGS': '0x0', 09:33:19 INFO - 'XPC_SERVICE_NAME': '0', 09:33:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:19 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 09:33:19 INFO - Cleaning up... 09:33:19 INFO - Return code: 0 09:33:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:33:19 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')]} 09:33:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:19 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')]} 09:33:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:19 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 0x10cd74490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbeb4aa170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb88d30>, '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 0x10c9eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb80b20>, '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.UWMb3M8wb4/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.FYusncHrbF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 09:33:19 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 09:33:19 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 09:33:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UWMb3M8wb4/Render', 09:33:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:19 INFO - 'HOME': '/Users/cltbld', 09:33:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:19 INFO - 'LOGNAME': 'cltbld', 09:33:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:19 INFO - 'MOZ_NO_REMOTE': '1', 09:33:19 INFO - 'NO_EM_RESTART': '1', 09:33:19 INFO - 'PAGER': '/bin/cat', 09:33:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:19 INFO - 'PWD': '/builds/slave/test', 09:33:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:19 INFO - 'SHELL': '/bin/bash', 09:33:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FYusncHrbF/Listeners', 09:33:19 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:33:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:19 INFO - 'USER': 'cltbld', 09:33:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:19 INFO - 'XPC_FLAGS': '0x0', 09:33:19 INFO - 'XPC_SERVICE_NAME': '0', 09:33:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:20 INFO - Downloading/unpacking psutil>=3.1.1 09:33:20 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 09:33:20 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 09:33:20 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 09:33:20 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 09:33:20 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 09:33:20 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 09:33:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:33:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:33:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:33:24 INFO - Installing collected packages: psutil 09:33:24 INFO - Running setup.py install for psutil 09:33:24 INFO - building 'psutil._psutil_osx' extension 09:33:24 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 09:33:24 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 09:33:24 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 09:33:24 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 09:33:24 INFO - building 'psutil._psutil_posix' extension 09:33:24 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 09:33:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:33:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:33:24 INFO - ^ 09:33:24 INFO - 1 warning generated. 09:33:24 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 09:33:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:33:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:33:24 INFO - Successfully installed psutil 09:33:24 INFO - Cleaning up... 09:33:24 INFO - Return code: 0 09:33:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:33: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')]} 09:33:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33: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')]} 09:33:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cd74490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbeb4aa170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb88d30>, '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 0x10c9eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb80b20>, '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.UWMb3M8wb4/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.FYusncHrbF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 09:33:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:33:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:33:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UWMb3M8wb4/Render', 09:33:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:24 INFO - 'HOME': '/Users/cltbld', 09:33:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:24 INFO - 'LOGNAME': 'cltbld', 09:33:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:24 INFO - 'MOZ_NO_REMOTE': '1', 09:33:24 INFO - 'NO_EM_RESTART': '1', 09:33:24 INFO - 'PAGER': '/bin/cat', 09:33:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:24 INFO - 'PWD': '/builds/slave/test', 09:33:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:24 INFO - 'SHELL': '/bin/bash', 09:33:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FYusncHrbF/Listeners', 09:33:24 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:33:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:24 INFO - 'USER': 'cltbld', 09:33:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:24 INFO - 'XPC_FLAGS': '0x0', 09:33:24 INFO - 'XPC_SERVICE_NAME': '0', 09:33:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:33:25 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 09:33:25 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 09:33:25 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 09:33:25 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 09:33:25 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 09:33:25 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 09:33:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:33:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:33:27 INFO - Installing collected packages: mozsystemmonitor 09:33:27 INFO - Running setup.py install for mozsystemmonitor 09:33:27 INFO - Successfully installed mozsystemmonitor 09:33:27 INFO - Cleaning up... 09:33:28 INFO - Return code: 0 09:33:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:33:28 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')]} 09:33:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:28 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')]} 09:33:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:28 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 0x10cd74490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbeb4aa170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb88d30>, '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 0x10c9eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb80b20>, '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.UWMb3M8wb4/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.FYusncHrbF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 09:33:28 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 09:33:28 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 09:33:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UWMb3M8wb4/Render', 09:33:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:28 INFO - 'HOME': '/Users/cltbld', 09:33:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:28 INFO - 'LOGNAME': 'cltbld', 09:33:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:28 INFO - 'MOZ_NO_REMOTE': '1', 09:33:28 INFO - 'NO_EM_RESTART': '1', 09:33:28 INFO - 'PAGER': '/bin/cat', 09:33:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:28 INFO - 'PWD': '/builds/slave/test', 09:33:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:28 INFO - 'SHELL': '/bin/bash', 09:33:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FYusncHrbF/Listeners', 09:33:28 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:33:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:28 INFO - 'USER': 'cltbld', 09:33:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:28 INFO - 'XPC_FLAGS': '0x0', 09:33:28 INFO - 'XPC_SERVICE_NAME': '0', 09:33:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:28 INFO - Downloading/unpacking jsonschema==2.5.1 09:33:28 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 09:33:28 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 09:33:28 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 09:33:28 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 09:33:28 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 09:33:28 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 09:33:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:33:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:31 INFO - Downloading functools32-3.2.3-2.tar.gz 09:33:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:33:31 INFO - warning: no files found matching '*.txt' 09:33:31 INFO - no previously-included directories found matching 'build' 09:33:31 INFO - no previously-included directories found matching 'dist' 09:33:31 INFO - no previously-included directories found matching '.git*' 09:33:31 INFO - Installing collected packages: jsonschema, functools32 09:33:31 INFO - Running setup.py install for functools32 09:33:31 INFO - warning: no files found matching '*.txt' 09:33:31 INFO - no previously-included directories found matching 'build' 09:33:31 INFO - no previously-included directories found matching 'dist' 09:33:31 INFO - no previously-included directories found matching '.git*' 09:33:31 INFO - Successfully installed jsonschema functools32 09:33:31 INFO - Cleaning up... 09:33:31 INFO - Return code: 0 09:33:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:33: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')]} 09:33:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33: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')]} 09:33:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:31 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 0x10cd74490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbeb4aa170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb88d30>, '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 0x10c9eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb80b20>, '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.UWMb3M8wb4/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.FYusncHrbF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 09:33:31 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 09:33:31 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 09:33:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UWMb3M8wb4/Render', 09:33:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:31 INFO - 'HOME': '/Users/cltbld', 09:33:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:31 INFO - 'LOGNAME': 'cltbld', 09:33:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:31 INFO - 'MOZ_NO_REMOTE': '1', 09:33:31 INFO - 'NO_EM_RESTART': '1', 09:33:31 INFO - 'PAGER': '/bin/cat', 09:33:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:31 INFO - 'PWD': '/builds/slave/test', 09:33:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:31 INFO - 'SHELL': '/bin/bash', 09:33:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FYusncHrbF/Listeners', 09:33:31 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:33:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:31 INFO - 'USER': 'cltbld', 09:33:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:31 INFO - 'XPC_FLAGS': '0x0', 09:33:31 INFO - 'XPC_SERVICE_NAME': '0', 09:33:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:33:31 INFO - Cleaning up... 09:33:32 INFO - Return code: 0 09:33:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:33: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')]} 09:33:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33: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')]} 09:33:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33: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', '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 0x10cd74490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbeb4aa170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb88d30>, '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 0x10c9eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb80b20>, '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.UWMb3M8wb4/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.FYusncHrbF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 09:33: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:33: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 blobuploader==1.2.4 09:33:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UWMb3M8wb4/Render', 09:33:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:32 INFO - 'HOME': '/Users/cltbld', 09:33:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:32 INFO - 'LOGNAME': 'cltbld', 09:33:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:32 INFO - 'MOZ_NO_REMOTE': '1', 09:33:32 INFO - 'NO_EM_RESTART': '1', 09:33:32 INFO - 'PAGER': '/bin/cat', 09:33:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:32 INFO - 'PWD': '/builds/slave/test', 09:33:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:32 INFO - 'SHELL': '/bin/bash', 09:33:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FYusncHrbF/Listeners', 09:33:32 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:33:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:32 INFO - 'USER': 'cltbld', 09:33:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:32 INFO - 'XPC_FLAGS': '0x0', 09:33:32 INFO - 'XPC_SERVICE_NAME': '0', 09:33:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:32 INFO - Downloading/unpacking blobuploader==1.2.4 09:33:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:34 INFO - Downloading blobuploader-1.2.4.tar.gz 09:33:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:33:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:33:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:33:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:36 INFO - Downloading docopt-0.6.1.tar.gz 09:33:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:33:36 INFO - Installing collected packages: blobuploader, requests, docopt 09:33:36 INFO - Running setup.py install for blobuploader 09:33:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:33:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:33:36 INFO - Running setup.py install for requests 09:33:36 INFO - Running setup.py install for docopt 09:33:36 INFO - Successfully installed blobuploader requests docopt 09:33:36 INFO - Cleaning up... 09:33:37 INFO - Return code: 0 09:33:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:33:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:37 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 0x10cd74490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbeb4aa170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb88d30>, '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 0x10c9eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb80b20>, '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.UWMb3M8wb4/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.FYusncHrbF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 09:33:37 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 09:33:37 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 09:33:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UWMb3M8wb4/Render', 09:33:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:37 INFO - 'HOME': '/Users/cltbld', 09:33:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:37 INFO - 'LOGNAME': 'cltbld', 09:33:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:37 INFO - 'MOZ_NO_REMOTE': '1', 09:33:37 INFO - 'NO_EM_RESTART': '1', 09:33:37 INFO - 'PAGER': '/bin/cat', 09:33:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:37 INFO - 'PWD': '/builds/slave/test', 09:33:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:37 INFO - 'SHELL': '/bin/bash', 09:33:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FYusncHrbF/Listeners', 09:33:37 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:33:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:37 INFO - 'USER': 'cltbld', 09:33:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:37 INFO - 'XPC_FLAGS': '0x0', 09:33:37 INFO - 'XPC_SERVICE_NAME': '0', 09:33:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:33:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Onk8Pe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:33:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-I1N9_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:33:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-byBfCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:33:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-94ln1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:33:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-p3hCwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:33:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-RqrqBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:33:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-RBbgCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:33:38 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Cb2HmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:33:38 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-WpJw0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:33:38 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-5wCnkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:33:38 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-8MIysI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:33:38 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-ulecv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:33:38 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-tIR4Mk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:33:38 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-9ne7nq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:38 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-bw0rxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:33:38 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Ne8XHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:33:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:33:39 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-KjXjB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:33:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:33:39 INFO - Running setup.py install for manifestparser 09:33:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:33:39 INFO - Running setup.py install for mozcrash 09:33:39 INFO - Running setup.py install for mozdebug 09:33:39 INFO - Running setup.py install for mozdevice 09:33:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:33:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:33:39 INFO - Running setup.py install for mozfile 09:33:39 INFO - Running setup.py install for mozhttpd 09:33:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:33:40 INFO - Running setup.py install for mozinfo 09:33:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:33:40 INFO - Running setup.py install for mozInstall 09:33:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:33:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:33:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:33:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:33:40 INFO - Running setup.py install for mozleak 09:33:40 INFO - Running setup.py install for mozlog 09:33:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:33:40 INFO - Running setup.py install for moznetwork 09:33:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:33:40 INFO - Running setup.py install for mozprocess 09:33:40 INFO - Running setup.py install for mozprofile 09:33:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:33:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:33:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:33:41 INFO - Running setup.py install for mozrunner 09:33:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:33:41 INFO - Running setup.py install for mozscreenshot 09:33:41 INFO - Running setup.py install for moztest 09:33:41 INFO - Running setup.py install for mozversion 09:33:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:33:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:33:41 INFO - Cleaning up... 09:33:41 INFO - Return code: 0 09:33:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:33: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')]} 09:33:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33: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')]} 09:33:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:41 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 0x10cd74490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbeb4aa170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb88d30>, '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 0x10c9eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb80b20>, '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.UWMb3M8wb4/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.FYusncHrbF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 09:33:41 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 09:33:41 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 09:33:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UWMb3M8wb4/Render', 09:33:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:41 INFO - 'HOME': '/Users/cltbld', 09:33:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:41 INFO - 'LOGNAME': 'cltbld', 09:33:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:41 INFO - 'MOZ_NO_REMOTE': '1', 09:33:41 INFO - 'NO_EM_RESTART': '1', 09:33:41 INFO - 'PAGER': '/bin/cat', 09:33:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:41 INFO - 'PWD': '/builds/slave/test', 09:33:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:41 INFO - 'SHELL': '/bin/bash', 09:33:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FYusncHrbF/Listeners', 09:33:41 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:33:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:41 INFO - 'USER': 'cltbld', 09:33:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:41 INFO - 'XPC_FLAGS': '0x0', 09:33:41 INFO - 'XPC_SERVICE_NAME': '0', 09:33:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:33:42 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-UUKF_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:33:42 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)) 09:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:33:42 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-COjfcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:33:42 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)) 09:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:33:42 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-c4TCfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:33:42 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)) 09:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:33:42 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-htu1hj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:33:42 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)) 09:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:33:42 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-UgDCIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:33:42 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)) 09:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:33:42 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-6DKSdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:33:42 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)) 09:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:33:42 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-FsNCgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:33:42 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)) 09:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:33:42 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-_JsYGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:33:42 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)) 09:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:33:42 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-rSFt7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:33:42 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)) 09:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:33:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-vWLY7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:33:43 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)) 09:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:33:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-_HsG40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:33:43 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)) 09:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:33:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-981jKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:33:43 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)) 09:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:33:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-0J2deJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:33:43 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)) 09:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:33:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-fXCeFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:33:43 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)) 09:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-y5So3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:43 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)) 09:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:33:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-OVisUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:33:43 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)) 09:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:33:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-sdRraK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:33:43 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)) 09:33:43 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)) 09:33:43 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)) 09:33:43 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)) 09:33:43 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)) 09:33:43 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)) 09:33:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:33: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 09:33: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 09:33: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 09:33: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 09:33: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 09:33: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 09:33:46 INFO - Downloading blessings-1.6.tar.gz 09:33:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:33:46 INFO - Installing collected packages: blessings 09:33:46 INFO - Running setup.py install for blessings 09:33:46 INFO - Successfully installed blessings 09:33:46 INFO - Cleaning up... 09:33:46 INFO - Return code: 0 09:33:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:33:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:33:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:33:46 INFO - Reading from file tmpfile_stdout 09:33:46 INFO - Current package versions: 09:33:46 INFO - blessings == 1.6 09:33:46 INFO - blobuploader == 1.2.4 09:33:46 INFO - docopt == 0.6.1 09:33:46 INFO - functools32 == 3.2.3-2 09:33:46 INFO - jsonschema == 2.5.1 09:33:46 INFO - manifestparser == 1.1 09:33:46 INFO - mozInstall == 1.12 09:33:46 INFO - mozcrash == 1.0 09:33:46 INFO - mozdebug == 0.1 09:33:46 INFO - mozdevice == 0.48 09:33:46 INFO - mozfile == 1.2 09:33:46 INFO - mozhttpd == 0.7 09:33:46 INFO - mozinfo == 0.9 09:33:46 INFO - mozleak == 0.1 09:33:46 INFO - mozlog == 3.4 09:33:46 INFO - moznetwork == 0.27 09:33:46 INFO - mozprocess == 0.23 09:33:46 INFO - mozprofile == 0.28 09:33:46 INFO - mozrunner == 6.13 09:33:46 INFO - mozscreenshot == 0.1 09:33:46 INFO - mozsystemmonitor == 0.3 09:33:46 INFO - moztest == 0.7 09:33:46 INFO - mozversion == 1.4 09:33:46 INFO - psutil == 3.1.1 09:33:47 INFO - requests == 1.2.3 09:33:47 INFO - wsgiref == 0.1.2 09:33:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:47 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 0x10cd74490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbeb4aa170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb88d30>, '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 0x10c9eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb80b20>, '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.UWMb3M8wb4/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.FYusncHrbF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 09:33:47 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 09:33:47 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 09:33:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UWMb3M8wb4/Render', 09:33:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:47 INFO - 'HOME': '/Users/cltbld', 09:33:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:47 INFO - 'LOGNAME': 'cltbld', 09:33:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:47 INFO - 'MOZ_NO_REMOTE': '1', 09:33:47 INFO - 'NO_EM_RESTART': '1', 09:33:47 INFO - 'PAGER': '/bin/cat', 09:33:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:47 INFO - 'PWD': '/builds/slave/test', 09:33:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:47 INFO - 'SHELL': '/bin/bash', 09:33:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FYusncHrbF/Listeners', 09:33:47 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:33:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:47 INFO - 'USER': 'cltbld', 09:33:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:47 INFO - 'XPC_FLAGS': '0x0', 09:33:47 INFO - 'XPC_SERVICE_NAME': '0', 09:33:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:47 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)) 09:33:47 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)) 09:33:47 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)) 09:33:47 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)) 09:33:47 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)) 09:33:47 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)) 09:33:47 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)) 09:33:47 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)) 09:33:47 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)) 09:33:47 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)) 09:33:47 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)) 09:33:47 INFO - Cleaning up... 09:33:47 INFO - Return code: 0 09:33:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cd74490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbeb4aa170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb88d30>, '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 0x10c9eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb80b20>, '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.UWMb3M8wb4/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.FYusncHrbF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 09:33:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:33:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:33:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UWMb3M8wb4/Render', 09:33:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:47 INFO - 'HOME': '/Users/cltbld', 09:33:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:47 INFO - 'LOGNAME': 'cltbld', 09:33:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:47 INFO - 'MOZ_NO_REMOTE': '1', 09:33:47 INFO - 'NO_EM_RESTART': '1', 09:33:47 INFO - 'PAGER': '/bin/cat', 09:33:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:47 INFO - 'PWD': '/builds/slave/test', 09:33:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:47 INFO - 'SHELL': '/bin/bash', 09:33:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FYusncHrbF/Listeners', 09:33:47 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:33:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:47 INFO - 'USER': 'cltbld', 09:33:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:47 INFO - 'XPC_FLAGS': '0x0', 09:33:47 INFO - 'XPC_SERVICE_NAME': '0', 09:33:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:33:47 INFO - Cleaning up... 09:33:47 INFO - Return code: 0 09:33:47 INFO - Running post-action listener: _resource_record_post_action 09:33:47 INFO - Running post-action listener: _start_resource_monitoring 09:33:47 INFO - Starting resource monitoring. 09:33:47 INFO - [mozharness: 2016-12-18 17:33:47.572654Z] Finished create-virtualenv step (success) 09:33:47 INFO - [mozharness: 2016-12-18 17:33:47.573769Z] Running install step. 09:33:47 INFO - Running pre-action listener: _resource_record_pre_action 09:33:47 INFO - Running main action method: install 09:33:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:33:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:33:47 INFO - Reading from file tmpfile_stdout 09:33:47 INFO - Detecting whether we're running mozinstall >=1.0... 09:33:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:33:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:33:47 INFO - Reading from file tmpfile_stdout 09:33:47 INFO - Output received: 09:33:47 INFO - Usage: mozinstall [options] installer 09:33:47 INFO - Options: 09:33:47 INFO - -h, --help show this help message and exit 09:33:47 INFO - -d DEST, --destination=DEST 09:33:47 INFO - Directory to install application into. [default: 09:33:47 INFO - "/builds/slave/test"] 09:33:47 INFO - --app=APP Application being installed. [default: firefox] 09:33:47 INFO - mkdir: /builds/slave/test/build/application 09:33:47 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'] 09:33:47 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 09:34:19 INFO - Reading from file tmpfile_stdout 09:34:19 INFO - Output received: 09:34:19 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:34:19 INFO - Running post-action listener: _resource_record_post_action 09:34:19 INFO - [mozharness: 2016-12-18 17:34:19.443953Z] Finished install step (success) 09:34:19 INFO - [mozharness: 2016-12-18 17:34:19.444103Z] Running run-tests step. 09:34:19 INFO - Running pre-action listener: _resource_record_pre_action 09:34:19 INFO - Running main action method: run_tests 09:34:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:34:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:34:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:34:19 INFO - 2016-12-18 09:34:19.458 screenresolution[2709:39647] starting screenresolution argv=screenresolution get 09:34:19 INFO - 2016-12-18 09:34:19.461 screenresolution[2709:39647] Display 0: 1600x1200x32@60 09:34:19 INFO - 2016-12-18 09:34:19.467 screenresolution[2710:39649] starting screenresolution argv=screenresolution list 09:34:19 INFO - Available Modes on Display 0 09:34:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:34:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:34:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:34:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:34:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:34:19 INFO - Intel Iris: 09:34:19 INFO - Chipset Model: Intel Iris 09:34:19 INFO - Type: GPU 09:34:19 INFO - Bus: Built-In 09:34:19 INFO - VRAM (Dynamic, Max): 1536 MB 09:34:19 INFO - Vendor: Intel (0x8086) 09:34:19 INFO - Device ID: 0x0a2e 09:34:19 INFO - Revision ID: 0x0009 09:34:19 INFO - Displays: 09:34:19 INFO - Display: 09:34:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:34:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:34:19 INFO - Display Serial Number: 1600x1200 60 09:34:19 INFO - Main Display: Yes 09:34:19 INFO - Mirror: Off 09:34:19 INFO - Online: Yes 09:34:19 INFO - Rotation: Supported 09:34:19 INFO - Return code: 0 09:34:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:34:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:34:19 INFO - Python 2.7.3 09:34:19 INFO - Return code: 0 09:34:19 INFO - grabbing minidump binary from tooltool 09:34:19 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')]} 09:34:19 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 0x10cb88d30>, '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 0x10c9eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb80b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:34:19 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 09:34:19 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 09:34:19 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 09:34:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:34:19 INFO - Return code: 0 09:34:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:34:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:34:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:34:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:34:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:34:19 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/TpOpIBitS4K5QGAH6b_R7w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 09:34:19 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/TpOpIBitS4K5QGAH6b_R7w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0111 --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 09:34:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UWMb3M8wb4/Render', 09:34:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:19 INFO - 'HOME': '/Users/cltbld', 09:34:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:19 INFO - 'LOGNAME': 'cltbld', 09:34:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:34:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:34:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:19 INFO - 'MOZ_NO_REMOTE': '1', 09:34:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:34:19 INFO - 'NO_EM_RESTART': '1', 09:34:19 INFO - 'PAGER': '/bin/cat', 09:34:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:19 INFO - 'PWD': '/builds/slave/test', 09:34:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:34:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:19 INFO - 'SHELL': '/bin/bash', 09:34:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FYusncHrbF/Listeners', 09:34:19 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:34:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:19 INFO - 'USER': 'cltbld', 09:34:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:19 INFO - 'XPC_FLAGS': '0x0', 09:34:19 INFO - 'XPC_SERVICE_NAME': '0', 09:34:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:19 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/TpOpIBitS4K5QGAH6b_R7w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 09:34:20 INFO - mozversion application_buildid: 20161218064735 09:34:20 INFO - mozversion application_changeset: 2f7c683f7d481c00b9f2664956c559235cdbb96a 09:34:20 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:34:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:34:20 INFO - mozversion application_name: Firefox 09:34:20 INFO - mozversion application_remotingname: firefox-dev 09:34:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:34:20 INFO - mozversion application_vendor: Mozilla 09:34:20 INFO - mozversion application_version: 52.0a2 09:34:20 INFO - mozversion platform_buildid: 20161218064735 09:34:20 INFO - mozversion platform_changeset: 2f7c683f7d481c00b9f2664956c559235cdbb96a 09:34:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:34:20 INFO - mozversion platform_version: 52.0a2 09:34:20 INFO - using testdate: 1482082460 09:34:20 INFO - actual date: 1482082460 09:34:20 INFO - starting webserver on 'localhost:64807' 09:34:20 INFO - SUITE-START | Running 2 tests 09:34:20 INFO - TEST-START | dromaeo_css 09:34:20 INFO - operating with platform_type : mac_ 09:34:20 INFO - Initialising browser for dromaeo_css test... 09:34:20 INFO - TEST-INFO | started process 2720 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpYAL92n/profile http://localhost:64807/getInfo.html) 09:34:23 INFO - PROCESS | 2720 | 2016-12-18 09:34:23.164 plugin-container[2722:39818] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d43, name = 'com.apple.tsm.portname' 09:34:23 INFO - PROCESS | 2720 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:34:23 INFO - PROCESS | 2720 | 2016-12-18 09:34:23.167 plugin-container[2722:39818] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920f, name = 'com.apple.CFPasteboardClient' 09:34:23 INFO - PROCESS | 2720 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:34:23 INFO - PROCESS | 2720 | 2016-12-18 09:34:23.167 plugin-container[2722:39818] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:34:23 INFO - PROCESS | 2720 | __metrics Screen width/height:1600/1200 09:34:23 INFO - PROCESS | 2720 | colorDepth:24 09:34:23 INFO - PROCESS | 2720 | Browser inner width/height: 1024/689 09:34:23 INFO - PROCESS | 2720 | __metrics 09:34:23 ERROR - PROCESS | 2720 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 09:34:24 INFO - TEST-INFO | 2720: exit 0 09:34:24 INFO - Browser initialized. 09:34:24 INFO - Running cycle 1/1 for dromaeo_css test... 09:34:24 INFO - TEST-INFO | started process 2723 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpYAL92n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:34:26 INFO - PROCESS | 2723 | 2016-12-18 09:34:26.803 plugin-container[2724:39947] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 09:34:26 INFO - PROCESS | 2723 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:34:26 INFO - PROCESS | 2723 | 2016-12-18 09:34:26.803 plugin-container[2724:39947] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x880b, name = 'com.apple.CFPasteboardClient' 09:34:26 INFO - PROCESS | 2723 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:34:26 INFO - PROCESS | 2723 | 2016-12-18 09:34:26.803 plugin-container[2724:39947] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:34:36 INFO - PROCESS | 2723 | RSS: Main: 229466112 09:34:36 INFO - PROCESS | 2723 | 09:35:26 INFO - PROCESS | 2723 | 1482082526124 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] 09:37:41 INFO - PROCESS | 2723 | Cycle 1(1): loaded http://localhost:64807/tests/dromaeo/cssquery-dojo.html (next: http://localhost:64807/tests/dromaeo/cssquery-ext.html) 09:37:42 INFO - PROCESS | 2723 | RSS: Main: 232534016 09:37:42 INFO - PROCESS | 2723 | 09:40:45 INFO - PROCESS | 2723 | Cycle 1(1): loaded http://localhost:64807/tests/dromaeo/cssquery-ext.html (next: http://localhost:64807/tests/dromaeo/cssquery-jquery.html) 09:40:45 INFO - PROCESS | 2723 | RSS: Main: 243609600 09:40:45 INFO - PROCESS | 2723 | 09:43:47 INFO - PROCESS | 2723 | Cycle 1(1): loaded http://localhost:64807/tests/dromaeo/cssquery-jquery.html (next: http://localhost:64807/tests/dromaeo/cssquery-mootools.html) 09:43:48 INFO - PROCESS | 2723 | RSS: Main: 246870016 09:43:48 INFO - PROCESS | 2723 | 09:46:50 INFO - PROCESS | 2723 | Cycle 1(1): loaded http://localhost:64807/tests/dromaeo/cssquery-mootools.html (next: http://localhost:64807/tests/dromaeo/cssquery-prototype.html) 09:46:50 INFO - PROCESS | 2723 | RSS: Main: 245547008 09:46:50 INFO - PROCESS | 2723 | 09:49:53 INFO - PROCESS | 2723 | Cycle 1(1): loaded http://localhost:64807/tests/dromaeo/cssquery-prototype.html (next: http://localhost:64807/tests/dromaeo/cssquery-yui.html) 09:49:54 INFO - PROCESS | 2723 | RSS: Main: 248967168 09:49:54 INFO - PROCESS | 2723 | 09:52:56 INFO - PROCESS | 2723 | Cycle 1(1): loaded http://localhost:64807/tests/dromaeo/cssquery-yui.html (next: http://localhost:64807/tests/dromaeo/cssquery-dojo.html) 09:52:56 INFO - PROCESS | 2723 | RSS: Main: 250847232 09:52:56 INFO - PROCESS | 2723 | 09:52:56 INFO - PROCESS | 2723 | __start_tp_report 09:52:56 INFO - PROCESS | 2723 | _x_x_mozilla_page_load 09:52:56 INFO - PROCESS | 2723 | _x_x_mozilla_page_load_details 09:52:56 INFO - PROCESS | 2723 | |i|pagename|runs| 09:52:56 INFO - PROCESS | 2723 | |0;dojo.html;3344.7156991655706;3370.730341572674;3377.3751855906658;3404.9148771280716;3410.0281419795356;5384.838454846361;5721.828345149647;5775.364709881909;5799.710014499268;5803;5752.913706294409;5781.439011466912;5795.884082318351;5826.533877289818;5846.590738648296;4192.706510544263;5273.340832395931;5284.4187139414635;5297.073012222865;5312.2828418163435;5327.174287985368;5338.225957236198;5343.679379237239;5370.838874833761;5399.946000540003;5203.713795741232;5226.92159617606;5318.1225097858805;5332.893342133155;5367.46325367464;5210.114280572312;5259.500347466985;5265.052290587692;5313.3092697949405;5403.081476149025;5191.506806853343;5239.947600524022;5264.052470555299;5275.340582427197;5310.097283461821;5738.110592858072;5845.152452894349;5855.941440585582;5871.383504731984;5906.438888305604;6109.877802443971;6145.832291864532;6150.04674275488;6151.169592105053;6173.938260617381;6154.599951003215;6182.196314479089;6232.9404001319435;6262.780802671885;6269.62382257066;5920.159680638722;5968.850778730566;5971.940280597225;5994.970025149846;6018.8495287617725;6185.350538193515;6267.874642507216;6274.905876411857;6324.272708638474;6366.076918846834;6113.385090541919;6128.258691089361;6131;6202.968985155046;6215.844603884849;6247.531435142382;6251.718672659741;6252.562320637603;6259.405356491126;6273.749050038039;4497.64018878489;6061.151438798572;6243.344448832898;6257.936150854366;6267.9373206268265;6170.722317495633;6217.9689101555105;6250.156228909039;6259.96870015647;6260.217472815898;4926.236433352836;4971;4980.850574482772;4984.626153038536;4986.2271347940405;3924.274009308287;4166.0834616384345;4254.638515674954;4476;4479.932801007987;4863.586595139453;5078.542931136216;5828.417158284223;5945.256842894638;6023.939760602338;5175.792968281226;6165.136880836597;6239.313675495692;6243.968780156161;6255.186825712628;5815.534757219412;5817.767289308465;5825;5971.164037034819;5993.430624090704;4707.82304423894;5361.731913404315;5374.086405311106;5405.810796622178;5418.295621569171;6086.878262434727;6119.2350956130485;6138.97087834436;6167.383261673976;6184.567080304336;5910.231669882888;5959.553033522417;5978.4021597839865;5992.580519363602;6008.669523176267;6160.9383906160365;6161.784337548164;6186.288576813724;6195.81412557624;6208.317085120723;6128.930927367261;6142.109394137914;6194.473469755121;6215.098810672337;6286.811395658137;6138.662373569497;6212.378762123933;6236.594621349598;6246.718897649525;6261.467775239155;5389.919151212814;6187.93582289368;6190.0405437157315;6204.534659900616;6240.282367527792;5935.376785437467;6062.211912451353;6102.115193296859;6109.222480666452;6110.51115910735;4751.809927602857;6104.145419641165;6107.114468402146;6118.741008805671;6141.263048434216;6156.776892887364;6173.72505653692;6190.969045154746;6200.945839207256;6209.379062093754;5846.707664616837;5987.880242395215;6056.757729690763;6061.909071364022;6105.297890742621 09:52:56 INFO - PROCESS | 2723 | |1;ext.html;475.37725579490865;522.4305506997374;523.0350004242174;531.9069162896492;534.8261814910151;33396.33207335858;38400.03999900004;38404.84785456434;40237.79881100594;40657.3901391479;33337.83317583885;35881.2823743525;41304.38043429343;41761.58238417615;41827.79086104573;7807.6876924922935;8159.55122468264;9490.81018379632;9503.14471697547;9521.285903557226;9257.953710231473;9267.304952128583;9317.534123293843;9369.250459963187;9390.953045234764;7542.358899493523;9315.72052838416;9425.670101546448;9431.952840235823;9471.052894710509;7991.520508769492;9402.15380615748;9404.905950940505;9407.670731524433;9408.529573521366;9195.586198621078;9206.677766278148;9223.49270790113;9320.487373194472;9335.019822918564;39129.41305880385;39441.80279098615;39569.60430395688;39574.80212598918;39712.80143599264;3261;3315.419801534722;3716.7584107033226;3738.2523495300775;3858.49518687712;29084.563731544044;29541.966031188804;29695.109146725634;30073.548896766566;30669.926552570778;3187.1713354527756;3188.5536024956523;3202.679732026802;3234.288456539581;3292.341531693647;451952;453579;455192;458692;459312.70343648404;61266;61540.07689884655;61712;61783.38216617776;61801.07298390528;64517.35482645207;64566;65134.67432662806;65154;65334.67332663306;16134.031958082553;16181.919090404472;16305.755413668567;16330.428435004718;16340.18299085041;12433.564825231073;12488.938440232681;12541.56104536355;12561.937190314173;12589.496420143274;15236;17678.989281850652;18951.81048189528;19031.238750449826;19197.32809351666;7243.963780181065;7372.755987365542;7491.138519070268;7508.136564295176;7558.281963213486;850.4132148817296;950.538988590533;1014.4319181258519;1063.8085144674033;1089.4716062709579;29701.257468563028;30057;30255.848720756254;30380;30538;733.4609062339176;770.4491288728586;992.5533509920565;1041.2450973044633;1088.6189833558192;14204.431822726976;15055.247237638074;15326.693466130615;15700.136492493022;15811.841881581036;666385;669158;672715;673527;674861;55273.72363138159;56004.439955600734;56190.43809561852;56463.43536564582;56571;1198.3289357959395;1333.5332633578364;1354.6410201296665;1366.938487768053;1378.81386012889;28735;29484;29509.704902950696;29624.259393515335;30299.93950211732;49657.75171124266;50034.749826250634;50069.24896126488;50191.24713129233;50240.49759502358;580537;582949;584393;584701;586881;25736;26049.08828191004;26259.474810504063;26548.734512654628;26561.33596660099;39924.802255932365;45134;45764.08471830478;45935.54064459446;46264.768676156404;35952.82023589865;38024.429633554966;38247.42628860514;38535;39449.81650550488;34606.30787384289;34628.82685586556;34989.77535786235;35656;35703.464448032784;23848;29411.411771764873;30261.243468913453;37060;37364;32336;33246.335073297916;33624.1593960153;34386;34471.82764086164;23193.76806231916;25858.87070564635;25945.870270648527;25952.61071083973;26350 09:52:56 INFO - PROCESS | 2723 | |2;jquery.html;351.7889266440136;363.08502573514727;373.07663532756413;373.5946498049616;390.7538250901931;18575.071246437674;18964.810351896478;19186.520336991583;19464.929428881424;19692.916889571068;18747.3438429655;18952.715709264372;19378.93415862127;19428.611427771473;19505.999999999964;4870.391201099863;5554.416786237448;5560.499555040045;5807.738651760681;5819.447152520504;5582.385937546866;5644;5744.597878148531;5752.769889204426;5757.913631295534;5639.887202255953;5693.459121383462;5694.914576281359;5698.8290351289525;5714.685622306872;5859.79490717825;5922.1116832475045;5952.791652292192;6018.8495287617725;6036.8188954331445;5609.27079479665;5776.133579962997;5811.128330750379;5817.214676018725;5863.413658634145;12884.80672789909;12937.094403391766;13056.673583160402;13600;24367.87816060926;1937.7577802774654;2172.5220451500645;2402.9639555406684;2409.361519197414;2742.5474796658527;9678.661246856396;9805.509724513746;9835.90164098357;9896;10318.38089714612;1756.2535922233;1818.5999080202334;1820.3537744100786;1826.6620675175136;1866.7759868815692;111242.88757112407;116014.41992789981;116374.83625163724;116599;117060.82939170585;9512.47681377517;9909.454979976026;10068.748281293027;10112.241581881388;11474.385568545882;12062.758744825054;12315.26108433479;12369.443375048144;12380.876191238152;12556.748865022648;6442.967785161138;7941.444098913021;7967.8406431871035;8011.599420028975;8027.799305017421;7012.474064445187;8198.401311744152;8200.097989221182;8269.503829770232;8371.748847534462;3780.7542509736777;3857.8456861725776;3871.535415750128;3907.5506316773935;3918.0988372674447;1509.7056074065672;1641.8193998660138;1815.6368726254802;1923.3652894544766;1969.5962327722782;829.6473998550592;856.8157846063002;878.1087196495562;892.9330300227509;925.2366797392178;19410.902945485464;20819.479513011993;20834.895825520776;20894.791052089284;21106.577868442546;745.392505108335;769.5575044349522;912.5710915869531;916.5417291354323;963.2534785541261;10280.897191028143;10778.892211077946;11024.724381890517;11060.949209825054;11071.833922491169;576325;581868;582440;590145;593448;73773.631131844;130702;136652.31673841568;142139;142165;423.6039303251408;452.58588391621953;452.92300308947557;998.1116806042628;1025.5333823110616;9258.95370523143;10613.9469302653;10827.621033263798;11177.72055698614;11339.206255562032;56922.43077569171;57402.13896791468;57457.425425745205;57967.4203257962;58286.4171358281;75574;76858.6157069211;78239;80915;82520.58739706263;6667.733290668319;8097.2307630774985;8144.429889907887;8185.181481852005;8247.670093196204;10946.452677366258;12570.1200915935;12675.492980280686;12873.22760634365;13006.674833129247;10311.381317120997;11002.284851484628;11024.779504410028;11045.83431248548;11052.557897684324;5030.899382012266;7370.705171793283;10731.946340268249;10734.79936613008;10872.945635271773;8933;10896.564137434414;11015;11300.378479183724;11408.144389171011;7489.550626962399;8030.598470076589;8126.471779334324;8243;8293.21214484623;10869.076128529163;11048.60351853686;11092.778144437227;11178.329300242012;11198.328100314007 09:52:56 INFO - PROCESS | 2723 | |3;mootools.html;1286.819845221669;1447.7104579084187;1606.3012589523557;1733.9133043347829;1796.6047469556702;7528;7766.8446631067345;7965.641546130423;8468.407211495205;8519.829603407929;7968.920310796891;8242.25819676229;8248.876266856001;8365.414420990533;8367.91632083679;2327.569399661064;2351.929442116739;2355.105060077168;2356.3048900574286;2360.9055637774554;2340.789328960393;2372.3120295114395;2374.3707917401903;2376.9049238030457;2385.49904520051;2325.244295603927;2327.406511339606;2349.4126468382906;2350.8354415190943;2352.4589344450787;2068.989655051715;2151.7633060363346;2300.9884950575224;2312.5028118954506;2319.652052192176;1826.488583196707;1826.7077267637246;1834.642244762272;1898.810118988104;2319.9188028419094;8356.665733370657;8387.748367548984;8397;8407.83184336316;8415.200555947236;5081.695098294114;5161.019406312788;5221.321228240343;5224.39919409269;5236.764345604457;2822.9011984580648;3291.6050073991273;3585.1216451969435;3614.710823134143;3658;1050.4380156616173;1070.318105716341;1085.1264731890824;1104.4422566603894;1107.5625128074366;8432.198941100585;10794.676159715142;10891.237613367068;10950.288231264863;10964.28732132409;3801.676857467091;4514.909701806011;4702.764861756967;4728.739919304402;4738.549837765407;9389.342746007851;9469.857952130724;9486.667966621135;9494;9531.761705957268;5222.477752224747;5228.085085110167;5229.08491014071;5232.738363081907;5289.338832645919;3858.691304695674;4241.681873859411;4248.9787551062045;4774.498677638868;4799.3760811094335;3795.525559305087;3807.9809600951817;3907.9804600976813;3928.941065884014;3933.5083114610675;1265.5444040145724;1269.0482138396203;1274.0762946863636;1277.157076329618;1286.6075846866795;883.3772190605607;892.6518657723493;893.7274131389988;899;906.2930913887178;4754.310624959361;5512.751926163332;5721.5136713379825;5758.452946970031;5852.941470585325;774.4191856107919;911.977200569991;930.237205491504;938.737153596994;950.4772375193616;3632.328019316382;3774.3960966245268;3814.504114465102;3889.0666240102737;3912.217556488714;9718.465484398426;10189.93005734402;10207.285490015776;10255.692329230134;10303.845442318372;9509.144177024102;9917.801643967225;10003.749906252402;10165.847512287322;10202;7614.885776713354;7886.842263154705;7907.327877130509;7908.52548847071;7919.366450683931;5072.163093089599;5668.319801623831;5727.398623144676;5836.620619659709;5869.767348856788;9288.674896378594;9315.25477961775;9347.112024357675;9426.575804088696;9439.716808495756;9513.23894088485;9532.714018579454;9549.761255968657;9638.132568068908;9662.516874156405;6947.40947019509;7112.822179445555;7145.785626431215;7216.3505284524645;7260.547890421831;7939.126696063313;7978.8005299865645;7985.041794984639;8007.479513831815;8011.038675358761;7066.116735408074;7100.715971361088;7158.713651453883;7191.892121618284;7333.193348731742;6927.064846245692;7048.718051277891;7050.294970502909;7160.820979475347;7186.568805871664;6582.341765823379;6586.736530538725;6620.536562440583;6656.933430665825;6668.233153187445;4632.305154226893;4634.165850147006;4669.019505903798;4750.097481478508;4791.520847915181;4654.883627909329;4672.626189904731;4698.7415692137265;4858.076965376567;4928.778204980857 09:52:56 INFO - PROCESS | 2723 | |4;prototype.html;266.9879855406507;291.3764543876107;304.1499010266306;307.947648899687;310.2383648143807;4087.0395457067566;4230.471191101108;4351.869443916684;4988.002399520093;5183.844484665466;4350.738955662662;4461.122575938518;4685.789139488723;4882.389701287339;5130.204818253167;4035.3341698619693;4054.560630976005;4055.2295063937904;4059.675225981929;4086.9386959195485;3709.851605935773;3775.490308808317;3944.4872166618297;4084.775337356444;4105.692073094515;3582.3372676054883;3650.8174591270463;3734.3838266685966;3972.0864201233735;3982.9402558961638;3350.664933506654;3954.5056867891512;3955.2287303975736;3969.9007524811823;3982.123932734794;3005.0534081764176;3383.678550537695;3440.587129544446;3442.397580423441;3464.670856268676;5105.897882042375;5152.2786809846275;5154.53609175176;5160.380754309507;5183.092958732244;3874.9612503874882;4148.56440073791;4208.494980602317;4584.312353147021;4589.518100599423;4803.447603525605;5295.364556253275;5340.412554618989;5574.832755017356;5616.382197958222;3238.206639373345;3311.172206948263;3826.617338266179;3846.903827404309;3851.5955824638568;4848.393950756155;5157.819476318311;5653.10620941985;5957.165996760457;5964.552658550582;3043.6020539404863;3091;3904.7461914975434;3916.1971795781797;3917.2361389529337;3573.249617580337;3780.4518344839844;3882.5340959085092;3885.3006458837117;3897;4364.738115713068;4593.379893714306;4594.724316541018;4612.723236605814;4634.768261586907;4652.790624421841;4683.555062269079;5666.546676265889;5747.396523364986;5756.366799652035;1221.1635030004297;1254.7866862633375;1273.630647112327;1281.8399348589542;1289.7291568770477;748.6144635512716;843.2158092973593;857.7947983083777;868.2316150207113;868.5700578213824;4133.731307465006;4475.843345482893;5056.443791182967;5056.9494305057215;5067.923981140286;4634.536546345406;5326.414094449608;5493.873755880035;5504.284443022461;5521.503064724114;3864.840547835638;4218.409422680827;4374.496932852762;4612.7001744886475;5090.414602320707;2543.465872166829;2710.2817753295394;2857.657081150275;2863.727945845141;2903.5209190483333;4355.825766969286;4765.9523404766205;4857.231378114511;5405.78376864921;5459.537716539348;4528.705634133837;5300.072487314705;5555.50000499957;5585.748641311069;5634.211210430543;5347.6470353045315;5599.524040456526;5655.802046928337;5661.631993920464;5696.829095126987;5233.7962268679175;5251.505534870906;5441.102218134122;5452.345718513804;5455.1544510600925;4434.711743736647;4520.9095818084115;4790.281457781361;5142.871428214325;5381.27352807366;4821.686590371614;5035.874103147451;5260;5574.648337595953;5614.9719251403485;3305.8842940496966;3604.9365437195565;3679.5952445231537;3706.7961262130843;3719.7768133910968;4846.321514987834;5705.145827606128;5796.739146738491;5798.391168927202;5803.9419605803405;4976.5023497649945;5659.745311460912;5662.235598194191;5662.716864156858;5675.354147297237;5047.4700156484505;5400.513953744182;5681.857953551194;5692.544596432358;5724.563463315963;4947.9466021602075;5063.038022775808;5193.7403129844115;5495.6153069284765;5555.2500412445415;4546.477155127202;4634.582887540138;4748.240281554935;5643.435656434323;5773.813984860943;5142.100132476877;5216;5239.947600523946;5456.017916775018;5523.481042713286 09:52:56 INFO - PROCESS | 2723 | |5;yui.html;665.6704931059127;715.6743681624864;752.2327226229246;785.4501848705905;786.9331106855917;13774.311284435764;13953.372098255577;14021.158730476178;14590;14623.341949612266;12133.939330303358;13167.275799831006;13253.734925301489;14157.07978981368;14776.113433194016;636.7421194416269;794.566961006253;815.4577206157927;815.8572249856281;816.102287483769;662.3145044878552;777.782220978127;791.4341246009102;804.9597520123999;808.2200676347323;856.1780690537092;859.2610355094614;862.0431321233426;862.2584577263548;863.9740807775777;844.6536919862857;851.0425771007616;854.5513605357175;857.0443954990203;857.3227150551057;633.9714712837933;634.1407392982488;634.7334119669749;636.2682914648145;637.3499030988412;14102.576922692335;14108.717825643429;14269.571912842528;14303.642408939755;14335.498257560936;11938.86580774825;12387.690307742288;13136.737265254642;13382.799258011137;13518.662033449144;10608;10934.781304373948;11194;11211;11468.598599049077;3153.731932785716;3558.679718825318;3573.8391772370046;3606.9098272543133;3699.5190625218547;88696;91573.99999999933;92454.5377273116;92987.07012929852;93339.53330233373;10152.23858210637;10250.538725757358;10260.538275777608;10263.538140783683;10271.486425678686;19410.90294548518;19585.314513991943;19641.607167856564;19723.91518466498;19766.40700778979;7280.890786638205;7330.450216233804;7381.735921418585;7496.437767167485;7636.465447418627;4161.83352665896;4208.789560521962;4227.450431443893;4255.765932873722;4292.549282325311;3367.2928684976428;3467.791932484059;3486.7210623150086;3576.8390422431053;3769.0200547857753;668.1881513960465;678.220046946016;681.4548361310931;687.9621620810904;693.6878404717897;621.4313902778906;645.7772068636312;655.7278729327371;666.3702800853147;670.6948338505968;7523.210062943422;8443.915560844436;8918.598663060178;9119.224865886474;9132.863007054899;583.2767368463075;619.3218425823716;630.2625927664643;649.4927392082697;649.8083065495596;4315.848866099816;4331.891702707458;4597.402337696145;4624.121416930772;4895.902081958341;64066;87995.56002220076;100768;110755;114412;45786.77106614446;67759.3224067753;87003.82490437789;91710.08289917016;101100;88809;89151.66272505917;90795.54602227079;92427.53786231026;93413;7531.774046778605;9468.484173405683;9756.633631713634;9761.260496650251;9786.902130978599;36303;50342.74143146451;56496.43503564912;57187;58004.41995580159;48496.787580310775;49107.017859643325;51369;69005.96491052538;70035.64982175057;12009.339486327986;12096.395180240777;12451.501939922302;12559.560415385418;12598.55905043319;13836.446542138605;13850.861491384958;13924.721505569629;14002.36989335462;14300.284985750879;12620;12986.935065324613;13303.66740831487;13366.665833354244;13664.111832730841;12424.0681948856;12994.640408824629;13205.339733013503;13492.662683432993;13863.237521935987;12524.185927914656;12752.4261408235;12968.372856893795;13085.738285234433;13160.934195328962;9107.863382049407;10607.840882386925;10665.41340226295;10937.617183398543;11014.284071535325;10758.8386174209;10761.838572421264;10783.406912619881;10788.622398216025;10867 09:52:56 INFO - PROCESS | 2723 | __end_tp_report 09:52:56 INFO - PROCESS | 2723 | __start_cc_report 09:52:56 INFO - PROCESS | 2723 | _x_x_mozilla_cycle_collect,374 09:52:56 INFO - PROCESS | 2723 | __end_cc_report 09:52:56 INFO - PROCESS | 2723 | __startTimestamp1482083576965__endTimestamp 09:52:56 INFO - PROCESS | 2723 | 09:52:56 INFO - PROCESS | 2723 | ------- Summary: start ------- 09:52:56 INFO - PROCESS | 2723 | Number of tests: 6 09:52:56 INFO - PROCESS | 2723 | 09:52:56 INFO - PROCESS | 2723 | [#0] dojo.html Cycles:165 Average:5724.55 Median:5993.43 stddev:662.70 (11.1%) stddev-sans-first:637.89 09:52:56 INFO - PROCESS | 2723 | Values: 3344.7 3370.7 3377.4 3404.9 3410.0 5384.8 5721.8 5775.4 5799.7 5803.0 5752.9 5781.4 5795.9 5826.5 5846.6 4192.7 5273.3 5284.4 5297.1 5312.3 5327.2 5338.2 5343.7 5370.8 5399.9 5203.7 5226.9 5318.1 5332.9 5367.5 5210.1 5259.5 5265.1 5313.3 5403.1 5191.5 5239.9 5264.1 5275.3 5310.1 5738.1 5845.2 5855.9 5871.4 5906.4 6109.9 6145.8 6150.0 6151.2 6173.9 6154.6 6182.2 6232.9 6262.8 6269.6 5920.2 5968.9 5971.9 5995.0 6018.8 6185.4 6267.9 6274.9 6324.3 6366.1 6113.4 6128.3 6131.0 6203.0 6215.8 6247.5 6251.7 6252.6 6259.4 6273.7 4497.6 6061.2 6243.3 6257.9 6267.9 6170.7 6218.0 6250.2 6260.0 6260.2 4926.2 4971.0 4980.9 4984.6 4986.2 3924.3 4166.1 4254.6 4476.0 4479.9 4863.6 5078.5 5828.4 5945.3 6023.9 5175.8 6165.1 6239.3 6244.0 6255.2 5815.5 5817.8 5825.0 5971.2 5993.4 4707.8 5361.7 5374.1 5405.8 5418.3 6086.9 6119.2 6139.0 6167.4 6184.6 5910.2 5959.6 5978.4 5992.6 6008.7 6160.9 6161.8 6186.3 6195.8 6208.3 6128.9 6142.1 6194.5 6215.1 6286.8 6138.7 6212.4 6236.6 6246.7 6261.5 5389.9 6187.9 6190.0 6204.5 6240.3 5935.4 6062.2 6102.1 6109.2 6110.5 4751.8 6104.1 6107.1 6118.7 6141.3 6156.8 6173.7 6191.0 6200.9 6209.4 5846.7 5987.9 6056.8 6061.9 6105.3 09:52:56 INFO - PROCESS | 2723 | 09:52:56 INFO - PROCESS | 2723 | [#1] ext.html Cycles:180 Average:69723.13 Median:39445.81 stddev:154528.16 (391.7%) stddev-sans-first:154874.18 09:52:56 INFO - PROCESS | 2723 | Values: 475.4 522.4 523.0 531.9 534.8 33396.3 38400.0 38404.8 40237.8 40657.4 33337.8 35881.3 41304.4 41761.6 41827.8 7807.7 8159.6 9490.8 9503.1 9521.3 9258.0 9267.3 9317.5 9369.3 9391.0 7542.4 9315.7 9425.7 9432.0 9471.1 7991.5 9402.2 9404.9 9407.7 9408.5 9195.6 9206.7 9223.5 9320.5 9335.0 39129.4 39441.8 39569.6 39574.8 39712.8 3261.0 3315.4 3716.8 3738.3 3858.5 29084.6 29542.0 29695.1 30073.5 30669.9 3187.2 3188.6 3202.7 3234.3 3292.3 451952.0 453579.0 455192.0 458692.0 459312.7 61266.0 61540.1 61712.0 61783.4 61801.1 64517.4 64566.0 65134.7 65154.0 65334.7 16134.0 16181.9 16305.8 16330.4 16340.2 12433.6 12488.9 12541.6 12561.9 12589.5 15236.0 17679.0 18951.8 19031.2 19197.3 7244.0 7372.8 7491.1 7508.1 7558.3 850.4 950.5 1014.4 1063.8 1089.5 29701.3 30057.0 30255.8 30380.0 30538.0 733.5 770.4 992.6 1041.2 1088.6 14204.4 15055.2 15326.7 15700.1 15811.8 666385.0 669158.0 672715.0 673527.0 674861.0 55273.7 56004.4 56190.4 56463.4 56571.0 1198.3 1333.5 1354.6 1366.9 1378.8 28735.0 29484.0 29509.7 29624.3 30299.9 49657.8 50034.7 50069.2 50191.2 50240.5 580537.0 582949.0 584393.0 584701.0 586881.0 25736.0 26049.1 26259.5 26548.7 26561.3 39924.8 45134.0 45764.1 45935.5 46264.8 35952.8 38024.4 38247.4 38535.0 39449.8 34606.3 34628.8 34989.8 35656.0 35703.5 23848.0 29411.4 30261.2 37060.0 37364.0 32336.0 33246.3 33624.2 34386.0 34471.8 23193.8 25858.9 25945.9 25952.6 26350.0 09:52:56 INFO - PROCESS | 2723 | 09:52:56 INFO - PROCESS | 2723 | [#2] jquery.html Cycles:180 Average:33804.68 Median:3864.69 stddev:97909.05 (2533.4%) stddev-sans-first:98151.49 09:52:56 INFO - PROCESS | 2723 | Values: 351.8 363.1 373.1 373.6 390.8 18575.1 18964.8 19186.5 19464.9 19692.9 18747.3 18952.7 19378.9 19428.6 19506.0 4870.4 5554.4 5560.5 5807.7 5819.4 5582.4 5644.0 5744.6 5752.8 5757.9 5639.9 5693.5 5694.9 5698.8 5714.7 5859.8 5922.1 5952.8 6018.8 6036.8 5609.3 5776.1 5811.1 5817.2 5863.4 12884.8 12937.1 13056.7 13600.0 24367.9 1937.8 2172.5 2403.0 2409.4 2742.5 9678.7 9805.5 9835.9 9896.0 10318.4 1756.3 1818.6 1820.4 1826.7 1866.8 111242.9 116014.4 116374.8 116599.0 117060.8 9512.5 9909.5 10068.7 10112.2 11474.4 12062.8 12315.3 12369.4 12380.9 12556.7 6443.0 7941.4 7967.8 8011.6 8027.8 7012.5 8198.4 8200.1 8269.5 8371.7 3780.8 3857.8 3871.5 3907.6 3918.1 1509.7 1641.8 1815.6 1923.4 1969.6 829.6 856.8 878.1 892.9 925.2 19410.9 20819.5 20834.9 20894.8 21106.6 745.4 769.6 912.6 916.5 963.3 10280.9 10778.9 11024.7 11060.9 11071.8 576325.0 581868.0 582440.0 590145.0 593448.0 73773.6 130702.0 136652.3 142139.0 142165.0 423.6 452.6 452.9 998.1 1025.5 9259.0 10613.9 10827.6 11177.7 11339.2 56922.4 57402.1 57457.4 57967.4 58286.4 75574.0 76858.6 78239.0 80915.0 82520.6 6667.7 8097.2 8144.4 8185.2 8247.7 10946.5 12570.1 12675.5 12873.2 13006.7 10311.4 11002.3 11024.8 11045.8 11052.6 5030.9 7370.7 10731.9 10734.8 10872.9 8933.0 10896.6 11015.0 11300.4 11408.1 7489.6 8030.6 8126.5 8243.0 8293.2 10869.1 11048.6 11092.8 11178.3 11198.3 09:52:56 INFO - PROCESS | 2723 | 09:52:56 INFO - PROCESS | 2723 | [#3] mootools.html Cycles:180 Average:5427.55 Median:5221.90 stddev:2988.15 (57.2%) stddev-sans-first:2980.32 09:52:56 INFO - PROCESS | 2723 | Values: 1286.8 1447.7 1606.3 1733.9 1796.6 7528.0 7766.8 7965.6 8468.4 8519.8 7968.9 8242.3 8248.9 8365.4 8367.9 2327.6 2351.9 2355.1 2356.3 2360.9 2340.8 2372.3 2374.4 2376.9 2385.5 2325.2 2327.4 2349.4 2350.8 2352.5 2069.0 2151.8 2301.0 2312.5 2319.7 1826.5 1826.7 1834.6 1898.8 2319.9 8356.7 8387.7 8397.0 8407.8 8415.2 5081.7 5161.0 5221.3 5224.4 5236.8 2822.9 3291.6 3585.1 3614.7 3658.0 1050.4 1070.3 1085.1 1104.4 1107.6 8432.2 10794.7 10891.2 10950.3 10964.3 3801.7 4514.9 4702.8 4728.7 4738.5 9389.3 9469.9 9486.7 9494.0 9531.8 5222.5 5228.1 5229.1 5232.7 5289.3 3858.7 4241.7 4249.0 4774.5 4799.4 3795.5 3808.0 3908.0 3928.9 3933.5 1265.5 1269.0 1274.1 1277.2 1286.6 883.4 892.7 893.7 899.0 906.3 4754.3 5512.8 5721.5 5758.5 5852.9 774.4 912.0 930.2 938.7 950.5 3632.3 3774.4 3814.5 3889.1 3912.2 9718.5 10189.9 10207.3 10255.7 10303.8 9509.1 9917.8 10003.7 10165.8 10202.0 7614.9 7886.8 7907.3 7908.5 7919.4 5072.2 5668.3 5727.4 5836.6 5869.8 9288.7 9315.3 9347.1 9426.6 9439.7 9513.2 9532.7 9549.8 9638.1 9662.5 6947.4 7112.8 7145.8 7216.4 7260.5 7939.1 7978.8 7985.0 8007.5 8011.0 7066.1 7100.7 7158.7 7191.9 7333.2 6927.1 7048.7 7050.3 7160.8 7186.6 6582.3 6586.7 6620.5 6656.9 6668.2 4632.3 4634.2 4669.0 4750.1 4791.5 4654.9 4672.6 4698.7 4858.1 4928.8 09:52:56 INFO - PROCESS | 2723 | 09:52:56 INFO - PROCESS | 2723 | [#4] prototype.html Cycles:180 Average:4340.18 Median:4717.01 stddev:1340.20 (28.4%) stddev-sans-first:1308.63 09:52:56 INFO - PROCESS | 2723 | Values: 267.0 291.4 304.1 307.9 310.2 4087.0 4230.5 4351.9 4988.0 5183.8 4350.7 4461.1 4685.8 4882.4 5130.2 4035.3 4054.6 4055.2 4059.7 4086.9 3709.9 3775.5 3944.5 4084.8 4105.7 3582.3 3650.8 3734.4 3972.1 3982.9 3350.7 3954.5 3955.2 3969.9 3982.1 3005.1 3383.7 3440.6 3442.4 3464.7 5105.9 5152.3 5154.5 5160.4 5183.1 3875.0 4148.6 4208.5 4584.3 4589.5 4803.4 5295.4 5340.4 5574.8 5616.4 3238.2 3311.2 3826.6 3846.9 3851.6 4848.4 5157.8 5653.1 5957.2 5964.6 3043.6 3091.0 3904.7 3916.2 3917.2 3573.2 3780.5 3882.5 3885.3 3897.0 4364.7 4593.4 4594.7 4612.7 4634.8 4652.8 4683.6 5666.5 5747.4 5756.4 1221.2 1254.8 1273.6 1281.8 1289.7 748.6 843.2 857.8 868.2 868.6 4133.7 4475.8 5056.4 5056.9 5067.9 4634.5 5326.4 5493.9 5504.3 5521.5 3864.8 4218.4 4374.5 4612.7 5090.4 2543.5 2710.3 2857.7 2863.7 2903.5 4355.8 4766.0 4857.2 5405.8 5459.5 4528.7 5300.1 5555.5 5585.7 5634.2 5347.6 5599.5 5655.8 5661.6 5696.8 5233.8 5251.5 5441.1 5452.3 5455.2 4434.7 4520.9 4790.3 5142.9 5381.3 4821.7 5035.9 5260.0 5574.6 5615.0 3305.9 3604.9 3679.6 3706.8 3719.8 4846.3 5705.1 5796.7 5798.4 5803.9 4976.5 5659.7 5662.2 5662.7 5675.4 5047.5 5400.5 5681.9 5692.5 5724.6 4947.9 5063.0 5193.7 5495.6 5555.3 4546.5 4634.6 4748.2 5643.4 5773.8 5142.1 5216.0 5239.9 5456.0 5523.5 09:52:56 INFO - PROCESS | 2723 | 09:52:56 INFO - PROCESS | 2723 | [#5] yui.html Cycles:180 Average:19350.46 Median:25059.33 stddev:28046.75 (111.9%) stddev-sans-first:28090.34 09:52:56 INFO - PROCESS | 2723 | Values: 665.7 715.7 752.2 785.5 786.9 13774.3 13953.4 14021.2 14590.0 14623.3 12133.9 13167.3 13253.7 14157.1 14776.1 636.7 794.6 815.5 815.9 816.1 662.3 777.8 791.4 805.0 808.2 856.2 859.3 862.0 862.3 864.0 844.7 851.0 854.6 857.0 857.3 634.0 634.1 634.7 636.3 637.3 14102.6 14108.7 14269.6 14303.6 14335.5 11938.9 12387.7 13136.7 13382.8 13518.7 10608.0 10934.8 11194.0 11211.0 11468.6 3153.7 3558.7 3573.8 3606.9 3699.5 88696.0 91574.0 92454.5 92987.1 93339.5 10152.2 10250.5 10260.5 10263.5 10271.5 19410.9 19585.3 19641.6 19723.9 19766.4 7280.9 7330.5 7381.7 7496.4 7636.5 4161.8 4208.8 4227.5 4255.8 4292.5 3367.3 3467.8 3486.7 3576.8 3769.0 668.2 678.2 681.5 688.0 693.7 621.4 645.8 655.7 666.4 670.7 7523.2 8443.9 8918.6 9119.2 9132.9 583.3 619.3 630.3 649.5 649.8 4315.8 4331.9 4597.4 4624.1 4895.9 64066.0 87995.6 100768.0 110755.0 114412.0 45786.8 67759.3 87003.8 91710.1 101100.0 88809.0 89151.7 90795.5 92427.5 93413.0 7531.8 9468.5 9756.6 9761.3 9786.9 36303.0 50342.7 56496.4 57187.0 58004.4 48496.8 49107.0 51369.0 69006.0 70035.6 12009.3 12096.4 12451.5 12559.6 12598.6 13836.4 13850.9 13924.7 14002.4 14300.3 12620.0 12986.9 13303.7 13366.7 13664.1 12424.1 12994.6 13205.3 13492.7 13863.2 12524.2 12752.4 12968.4 13085.7 13160.9 9107.9 10607.8 10665.4 10937.6 11014.3 10758.8 10761.8 10783.4 10788.6 10867.0 09:52:56 INFO - PROCESS | 2723 | -------- Summary: end -------- 09:52:56 INFO - PROCESS | 2723 | 09:52:57 INFO - TEST-INFO | 2723: exit 0 09:52:57 INFO - TEST-OK | dromaeo_css | took 1117668ms 09:52:57 INFO - TEST-START | kraken 09:52:57 INFO - operating with platform_type : mac_ 09:52:57 INFO - Initialising browser for kraken test... 09:52:57 INFO - TEST-INFO | started process 2729 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpxVr3DT/profile http://localhost:64807/getInfo.html) 09:53:00 INFO - PROCESS | 2729 | 2016-12-18 09:53:00.533 plugin-container[2730:42376] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 09:53:00 INFO - PROCESS | 2729 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:00 INFO - PROCESS | 2729 | 2016-12-18 09:53:00.536 plugin-container[2730:42376] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d17, name = 'com.apple.CFPasteboardClient' 09:53:00 INFO - PROCESS | 2729 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:00 INFO - PROCESS | 2729 | 2016-12-18 09:53:00.536 plugin-container[2730:42376] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:53:00 INFO - PROCESS | 2729 | __metrics Screen width/height:1600/1200 09:53:00 INFO - PROCESS | 2729 | colorDepth:24 09:53:00 INFO - PROCESS | 2729 | Browser inner width/height: 1024/689 09:53:00 INFO - PROCESS | 2729 | __metrics 09:53:00 ERROR - PROCESS | 2729 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 09:53:01 INFO - TEST-INFO | 2729: exit 0 09:53:01 INFO - Browser initialized. 09:53:01 INFO - Running cycle 1/1 for kraken test... 09:53:01 INFO - TEST-INFO | started process 2731 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpxVr3DT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:53:04 INFO - PROCESS | 2731 | 2016-12-18 09:53:04.328 plugin-container[2732:42503] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 09:53:04 INFO - PROCESS | 2731 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:04 INFO - PROCESS | 2731 | 2016-12-18 09:53:04.328 plugin-container[2732:42503] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4223, name = 'com.apple.CFPasteboardClient' 09:53:04 INFO - PROCESS | 2731 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:04 INFO - PROCESS | 2731 | 2016-12-18 09:53:04.328 plugin-container[2732:42503] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:53:13 INFO - PROCESS | 2731 | RSS: Main: 229404672 09:53:13 INFO - PROCESS | 2731 | 09:53:16 INFO - PROCESS | 2731 | Cycle 1(1): loaded http://localhost:64807/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:64807/tests/kraken/driver.html?testName=audio-beat-detection) 09:53:17 INFO - PROCESS | 2731 | RSS: Main: 240926720 09:53:17 INFO - PROCESS | 2731 | 09:53:20 INFO - PROCESS | 2731 | Cycle 1(1): loaded http://localhost:64807/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:64807/tests/kraken/driver.html?testName=audio-dft) 09:53:20 INFO - PROCESS | 2731 | RSS: Main: 248819712 09:53:20 INFO - PROCESS | 2731 | 09:53:24 INFO - PROCESS | 2731 | Cycle 1(1): loaded http://localhost:64807/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:64807/tests/kraken/driver.html?testName=audio-fft) 09:53:24 INFO - PROCESS | 2731 | RSS: Main: 252657664 09:53:24 INFO - PROCESS | 2731 | 09:53:26 INFO - PROCESS | 2731 | Cycle 1(1): loaded http://localhost:64807/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:64807/tests/kraken/driver.html?testName=audio-oscillator) 09:53:27 INFO - PROCESS | 2731 | RSS: Main: 254009344 09:53:27 INFO - PROCESS | 2731 | 09:53:29 INFO - PROCESS | 2731 | Cycle 1(1): loaded http://localhost:64807/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:64807/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:53:30 INFO - PROCESS | 2731 | RSS: Main: 254074880 09:53:30 INFO - PROCESS | 2731 | 09:53:33 INFO - PROCESS | 2731 | Cycle 1(1): loaded http://localhost:64807/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:64807/tests/kraken/driver.html?testName=imaging-darkroom) 09:53:33 INFO - PROCESS | 2731 | RSS: Main: 258699264 09:53:33 INFO - PROCESS | 2731 | 09:53:36 INFO - PROCESS | 2731 | Cycle 1(1): loaded http://localhost:64807/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:64807/tests/kraken/driver.html?testName=imaging-desaturate) 09:53:37 INFO - PROCESS | 2731 | RSS: Main: 258519040 09:53:37 INFO - PROCESS | 2731 | 09:53:40 INFO - PROCESS | 2731 | Cycle 1(1): loaded http://localhost:64807/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:64807/tests/kraken/driver.html?testName=json-parse-financial) 09:53:40 INFO - PROCESS | 2731 | RSS: Main: 258240512 09:53:40 INFO - PROCESS | 2731 | 09:53:42 INFO - PROCESS | 2731 | Cycle 1(1): loaded http://localhost:64807/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:64807/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:53:42 INFO - PROCESS | 2731 | RSS: Main: 257069056 09:53:42 INFO - PROCESS | 2731 | 09:53:44 INFO - PROCESS | 2731 | Cycle 1(1): loaded http://localhost:64807/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:64807/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:53:45 INFO - PROCESS | 2731 | RSS: Main: 257015808 09:53:45 INFO - PROCESS | 2731 | 09:53:47 INFO - PROCESS | 2731 | Cycle 1(1): loaded http://localhost:64807/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:64807/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:53:48 INFO - PROCESS | 2731 | RSS: Main: 256974848 09:53:48 INFO - PROCESS | 2731 | 09:53:51 INFO - PROCESS | 2731 | Cycle 1(1): loaded http://localhost:64807/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:64807/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:53:52 INFO - PROCESS | 2731 | RSS: Main: 257327104 09:53:52 INFO - PROCESS | 2731 | 09:53:55 INFO - PROCESS | 2731 | Cycle 1(1): loaded http://localhost:64807/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:64807/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:53:55 INFO - PROCESS | 2731 | RSS: Main: 257372160 09:53:55 INFO - PROCESS | 2731 | 09:53:57 INFO - PROCESS | 2731 | Cycle 1(1): loaded http://localhost:64807/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:64807/tests/kraken/driver.html?testName=ai-astar) 09:53:58 INFO - PROCESS | 2731 | RSS: Main: 259170304 09:53:58 INFO - PROCESS | 2731 | 09:53:58 INFO - PROCESS | 2731 | __start_tp_report 09:53:58 INFO - PROCESS | 2731 | _x_x_mozilla_page_load 09:53:58 INFO - PROCESS | 2731 | _x_x_mozilla_page_load_details 09:53:58 INFO - PROCESS | 2731 | |i|pagename|runs| 09:53:58 INFO - PROCESS | 2731 | |0;ai-astar;113;117;119;120;119;117;119;118;117;118 09:53:58 INFO - PROCESS | 2731 | |1;audio-beat-detection;144;103;148;144;102;159;145;149;144;115 09:53:58 INFO - PROCESS | 2731 | |2;audio-dft;198;198;200;237;278;199;195;203;202;209 09:53:58 INFO - PROCESS | 2731 | |3;audio-fft;83;58;59;88;60;59;59;71;104;76 09:53:58 INFO - PROCESS | 2731 | |4;audio-oscillator;85;73;90;71;71;71;90;88;91;127 09:53:58 INFO - PROCESS | 2731 | |5;imaging-gaussian-blur;91;90;104;122;92;88;93;86;92;84 09:53:58 INFO - PROCESS | 2731 | |6;imaging-darkroom;99;98;98;96;99;98;96;113;97;97 09:53:58 INFO - PROCESS | 2731 | |7;imaging-desaturate;125;144;88;91;89;87;92;88;90;90 09:53:58 INFO - PROCESS | 2731 | |8;json-parse-financial;56;58;56;60;59;61;61;61;59;60 09:53:58 INFO - PROCESS | 2731 | |9;json-stringify-tinderbox;70;75;74;105;113;110;69;101;61;74 09:53:58 INFO - PROCESS | 2731 | |10;stanford-crypto-aes;93;105;106;100;85;82;87;84;86;84 09:53:58 INFO - PROCESS | 2731 | |11;stanford-crypto-ccm;147;173;154;164;150;144;142;149;206;182 09:53:58 INFO - PROCESS | 2731 | |12;stanford-crypto-pbkdf2;166;176;169;169;177;166;166;236;228;167 09:53:58 INFO - PROCESS | 2731 | |13;stanford-crypto-sha256-iterative;69;69;70;67;74;84;89;92;73;69 09:53:58 INFO - PROCESS | 2731 | __end_tp_report 09:53:58 INFO - PROCESS | 2731 | __start_cc_report 09:53:58 INFO - PROCESS | 2731 | _x_x_mozilla_cycle_collect,1008 09:53:58 INFO - PROCESS | 2731 | __end_cc_report 09:53:58 INFO - PROCESS | 2731 | __startTimestamp1482083638035__endTimestamp 09:53:58 INFO - PROCESS | 2731 | 09:53:58 INFO - PROCESS | 2731 | ------- Summary: start ------- 09:53:58 INFO - PROCESS | 2731 | Number of tests: 14 09:53:58 INFO - PROCESS | 2731 | 09:53:58 INFO - PROCESS | 2731 | [#0] ai-astar Cycles:10 Average:117.70 Median:118.50 stddev:1.95 (1.6%) stddev-sans-first:1.09 09:53:58 INFO - PROCESS | 2731 | Values: 113.0 117.0 119.0 120.0 119.0 117.0 119.0 118.0 117.0 118.0 09:53:58 INFO - PROCESS | 2731 | 09:53:58 INFO - PROCESS | 2731 | [#1] audio-beat-detection Cycles:10 Average:135.30 Median:144.50 stddev:20.54 (14.2%) stddev-sans-first:21.54 09:53:58 INFO - PROCESS | 2731 | Values: 144.0 103.0 148.0 144.0 102.0 159.0 145.0 149.0 144.0 115.0 09:53:58 INFO - PROCESS | 2731 | 09:53:58 INFO - PROCESS | 2731 | [#2] audio-dft Cycles:10 Average:211.90 Median:202.50 stddev:26.17 (12.9%) stddev-sans-first:27.27 09:53:58 INFO - PROCESS | 2731 | Values: 198.0 198.0 200.0 237.0 278.0 199.0 195.0 203.0 202.0 209.0 09:53:58 INFO - PROCESS | 2731 | 09:53:58 INFO - PROCESS | 2731 | [#3] audio-fft Cycles:10 Average:71.70 Median:65.50 stddev:15.86 (24.2%) stddev-sans-first:16.29 09:53:58 INFO - PROCESS | 2731 | Values: 83.0 58.0 59.0 88.0 60.0 59.0 59.0 71.0 104.0 76.0 09:53:58 INFO - PROCESS | 2731 | 09:53:58 INFO - PROCESS | 2731 | [#4] audio-oscillator Cycles:10 Average:85.70 Median:86.50 stddev:16.95 (19.6%) stddev-sans-first:17.98 09:53:58 INFO - PROCESS | 2731 | Values: 85.0 73.0 90.0 71.0 71.0 71.0 90.0 88.0 91.0 127.0 09:53:58 INFO - PROCESS | 2731 | 09:53:58 INFO - PROCESS | 2731 | [#5] imaging-gaussian-blur Cycles:10 Average:94.20 Median:90.50 stddev:11.14 (12.3%) stddev-sans-first:11.76 09:53:58 INFO - PROCESS | 2731 | Values: 91.0 90.0 104.0 122.0 92.0 88.0 93.0 86.0 92.0 84.0 09:53:58 INFO - PROCESS | 2731 | 09:53:58 INFO - PROCESS | 2731 | [#6] imaging-darkroom Cycles:10 Average:99.10 Median:98.00 stddev:5.00 (5.1%) stddev-sans-first:5.30 09:53:58 INFO - PROCESS | 2731 | Values: 99.0 98.0 98.0 96.0 99.0 98.0 96.0 113.0 97.0 97.0 09:53:58 INFO - PROCESS | 2731 | 09:53:58 INFO - PROCESS | 2731 | [#7] imaging-desaturate Cycles:10 Average:98.40 Median:89.50 stddev:19.60 (21.9%) stddev-sans-first:18.28 09:53:58 INFO - PROCESS | 2731 | Values: 125.0 144.0 88.0 91.0 89.0 87.0 92.0 88.0 90.0 90.0 09:53:58 INFO - PROCESS | 2731 | 09:53:58 INFO - PROCESS | 2731 | [#8] json-parse-financial Cycles:10 Average:59.10 Median:60.00 stddev:1.91 (3.2%) stddev-sans-first:1.67 09:53:58 INFO - PROCESS | 2731 | Values: 56.0 58.0 56.0 60.0 59.0 61.0 61.0 61.0 59.0 60.0 09:53:58 INFO - PROCESS | 2731 | 09:53:58 INFO - PROCESS | 2731 | [#9] json-stringify-tinderbox Cycles:10 Average:85.20 Median:69.50 stddev:19.62 (28.2%) stddev-sans-first:20.02 09:53:58 INFO - PROCESS | 2731 | Values: 70.0 75.0 74.0 105.0 113.0 110.0 69.0 101.0 61.0 74.0 09:53:58 INFO - PROCESS | 2731 | 09:53:58 INFO - PROCESS | 2731 | [#10] stanford-crypto-aes Cycles:10 Average:91.20 Median:84.50 stddev:9.20 (10.9%) stddev-sans-first:9.73 09:53:58 INFO - PROCESS | 2731 | Values: 93.0 105.0 106.0 100.0 85.0 82.0 87.0 84.0 86.0 84.0 09:53:58 INFO - PROCESS | 2731 | 09:53:58 INFO - PROCESS | 2731 | [#11] stanford-crypto-ccm Cycles:10 Average:161.10 Median:159.00 stddev:20.49 (12.9%) stddev-sans-first:21.09 09:53:58 INFO - PROCESS | 2731 | Values: 147.0 173.0 154.0 164.0 150.0 144.0 142.0 149.0 206.0 182.0 09:53:58 INFO - PROCESS | 2731 | 09:53:58 INFO - PROCESS | 2731 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.00 Median:172.50 stddev:26.72 (15.5%) stddev-sans-first:27.70 09:53:58 INFO - PROCESS | 2731 | Values: 166.0 176.0 169.0 169.0 177.0 166.0 166.0 236.0 228.0 167.0 09:53:58 INFO - PROCESS | 2731 | 09:53:58 INFO - PROCESS | 2731 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.60 Median:73.50 stddev:9.22 (12.5%) stddev-sans-first:9.46 09:53:58 INFO - PROCESS | 2731 | Values: 69.0 69.0 70.0 67.0 74.0 84.0 89.0 92.0 73.0 69.0 09:53:58 INFO - PROCESS | 2731 | -------- Summary: end -------- 09:53:58 INFO - PROCESS | 2731 | 09:53:59 INFO - TEST-INFO | 2731: exit 0 09:53:59 INFO - TEST-OK | kraken | took 61371ms 09:53:59 INFO - SUITE-END | took 1179s 09:53:59 INFO - Completed test suite (00:19:39) 09:53:59 INFO - javascript benchmark 09:53:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7024.017214448759, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3344.7156991655706, 3370.730341572674, 3377.3751855906658, 3404.9148771280716, 3410.0281419795356, 5384.838454846361, 5721.828345149647, 5775.364709881909, 5799.710014499268, 5803.0, 5752.913706294409, 5781.439011466912, 5795.884082318351, 5826.533877289818, 5846.590738648296, 4192.706510544263, 5273.340832395931, 5284.4187139414635, 5297.073012222865, 5312.2828418163435, 5327.174287985368, 5338.225957236198, 5343.679379237239, 5370.838874833761, 5399.946000540003, 5203.713795741232, 5226.92159617606, 5318.1225097858805, 5332.893342133155, 5367.46325367464, 5210.114280572312, 5259.500347466985, 5265.052290587692, 5313.3092697949405, 5403.081476149025, 5191.506806853343, 5239.947600524022, 5264.052470555299, 5275.340582427197, 5310.097283461821, 5738.110592858072, 5845.152452894349, 5855.941440585582, 5871.383504731984, 5906.438888305604, 6109.877802443971, 6145.832291864532, 6150.04674275488, 6151.169592105053, 6173.938260617381, 6154.599951003215, 6182.196314479089, 6232.9404001319435, 6262.780802671885, 6269.62382257066, 5920.159680638722, 5968.850778730566, 5971.940280597225, 5994.970025149846, 6018.8495287617725, 6185.350538193515, 6267.874642507216, 6274.905876411857, 6324.272708638474, 6366.076918846834, 6113.385090541919, 6128.258691089361, 6131.0, 6202.968985155046, 6215.844603884849, 6247.531435142382, 6251.718672659741, 6252.562320637603, 6259.405356491126, 6273.749050038039, 4497.64018878489, 6061.151438798572, 6243.344448832898, 6257.936150854366, 6267.9373206268265, 6170.722317495633, 6217.9689101555105, 6250.156228909039, 6259.96870015647, 6260.217472815898, 4926.236433352836, 4971.0, 4980.850574482772, 4984.626153038536, 4986.2271347940405, 3924.274009308287, 4166.0834616384345, 4254.638515674954, 4476.0, 4479.932801007987, 4863.586595139453, 5078.542931136216, 5828.417158284223, 5945.256842894638, 6023.939760602338, 5175.792968281226, 6165.136880836597, 6239.313675495692, 6243.968780156161, 6255.186825712628, 5815.534757219412, 5817.767289308465, 5825.0, 5971.164037034819, 5993.430624090704, 4707.82304423894, 5361.731913404315, 5374.086405311106, 5405.810796622178, 5418.295621569171, 6086.878262434727, 6119.2350956130485, 6138.97087834436, 6167.383261673976, 6184.567080304336, 5910.231669882888, 5959.553033522417, 5978.4021597839865, 5992.580519363602, 6008.669523176267, 6160.9383906160365, 6161.784337548164, 6186.288576813724, 6195.81412557624, 6208.317085120723, 6128.930927367261, 6142.109394137914, 6194.473469755121, 6215.098810672337, 6286.811395658137, 6138.662373569497, 6212.378762123933, 6236.594621349598, 6246.718897649525, 6261.467775239155, 5389.919151212814, 6187.93582289368, 6190.0405437157315, 6204.534659900616, 6240.282367527792, 5935.376785437467, 6062.211912451353, 6102.115193296859, 6109.222480666452, 6110.51115910735, 4751.809927602857, 6104.145419641165, 6107.114468402146, 6118.741008805671, 6141.263048434216, 6156.776892887364, 6173.72505653692, 6190.969045154746, 6200.945839207256, 6209.379062093754, 5846.707664616837, 5987.880242395215, 6056.757729690763, 6061.909071364022, 6105.297890742621], "value": 5684.050232971224, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.37725579490865, 522.4305506997374, 523.0350004242174, 531.9069162896492, 534.8261814910151, 33396.33207335858, 38400.03999900004, 38404.84785456434, 40237.79881100594, 40657.3901391479, 33337.83317583885, 35881.2823743525, 41304.38043429343, 41761.58238417615, 41827.79086104573, 7807.6876924922935, 8159.55122468264, 9490.81018379632, 9503.14471697547, 9521.285903557226, 9257.953710231473, 9267.304952128583, 9317.534123293843, 9369.250459963187, 9390.953045234764, 7542.358899493523, 9315.72052838416, 9425.670101546448, 9431.952840235823, 9471.052894710509, 7991.520508769492, 9402.15380615748, 9404.905950940505, 9407.670731524433, 9408.529573521366, 9195.586198621078, 9206.677766278148, 9223.49270790113, 9320.487373194472, 9335.019822918564, 39129.41305880385, 39441.80279098615, 39569.60430395688, 39574.80212598918, 39712.80143599264, 3261.0, 3315.419801534722, 3716.7584107033226, 3738.2523495300775, 3858.49518687712, 29084.563731544044, 29541.966031188804, 29695.109146725634, 30073.548896766566, 30669.926552570778, 3187.1713354527756, 3188.5536024956523, 3202.679732026802, 3234.288456539581, 3292.341531693647, 451952.0, 453579.0, 455192.0, 458692.0, 459312.70343648404, 61266.0, 61540.07689884655, 61712.0, 61783.38216617776, 61801.07298390528, 64517.35482645207, 64566.0, 65134.67432662806, 65154.0, 65334.67332663306, 16134.031958082553, 16181.919090404472, 16305.755413668567, 16330.428435004718, 16340.18299085041, 12433.564825231073, 12488.938440232681, 12541.56104536355, 12561.937190314173, 12589.496420143274, 15236.0, 17678.989281850652, 18951.81048189528, 19031.238750449826, 19197.32809351666, 7243.963780181065, 7372.755987365542, 7491.138519070268, 7508.136564295176, 7558.281963213486, 850.4132148817296, 950.538988590533, 1014.4319181258519, 1063.8085144674033, 1089.4716062709579, 29701.257468563028, 30057.0, 30255.848720756254, 30380.0, 30538.0, 733.4609062339176, 770.4491288728586, 992.5533509920565, 1041.2450973044633, 1088.6189833558192, 14204.431822726976, 15055.247237638074, 15326.693466130615, 15700.136492493022, 15811.841881581036, 666385.0, 669158.0, 672715.0, 673527.0, 674861.0, 55273.72363138159, 56004.439955600734, 56190.43809561852, 56463.43536564582, 56571.0, 1198.3289357959395, 1333.5332633578364, 1354.6410201296665, 1366.938487768053, 1378.81386012889, 28735.0, 29484.0, 29509.704902950696, 29624.259393515335, 30299.93950211732, 49657.75171124266, 50034.749826250634, 50069.24896126488, 50191.24713129233, 50240.49759502358, 580537.0, 582949.0, 584393.0, 584701.0, 586881.0, 25736.0, 26049.08828191004, 26259.474810504063, 26548.734512654628, 26561.33596660099, 39924.802255932365, 45134.0, 45764.08471830478, 45935.54064459446, 46264.768676156404, 35952.82023589865, 38024.429633554966, 38247.42628860514, 38535.0, 39449.81650550488, 34606.30787384289, 34628.82685586556, 34989.77535786235, 35656.0, 35703.464448032784, 23848.0, 29411.411771764873, 30261.243468913453, 37060.0, 37364.0, 32336.0, 33246.335073297916, 33624.1593960153, 34386.0, 34471.82764086164, 23193.76806231916, 25858.87070564635, 25945.870270648527, 25952.61071083973, 26350.0], "value": 19453.6955772657, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.7889266440136, 363.08502573514727, 373.07663532756413, 373.5946498049616, 390.7538250901931, 18575.071246437674, 18964.810351896478, 19186.520336991583, 19464.929428881424, 19692.916889571068, 18747.3438429655, 18952.715709264372, 19378.93415862127, 19428.611427771473, 19505.999999999964, 4870.391201099863, 5554.416786237448, 5560.499555040045, 5807.738651760681, 5819.447152520504, 5582.385937546866, 5644.0, 5744.597878148531, 5752.769889204426, 5757.913631295534, 5639.887202255953, 5693.459121383462, 5694.914576281359, 5698.8290351289525, 5714.685622306872, 5859.79490717825, 5922.1116832475045, 5952.791652292192, 6018.8495287617725, 6036.8188954331445, 5609.27079479665, 5776.133579962997, 5811.128330750379, 5817.214676018725, 5863.413658634145, 12884.80672789909, 12937.094403391766, 13056.673583160402, 13600.0, 24367.87816060926, 1937.7577802774654, 2172.5220451500645, 2402.9639555406684, 2409.361519197414, 2742.5474796658527, 9678.661246856396, 9805.509724513746, 9835.90164098357, 9896.0, 10318.38089714612, 1756.2535922233, 1818.5999080202334, 1820.3537744100786, 1826.6620675175136, 1866.7759868815692, 111242.88757112407, 116014.41992789981, 116374.83625163724, 116599.0, 117060.82939170585, 9512.47681377517, 9909.454979976026, 10068.748281293027, 10112.241581881388, 11474.385568545882, 12062.758744825054, 12315.26108433479, 12369.443375048144, 12380.876191238152, 12556.748865022648, 6442.967785161138, 7941.444098913021, 7967.8406431871035, 8011.599420028975, 8027.799305017421, 7012.474064445187, 8198.401311744152, 8200.097989221182, 8269.503829770232, 8371.748847534462, 3780.7542509736777, 3857.8456861725776, 3871.535415750128, 3907.5506316773935, 3918.0988372674447, 1509.7056074065672, 1641.8193998660138, 1815.6368726254802, 1923.3652894544766, 1969.5962327722782, 829.6473998550592, 856.8157846063002, 878.1087196495562, 892.9330300227509, 925.2366797392178, 19410.902945485464, 20819.479513011993, 20834.895825520776, 20894.791052089284, 21106.577868442546, 745.392505108335, 769.5575044349522, 912.5710915869531, 916.5417291354323, 963.2534785541261, 10280.897191028143, 10778.892211077946, 11024.724381890517, 11060.949209825054, 11071.833922491169, 576325.0, 581868.0, 582440.0, 590145.0, 593448.0, 73773.631131844, 130702.0, 136652.31673841568, 142139.0, 142165.0, 423.6039303251408, 452.58588391621953, 452.92300308947557, 998.1116806042628, 1025.5333823110616, 9258.95370523143, 10613.9469302653, 10827.621033263798, 11177.72055698614, 11339.206255562032, 56922.43077569171, 57402.13896791468, 57457.425425745205, 57967.4203257962, 58286.4171358281, 75574.0, 76858.6157069211, 78239.0, 80915.0, 82520.58739706263, 6667.733290668319, 8097.2307630774985, 8144.429889907887, 8185.181481852005, 8247.670093196204, 10946.452677366258, 12570.1200915935, 12675.492980280686, 12873.22760634365, 13006.674833129247, 10311.381317120997, 11002.284851484628, 11024.779504410028, 11045.83431248548, 11052.557897684324, 5030.899382012266, 7370.705171793283, 10731.946340268249, 10734.79936613008, 10872.945635271773, 8933.0, 10896.564137434414, 11015.0, 11300.378479183724, 11408.144389171011, 7489.550626962399, 8030.598470076589, 8126.471779334324, 8243.0, 8293.21214484623, 10869.076128529163, 11048.60351853686, 11092.778144437227, 11178.329300242012, 11198.328100314007], "value": 8847.181569151437, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1286.819845221669, 1447.7104579084187, 1606.3012589523557, 1733.9133043347829, 1796.6047469556702, 7528.0, 7766.8446631067345, 7965.641546130423, 8468.407211495205, 8519.829603407929, 7968.920310796891, 8242.25819676229, 8248.876266856001, 8365.414420990533, 8367.91632083679, 2327.569399661064, 2351.929442116739, 2355.105060077168, 2356.3048900574286, 2360.9055637774554, 2340.789328960393, 2372.3120295114395, 2374.3707917401903, 2376.9049238030457, 2385.49904520051, 2325.244295603927, 2327.406511339606, 2349.4126468382906, 2350.8354415190943, 2352.4589344450787, 2068.989655051715, 2151.7633060363346, 2300.9884950575224, 2312.5028118954506, 2319.652052192176, 1826.488583196707, 1826.7077267637246, 1834.642244762272, 1898.810118988104, 2319.9188028419094, 8356.665733370657, 8387.748367548984, 8397.0, 8407.83184336316, 8415.200555947236, 5081.695098294114, 5161.019406312788, 5221.321228240343, 5224.39919409269, 5236.764345604457, 2822.9011984580648, 3291.6050073991273, 3585.1216451969435, 3614.710823134143, 3658.0, 1050.4380156616173, 1070.318105716341, 1085.1264731890824, 1104.4422566603894, 1107.5625128074366, 8432.198941100585, 10794.676159715142, 10891.237613367068, 10950.288231264863, 10964.28732132409, 3801.676857467091, 4514.909701806011, 4702.764861756967, 4728.739919304402, 4738.549837765407, 9389.342746007851, 9469.857952130724, 9486.667966621135, 9494.0, 9531.761705957268, 5222.477752224747, 5228.085085110167, 5229.08491014071, 5232.738363081907, 5289.338832645919, 3858.691304695674, 4241.681873859411, 4248.9787551062045, 4774.498677638868, 4799.3760811094335, 3795.525559305087, 3807.9809600951817, 3907.9804600976813, 3928.941065884014, 3933.5083114610675, 1265.5444040145724, 1269.0482138396203, 1274.0762946863636, 1277.157076329618, 1286.6075846866795, 883.3772190605607, 892.6518657723493, 893.7274131389988, 899.0, 906.2930913887178, 4754.310624959361, 5512.751926163332, 5721.5136713379825, 5758.452946970031, 5852.941470585325, 774.4191856107919, 911.977200569991, 930.237205491504, 938.737153596994, 950.4772375193616, 3632.328019316382, 3774.3960966245268, 3814.504114465102, 3889.0666240102737, 3912.217556488714, 9718.465484398426, 10189.93005734402, 10207.285490015776, 10255.692329230134, 10303.845442318372, 9509.144177024102, 9917.801643967225, 10003.749906252402, 10165.847512287322, 10202.0, 7614.885776713354, 7886.842263154705, 7907.327877130509, 7908.52548847071, 7919.366450683931, 5072.163093089599, 5668.319801623831, 5727.398623144676, 5836.620619659709, 5869.767348856788, 9288.674896378594, 9315.25477961775, 9347.112024357675, 9426.575804088696, 9439.716808495756, 9513.23894088485, 9532.714018579454, 9549.761255968657, 9638.132568068908, 9662.516874156405, 6947.40947019509, 7112.822179445555, 7145.785626431215, 7216.3505284524645, 7260.547890421831, 7939.126696063313, 7978.8005299865645, 7985.041794984639, 8007.479513831815, 8011.038675358761, 7066.116735408074, 7100.715971361088, 7158.713651453883, 7191.892121618284, 7333.193348731742, 6927.064846245692, 7048.718051277891, 7050.294970502909, 7160.820979475347, 7186.568805871664, 6582.341765823379, 6586.736530538725, 6620.536562440583, 6656.933430665825, 6668.233153187445, 4632.305154226893, 4634.165850147006, 4669.019505903798, 4750.097481478508, 4791.520847915181, 4654.883627909329, 4672.626189904731, 4698.7415692137265, 4858.076965376567, 4928.778204980857], "value": 4393.346285733216, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [266.9879855406507, 291.3764543876107, 304.1499010266306, 307.947648899687, 310.2383648143807, 4087.0395457067566, 4230.471191101108, 4351.869443916684, 4988.002399520093, 5183.844484665466, 4350.738955662662, 4461.122575938518, 4685.789139488723, 4882.389701287339, 5130.204818253167, 4035.3341698619693, 4054.560630976005, 4055.2295063937904, 4059.675225981929, 4086.9386959195485, 3709.851605935773, 3775.490308808317, 3944.4872166618297, 4084.775337356444, 4105.692073094515, 3582.3372676054883, 3650.8174591270463, 3734.3838266685966, 3972.0864201233735, 3982.9402558961638, 3350.664933506654, 3954.5056867891512, 3955.2287303975736, 3969.9007524811823, 3982.123932734794, 3005.0534081764176, 3383.678550537695, 3440.587129544446, 3442.397580423441, 3464.670856268676, 5105.897882042375, 5152.2786809846275, 5154.53609175176, 5160.380754309507, 5183.092958732244, 3874.9612503874882, 4148.56440073791, 4208.494980602317, 4584.312353147021, 4589.518100599423, 4803.447603525605, 5295.364556253275, 5340.412554618989, 5574.832755017356, 5616.382197958222, 3238.206639373345, 3311.172206948263, 3826.617338266179, 3846.903827404309, 3851.5955824638568, 4848.393950756155, 5157.819476318311, 5653.10620941985, 5957.165996760457, 5964.552658550582, 3043.6020539404863, 3091.0, 3904.7461914975434, 3916.1971795781797, 3917.2361389529337, 3573.249617580337, 3780.4518344839844, 3882.5340959085092, 3885.3006458837117, 3897.0, 4364.738115713068, 4593.379893714306, 4594.724316541018, 4612.723236605814, 4634.768261586907, 4652.790624421841, 4683.555062269079, 5666.546676265889, 5747.396523364986, 5756.366799652035, 1221.1635030004297, 1254.7866862633375, 1273.630647112327, 1281.8399348589542, 1289.7291568770477, 748.6144635512716, 843.2158092973593, 857.7947983083777, 868.2316150207113, 868.5700578213824, 4133.731307465006, 4475.843345482893, 5056.443791182967, 5056.9494305057215, 5067.923981140286, 4634.536546345406, 5326.414094449608, 5493.873755880035, 5504.284443022461, 5521.503064724114, 3864.840547835638, 4218.409422680827, 4374.496932852762, 4612.7001744886475, 5090.414602320707, 2543.465872166829, 2710.2817753295394, 2857.657081150275, 2863.727945845141, 2903.5209190483333, 4355.825766969286, 4765.9523404766205, 4857.231378114511, 5405.78376864921, 5459.537716539348, 4528.705634133837, 5300.072487314705, 5555.50000499957, 5585.748641311069, 5634.211210430543, 5347.6470353045315, 5599.524040456526, 5655.802046928337, 5661.631993920464, 5696.829095126987, 5233.7962268679175, 5251.505534870906, 5441.102218134122, 5452.345718513804, 5455.1544510600925, 4434.711743736647, 4520.9095818084115, 4790.281457781361, 5142.871428214325, 5381.27352807366, 4821.686590371614, 5035.874103147451, 5260.0, 5574.648337595953, 5614.9719251403485, 3305.8842940496966, 3604.9365437195565, 3679.5952445231537, 3706.7961262130843, 3719.7768133910968, 4846.321514987834, 5705.145827606128, 5796.739146738491, 5798.391168927202, 5803.9419605803405, 4976.5023497649945, 5659.745311460912, 5662.235598194191, 5662.716864156858, 5675.354147297237, 5047.4700156484505, 5400.513953744182, 5681.857953551194, 5692.544596432358, 5724.563463315963, 4947.9466021602075, 5063.038022775808, 5193.7403129844115, 5495.6153069284765, 5555.2500412445415, 4546.477155127202, 4634.582887540138, 4748.240281554935, 5643.435656434323, 5773.813984860943, 5142.100132476877, 5216.0, 5239.947600523946, 5456.017916775018, 5523.481042713286], "value": 3915.960541196814, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.6704931059127, 715.6743681624864, 752.2327226229246, 785.4501848705905, 786.9331106855917, 13774.311284435764, 13953.372098255577, 14021.158730476178, 14590.0, 14623.341949612266, 12133.939330303358, 13167.275799831006, 13253.734925301489, 14157.07978981368, 14776.113433194016, 636.7421194416269, 794.566961006253, 815.4577206157927, 815.8572249856281, 816.102287483769, 662.3145044878552, 777.782220978127, 791.4341246009102, 804.9597520123999, 808.2200676347323, 856.1780690537092, 859.2610355094614, 862.0431321233426, 862.2584577263548, 863.9740807775777, 844.6536919862857, 851.0425771007616, 854.5513605357175, 857.0443954990203, 857.3227150551057, 633.9714712837933, 634.1407392982488, 634.7334119669749, 636.2682914648145, 637.3499030988412, 14102.576922692335, 14108.717825643429, 14269.571912842528, 14303.642408939755, 14335.498257560936, 11938.86580774825, 12387.690307742288, 13136.737265254642, 13382.799258011137, 13518.662033449144, 10608.0, 10934.781304373948, 11194.0, 11211.0, 11468.598599049077, 3153.731932785716, 3558.679718825318, 3573.8391772370046, 3606.9098272543133, 3699.5190625218547, 88696.0, 91573.99999999933, 92454.5377273116, 92987.07012929852, 93339.53330233373, 10152.23858210637, 10250.538725757358, 10260.538275777608, 10263.538140783683, 10271.486425678686, 19410.90294548518, 19585.314513991943, 19641.607167856564, 19723.91518466498, 19766.40700778979, 7280.890786638205, 7330.450216233804, 7381.735921418585, 7496.437767167485, 7636.465447418627, 4161.83352665896, 4208.789560521962, 4227.450431443893, 4255.765932873722, 4292.549282325311, 3367.2928684976428, 3467.791932484059, 3486.7210623150086, 3576.8390422431053, 3769.0200547857753, 668.1881513960465, 678.220046946016, 681.4548361310931, 687.9621620810904, 693.6878404717897, 621.4313902778906, 645.7772068636312, 655.7278729327371, 666.3702800853147, 670.6948338505968, 7523.210062943422, 8443.915560844436, 8918.598663060178, 9119.224865886474, 9132.863007054899, 583.2767368463075, 619.3218425823716, 630.2625927664643, 649.4927392082697, 649.8083065495596, 4315.848866099816, 4331.891702707458, 4597.402337696145, 4624.121416930772, 4895.902081958341, 64066.0, 87995.56002220076, 100768.0, 110755.0, 114412.0, 45786.77106614446, 67759.3224067753, 87003.82490437789, 91710.08289917016, 101100.0, 88809.0, 89151.66272505917, 90795.54602227079, 92427.53786231026, 93413.0, 7531.774046778605, 9468.484173405683, 9756.633631713634, 9761.260496650251, 9786.902130978599, 36303.0, 50342.74143146451, 56496.43503564912, 57187.0, 58004.41995580159, 48496.787580310775, 49107.017859643325, 51369.0, 69005.96491052538, 70035.64982175057, 12009.339486327986, 12096.395180240777, 12451.501939922302, 12559.560415385418, 12598.55905043319, 13836.446542138605, 13850.861491384958, 13924.721505569629, 14002.36989335462, 14300.284985750879, 12620.0, 12986.935065324613, 13303.66740831487, 13366.665833354244, 13664.111832730841, 12424.0681948856, 12994.640408824629, 13205.339733013503, 13492.662683432993, 13863.237521935987, 12524.185927914656, 12752.4261408235, 12968.372856893795, 13085.738285234433, 13160.934195328962, 9107.863382049407, 10607.840882386925, 10665.41340226295, 10937.617183398543, 11014.284071535325, 10758.8386174209, 10761.838572421264, 10783.406912619881, 10788.622398216025, 10867.0], "value": 7134.5607979305305, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1568.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 117.0, 119.0, 120.0, 119.0, 117.0, 119.0, 118.0, 117.0, 118.0], "value": 117.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 103.0, 148.0, 144.0, 102.0, 159.0, 145.0, 149.0, 144.0, 115.0], "value": 135.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 198.0, 200.0, 237.0, 278.0, 199.0, 195.0, 203.0, 202.0, 209.0], "value": 211.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 59.0, 88.0, 60.0, 59.0, 59.0, 71.0, 104.0, 76.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 73.0, 90.0, 71.0, 71.0, 71.0, 90.0, 88.0, 91.0, 127.0], "value": 85.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 104.0, 122.0, 92.0, 88.0, 93.0, 86.0, 92.0, 84.0], "value": 94.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 98.0, 98.0, 96.0, 99.0, 98.0, 96.0, 113.0, 97.0, 97.0], "value": 99.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 144.0, 88.0, 91.0, 89.0, 87.0, 92.0, 88.0, 90.0, 90.0], "value": 98.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 56.0, 60.0, 59.0, 61.0, 61.0, 61.0, 59.0, 60.0], "value": 59.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 75.0, 74.0, 105.0, 113.0, 110.0, 69.0, 101.0, 61.0, 74.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 105.0, 106.0, 100.0, 85.0, 82.0, 87.0, 84.0, 86.0, 84.0], "value": 91.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 173.0, 154.0, 164.0, 150.0, 144.0, 142.0, 149.0, 206.0, 182.0], "value": 161.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 176.0, 169.0, 169.0, 177.0, 166.0, 166.0, 236.0, 228.0, 167.0], "value": 182.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 70.0, 67.0, 74.0, 84.0, 89.0, 92.0, 73.0, 69.0], "value": 75.6, "unit": "score"}]}]} 09:53:59 INFO - Return code: 0 09:53:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:53:59 ERROR - # TBPL SUCCESS # 09:53:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:53:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:53:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:53:59 INFO - 2016-12-18 09:53:59.363 screenresolution[2736:42886] starting screenresolution argv=screenresolution get 09:53:59 INFO - 2016-12-18 09:53:59.365 screenresolution[2736:42886] Display 0: 1600x1200x32@60 09:53:59 INFO - 2016-12-18 09:53:59.371 screenresolution[2737:42889] starting screenresolution argv=screenresolution list 09:53:59 INFO - Available Modes on Display 0 09:53:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:53:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:53:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:53:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:53:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:53:59 INFO - Intel Iris: 09:53:59 INFO - Chipset Model: Intel Iris 09:53:59 INFO - Type: GPU 09:53:59 INFO - Bus: Built-In 09:53:59 INFO - VRAM (Dynamic, Max): 1536 MB 09:53:59 INFO - Vendor: Intel (0x8086) 09:53:59 INFO - Device ID: 0x0a2e 09:53:59 INFO - Revision ID: 0x0009 09:53:59 INFO - Displays: 09:53:59 INFO - Display: 09:53:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:53:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:53:59 INFO - Display Serial Number: 1600x1200 60 09:53:59 INFO - Main Display: Yes 09:53:59 INFO - Mirror: Off 09:53:59 INFO - Online: Yes 09:53:59 INFO - Rotation: Supported 09:53:59 INFO - Return code: 0 09:53:59 INFO - Running post-action listener: _resource_record_post_action 09:53:59 INFO - [mozharness: 2016-12-18 17:53:59.613597Z] Finished run-tests step (success) 09:53:59 INFO - Running post-run listener: _resource_record_post_run 09:53:59 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 135168; Write bytes: 664476672; Read time: 3; Write time: 2097 09:53:59 INFO - TinderboxPrint: CPU usage
54.3% 09:53:59 INFO - TinderboxPrint: I/O read bytes / time
135,168 / 3 09:53:59 INFO - TinderboxPrint: I/O write bytes / time
664,476,672 / 2,097 09:53:59 INFO - TinderboxPrint: CPU idle
2,216.5 (45.7%) 09:53:59 INFO - TinderboxPrint: CPU system
979.4 (20.2%) 09:53:59 INFO - TinderboxPrint: CPU user
1,649.8 (34.0%) 09:53:59 INFO - TinderboxPrint: Swap in / out
348,020,736 / 0 09:53:59 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 336011776; Write bytes: 333473792; Read time: 26610; Write time: 649 09:53:59 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 135168; Write bytes: 313312256; Read time: 3; Write time: 1396 09:54:00 INFO - Running post-run listener: _upload_blobber_files 09:54:00 INFO - Blob upload gear active. 09:54:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:54:00 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 09:54:00 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'] 09:54:00 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 09:54:00 INFO - (blobuploader) - INFO - Open directory for files ... 09:54:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:54:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:54:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:01 INFO - (blobuploader) - INFO - Done attempting. 09:54:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:54:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:54:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:02 INFO - (blobuploader) - INFO - Done attempting. 09:54:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:54:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:54:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:05 INFO - (blobuploader) - INFO - Done attempting. 09:54:05 INFO - (blobuploader) - INFO - Iteration through files over. 09:54:05 INFO - Return code: 0 09:54:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:54:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:54:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/20dd1da882c085021e8dd71c38467510cfe334fb9c601f6f4788739973d101911128a043df5e63a5627f5f1e66b21627059f1ea55d7f00ac8aa4db92c9f1ea60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd17621f906e478fa750ba7c1b23fd9bb6af05fc89cd728c9f7a1f8fc340c70c5a68f39bb0df4aed1aa584df149892be7267c6cdbd2a9623e72fa1c96d0d2af5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/971d5657575ad073724fbecc32b02978491a7463325c3e10dcd1bcde3d23e781a51340782da588cdfef2f892f3ddabf595cded2a854c489ea4ea91285860f4eb"} 09:54:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:54:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:54:05 INFO - Contents: 09:54:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/20dd1da882c085021e8dd71c38467510cfe334fb9c601f6f4788739973d101911128a043df5e63a5627f5f1e66b21627059f1ea55d7f00ac8aa4db92c9f1ea60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd17621f906e478fa750ba7c1b23fd9bb6af05fc89cd728c9f7a1f8fc340c70c5a68f39bb0df4aed1aa584df149892be7267c6cdbd2a9623e72fa1c96d0d2af5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/971d5657575ad073724fbecc32b02978491a7463325c3e10dcd1bcde3d23e781a51340782da588cdfef2f892f3ddabf595cded2a854c489ea4ea91285860f4eb"} 09:54:05 INFO - Running post-run listener: copy_logs_to_upload_dir 09:54:05 INFO - Copying logs to upload dir... 09:54:05 INFO - mkdir: /builds/slave/test/build/upload/logs 09:54:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.130673 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-18 09:54:05.198474) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-18 09:54:05.202161) ========= 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.UWMb3M8wb4/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.FYusncHrbF/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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/20dd1da882c085021e8dd71c38467510cfe334fb9c601f6f4788739973d101911128a043df5e63a5627f5f1e66b21627059f1ea55d7f00ac8aa4db92c9f1ea60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd17621f906e478fa750ba7c1b23fd9bb6af05fc89cd728c9f7a1f8fc340c70c5a68f39bb0df4aed1aa584df149892be7267c6cdbd2a9623e72fa1c96d0d2af5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/971d5657575ad073724fbecc32b02978491a7463325c3e10dcd1bcde3d23e781a51340782da588cdfef2f892f3ddabf595cded2a854c489ea4ea91285860f4eb"} build_url:https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011551 build_url: 'https://queue.taskcluster.net/v1/task/TpOpIBitS4K5QGAH6b_R7w/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/20dd1da882c085021e8dd71c38467510cfe334fb9c601f6f4788739973d101911128a043df5e63a5627f5f1e66b21627059f1ea55d7f00ac8aa4db92c9f1ea60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd17621f906e478fa750ba7c1b23fd9bb6af05fc89cd728c9f7a1f8fc340c70c5a68f39bb0df4aed1aa584df149892be7267c6cdbd2a9623e72fa1c96d0d2af5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/971d5657575ad073724fbecc32b02978491a7463325c3e10dcd1bcde3d23e781a51340782da588cdfef2f892f3ddabf595cded2a854c489ea4ea91285860f4eb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-18 09:54:05.237283) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 09:54:05.237603) ========= 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.UWMb3M8wb4/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.FYusncHrbF/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.005260 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 09:54:05.310278) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-18 09:54:05.310618) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-18 09:54:05.311277) ========= ========= Total master_lag: 0.22 =========