builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0333 starttime: 1480351334.71 results: success (0) buildid: 20161128062457 builduid: e2db70e051d6405ab6113b11de9ffdfd revision: bc4ed3faa33e589fcc1b86221d837b98af3551b7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-28 08:42:14.706840) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-28 08:42:14.707310) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-28 08:42:14.707591) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-28 08:42:14.733807) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 08:42:14.734195) ========= 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.KYaAbAsJYf/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.a0YsBKrGIi/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-28 08:42:14-- 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% 22.9M=0.001s 2016-11-28 08:42:14 (22.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126597 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 08:42:14.884712) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 08:42:14.885043) ========= 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.KYaAbAsJYf/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.a0YsBKrGIi/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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.023799 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 08:42:14.933896) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-28 08:42:14.934265) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bc4ed3faa33e589fcc1b86221d837b98af3551b7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev bc4ed3faa33e589fcc1b86221d837b98af3551b7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KYaAbAsJYf/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.a0YsBKrGIi/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-28 08:42:15,030 truncating revision to first 12 chars 2016-11-28 08:42:15,030 Setting DEBUG logging. 2016-11-28 08:42:15,030 attempt 1/10 2016-11-28 08:42:15,030 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bc4ed3faa33e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-28 08:42:16,137 unpacking tar archive at: mozilla-central-bc4ed3faa33e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.383238 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-28 08:42:16.340739) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-28 08:42:16.341109) ========= script_repo_revision: bc4ed3faa33e589fcc1b86221d837b98af3551b7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-28 08:42:16.341484) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-28 08:42:16.341749) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-28 08:42:16.361852) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-28 08:42:16.362266) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KYaAbAsJYf/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.a0YsBKrGIi/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 08:42:16 INFO - MultiFileLogger online at 20161128 08:42:16 in /builds/slave/test 08:42:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:42:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:42:16 INFO - {'append_to_log': False, 08:42:16 INFO - 'base_work_dir': '/builds/slave/test', 08:42:16 INFO - 'blob_upload_branch': 'Firefox', 08:42:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:42:16 INFO - 'branch': 'Firefox', 08:42:16 INFO - 'buildbot_json_path': 'buildprops.json', 08:42:16 INFO - 'config_files': ('talos/mac_config.py',), 08:42:16 INFO - 'default_actions': ('clobber', 08:42:16 INFO - 'read-buildbot-config', 08:42:16 INFO - 'download-and-extract', 08:42:16 INFO - 'populate-webroot', 08:42:16 INFO - 'create-virtualenv', 08:42:16 INFO - 'install', 08:42:16 INFO - 'run-tests'), 08:42:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:42:16 INFO - 'download_minidump_stackwalk': True, 08:42:16 INFO - 'download_symbols': 'ondemand', 08:42:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:42:16 INFO - 'tooltool.py': '/tools/tooltool.py', 08:42:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:42:16 INFO - '/tools/misc-python/virtualenv.py')}, 08:42:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:42:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:42:16 INFO - 'installer_path': 'installer.exe', 08:42:16 INFO - 'log_level': 'info', 08:42:16 INFO - 'log_name': 'talos', 08:42:16 INFO - 'log_to_console': True, 08:42:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:42:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:42:16 INFO - 'opt_config_files': (), 08:42:16 INFO - 'pip_index': False, 08:42:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:42:16 INFO - 'cmd': ('bash', 08:42:16 INFO - '-c', 08:42:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:42:16 INFO - 'enabled': True, 08:42:16 INFO - 'halt_on_failure': False, 08:42:16 INFO - 'name': 'check_screen_resolution'},), 08:42:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:42:16 INFO - 'cmd': ('bash', 08:42:16 INFO - '-c', 08:42:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:42:16 INFO - 'enabled': True, 08:42:16 INFO - 'halt_on_failure': False, 08:42:16 INFO - 'name': 'check_screen_resolution'},), 08:42:16 INFO - 'run_cmd_checks_enabled': True, 08:42:16 INFO - 'sps_profile': False, 08:42:16 INFO - 'sps_profile_interval': 0, 08:42:16 INFO - 'suite': 'dromaeojs-e10s', 08:42:16 INFO - 'system_bits': '32', 08:42:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:42:16 INFO - 'title': 't-yosemite-r7-0333', 08:42:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:42:16 INFO - 'use_talos_json': True, 08:42:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:42:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:42:16 INFO - 'work_dir': 'build'} 08:42:16 INFO - [mozharness: 2016-11-28 16:42:16.566753Z] Running clobber step. 08:42:16 INFO - Running pre-action listener: _resource_record_pre_action 08:42:16 INFO - Running main action method: clobber 08:42:16 INFO - rmtree: /builds/slave/test/build 08:42:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:42:18 INFO - Running post-action listener: _resource_record_post_action 08:42:18 INFO - [mozharness: 2016-11-28 16:42:18.884209Z] Finished clobber step (success) 08:42:18 INFO - [mozharness: 2016-11-28 16:42:18.884360Z] Running read-buildbot-config step. 08:42:18 INFO - Running pre-action listener: _resource_record_pre_action 08:42:18 INFO - Running main action method: read_buildbot_config 08:42:18 INFO - Using buildbot properties: 08:42:18 INFO - { 08:42:18 INFO - "project": "", 08:42:18 INFO - "product": "firefox", 08:42:18 INFO - "script_repo_revision": "production", 08:42:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:42:18 INFO - "repository": "", 08:42:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:42:18 INFO - "buildid": "20161128062457", 08:42:18 INFO - "pgo_build": "False", 08:42:18 INFO - "basedir": "/builds/slave/test", 08:42:18 INFO - "buildnumber": 0, 08:42:18 INFO - "slavename": "t-yosemite-r7-0333", 08:42:18 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 08:42:18 INFO - "platform": "yosemite_r7", 08:42:18 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:42:18 INFO - "branch": "mozilla-central", 08:42:18 INFO - "repo_path": "mozilla-central", 08:42:18 INFO - "revision": "bc4ed3faa33e589fcc1b86221d837b98af3551b7", 08:42:18 INFO - "stage_platform": "macosx64", 08:42:18 INFO - "builduid": "e2db70e051d6405ab6113b11de9ffdfd", 08:42:18 INFO - "slavebuilddir": "test" 08:42:18 INFO - } 08:42:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:42:18 INFO - Running post-action listener: _resource_record_post_action 08:42:18 INFO - [mozharness: 2016-11-28 16:42:18.887664Z] Finished read-buildbot-config step (success) 08:42:18 INFO - [mozharness: 2016-11-28 16:42:18.887800Z] Running download-and-extract step. 08:42:18 INFO - Running pre-action listener: _resource_record_pre_action 08:42:18 INFO - Running main action method: download_and_extract 08:42:18 INFO - mkdir: /builds/slave/test/build/tests 08:42:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:18 INFO - trying https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:42:18 INFO - Downloading https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:42:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 08:42:20 INFO - Downloaded 1199 bytes. 08:42:20 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:42:20 INFO - Using the following test package requirements: 08:42:20 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:42:20 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:42:20 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:42:20 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:42:20 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:42:20 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:42:20 INFO - u'jsshell-mac.zip'], 08:42:20 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:42:20 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:42:20 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:42:20 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:42:20 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:42:20 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:42:20 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:42:20 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:42:20 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:42:20 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:42:20 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:42:20 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:42:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:42:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:42:20 INFO - Fetch https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:42:20 INFO - Http code: 200 08:42:20 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 08:42:20 INFO - x-amz-cf-id: QA7Ztc7wRUgqczb7GeLJEMbpRaquE5a54DyaagTFFnHUIU7kSp1XXg== 08:42:20 INFO - x-amz-version-id: eo8vTiRvXnBvAmCbqxe6njP7qllyg5Z6 08:42:21 INFO - Expected file size: 16282687 08:42:21 INFO - Obtained file size: 16282687 08:42:28 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:42:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:42:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:42:28 INFO - Fetch https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:42:28 INFO - Http code: 200 08:42:28 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 08:42:28 INFO - x-amz-cf-id: 4RNqO_MemQ0bC99Zf8qN05IS_ASHAwTZvHAog9OL5GSHd5Sk0k_qbw== 08:42:28 INFO - x-amz-version-id: eo8vTiRvXnBvAmCbqxe6njP7qllyg5Z6 08:42:29 INFO - Expected file size: 16282687 08:42:29 INFO - Obtained file size: 16282687 08:42:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:42:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:42:35 INFO - Fetch https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:42:36 INFO - Http code: 200 08:42:36 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 08:42:36 INFO - x-amz-cf-id: sAcJ6UbK3GwlgpTHBww9ljldd1GxfqUm-sgajVkwVnaD2UvMSHPZEQ== 08:42:36 INFO - x-amz-version-id: TGiqc1bZf7x3wBEpF..NUwzRNe66c5k3 08:42:37 INFO - Expected file size: 14409702 08:42:37 INFO - Obtained file size: 14409702 08:42: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')]} 08:42:37 INFO - trying https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:42:37 INFO - Downloading https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:42:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 08:42:43 INFO - Downloaded 107570356 bytes. 08:42:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:42:43 INFO - mkdir: /builds/slave/test/properties 08:42:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:42:43 INFO - Writing to file /builds/slave/test/properties/build_url 08:42:43 INFO - Contents: 08:42:43 INFO - build_url:https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:42:43 INFO - Running post-action listener: _resource_record_post_action 08:42:43 INFO - Running post-action listener: set_extra_try_arguments 08:42:43 INFO - [mozharness: 2016-11-28 16:42:43.848028Z] Finished download-and-extract step (success) 08:42:43 INFO - [mozharness: 2016-11-28 16:42:43.848146Z] Running populate-webroot step. 08:42:43 INFO - Running pre-action listener: _resource_record_pre_action 08:42:43 INFO - Running main action method: populate_webroot 08:42:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:42:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:42:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:42:43 INFO - '--tppagecycles', 08:42:43 INFO - '7'], 08:42:43 INFO - 'tests': ['tsvgm']}}, 08:42:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:42:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:42:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:42:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:42:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:42:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:42:43 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:42:43 INFO - 'talos_options': ['--disable-e10s'], 08:42:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:42:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:42:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:42:43 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:42:43 INFO - 'talos_options': ['--disable-e10s'], 08:42:43 INFO - 'tests': ['damp', 'tps']}, 08:42:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:42:43 INFO - 'tests': ['damp', 'tps']}, 08:42:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:42:43 INFO - 'tests': ['dromaeo_dom']}, 08:42:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:42:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:42:43 INFO - 'tests': ['basic_compositor_video']}, 08:42:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:42:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:42:43 INFO - 'tests': ['a11yr', 08:42:43 INFO - 'ts_paint', 08:42:43 INFO - 'tpaint', 08:42:43 INFO - 'sessionrestore', 08:42:43 INFO - 'sessionrestore_no_auto_restore', 08:42:43 INFO - 'tabpaint']}, 08:42:43 INFO - 'other-e10s': {'tests': ['a11yr', 08:42:43 INFO - 'ts_paint', 08:42:43 INFO - 'tpaint', 08:42:43 INFO - 'sessionrestore', 08:42:43 INFO - 'sessionrestore_no_auto_restore', 08:42:43 INFO - 'tabpaint']}, 08:42:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:42:43 INFO - 'ts_paint', 08:42:43 INFO - 'tpaint', 08:42:43 INFO - 'sessionrestore', 08:42:43 INFO - 'sessionrestore_no_auto_restore', 08:42:43 INFO - 'tabpaint']}, 08:42:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:42:43 INFO - 'ts_paint', 08:42:43 INFO - 'tpaint', 08:42:43 INFO - 'sessionrestore', 08:42:43 INFO - 'sessionrestore_no_auto_restore', 08:42:43 INFO - 'tabpaint']}, 08:42:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:42:43 INFO - 'tests': ['a11yr', 08:42:43 INFO - 'ts_paint', 08:42:43 INFO - 'tpaint', 08:42:43 INFO - 'sessionrestore', 08:42:43 INFO - 'sessionrestore_no_auto_restore', 08:42:43 INFO - 'tabpaint']}, 08:42:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:42:43 INFO - 'tests': ['a11yr', 08:42:43 INFO - 'ts_paint', 08:42:43 INFO - 'tpaint', 08:42:43 INFO - 'sessionrestore', 08:42:43 INFO - 'sessionrestore_no_auto_restore', 08:42:43 INFO - 'tabpaint']}, 08:42:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:42:43 INFO - 'tests': ['tsvgx', 08:42:43 INFO - 'tsvgr_opacity', 08:42:43 INFO - 'tart', 08:42:43 INFO - 'tscrollx', 08:42:43 INFO - 'cart', 08:42:43 INFO - 'tsvg_static']}, 08:42:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:42:43 INFO - 'tsvgr_opacity', 08:42:43 INFO - 'tart', 08:42:43 INFO - 'tscrollx', 08:42:43 INFO - 'cart', 08:42:43 INFO - 'tsvg_static']}, 08:42:43 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:42:43 INFO - 'talos_options': ['--disable-e10s'], 08:42:43 INFO - 'tests': ['tp5o']}, 08:42:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:42:43 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:42:43 INFO - 'talos_options': ['--disable-e10s', 08:42:43 INFO - '--xperf_path', 08:42:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:42:43 INFO - 'tests': ['tp5n']}, 08:42:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:42:43 INFO - 'talos_options': ['--xperf_path', 08:42:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:42:43 INFO - 'tests': ['tp5n']}}, 08:42:43 INFO - 'talos.zip': {'path': '', 08:42:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:42:43 INFO - Running post-action listener: _resource_record_post_action 08:42:43 INFO - [mozharness: 2016-11-28 16:42:43.866362Z] Finished populate-webroot step (success) 08:42:43 INFO - [mozharness: 2016-11-28 16:42:43.866497Z] Running create-virtualenv step. 08:42:43 INFO - Running pre-action listener: _resource_record_pre_action 08:42:43 INFO - Running main action method: create_virtualenv 08:42:43 INFO - Creating virtualenv /builds/slave/test/build/venv 08:42:43 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 08:42:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:42:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:42:43 INFO - Using real prefix '/tools/python27' 08:42:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:42:44 INFO - Installing distribute.............................................................................................................................................................................................done. 08:42:47 INFO - Installing pip.................done. 08:42:47 INFO - Return code: 0 08:42:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:42:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:42:47 INFO - Reading from file tmpfile_stdout 08:42:47 INFO - Output received: 08:42:47 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) 08:42:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:42: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')]} 08:42:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42: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')]} 08:42:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1017c73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10173d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67ad61070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015ddd30>, '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 0x101444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d5b20>, '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.KYaAbAsJYf/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.a0YsBKrGIi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 08:42: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', 'pip>=1.5'] in /builds/slave/test/build 08:42: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 pip>=1.5 08:42:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KYaAbAsJYf/Render', 08:42:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:47 INFO - 'HOME': '/Users/cltbld', 08:42:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:47 INFO - 'LOGNAME': 'cltbld', 08:42:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:47 INFO - 'MOZ_NO_REMOTE': '1', 08:42:47 INFO - 'NO_EM_RESTART': '1', 08:42:47 INFO - 'PAGER': '/bin/cat', 08:42:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:47 INFO - 'PWD': '/builds/slave/test', 08:42:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:47 INFO - 'SHELL': '/bin/bash', 08:42:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a0YsBKrGIi/Listeners', 08:42:47 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 08:42:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:47 INFO - 'USER': 'cltbld', 08:42:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:47 INFO - 'XPC_FLAGS': '0x0', 08:42:47 INFO - 'XPC_SERVICE_NAME': '0', 08:42:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:48 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 08:42:48 INFO - Cleaning up... 08:42:48 INFO - Return code: 0 08:42:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:42:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:48 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 0x1017c73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10173d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67ad61070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015ddd30>, '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 0x101444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d5b20>, '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.KYaAbAsJYf/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.a0YsBKrGIi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 08:42:48 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 08:42:48 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 08:42:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KYaAbAsJYf/Render', 08:42:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:48 INFO - 'HOME': '/Users/cltbld', 08:42:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:48 INFO - 'LOGNAME': 'cltbld', 08:42:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:48 INFO - 'MOZ_NO_REMOTE': '1', 08:42:48 INFO - 'NO_EM_RESTART': '1', 08:42:48 INFO - 'PAGER': '/bin/cat', 08:42:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:48 INFO - 'PWD': '/builds/slave/test', 08:42:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:48 INFO - 'SHELL': '/bin/bash', 08:42:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a0YsBKrGIi/Listeners', 08:42:48 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 08:42:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:48 INFO - 'USER': 'cltbld', 08:42:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:48 INFO - 'XPC_FLAGS': '0x0', 08:42:48 INFO - 'XPC_SERVICE_NAME': '0', 08:42:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:48 INFO - Downloading/unpacking psutil>=3.1.1 08:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:42:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:42:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:42:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:42:51 INFO - Installing collected packages: psutil 08:42:51 INFO - Running setup.py install for psutil 08:42:51 INFO - building 'psutil._psutil_osx' extension 08:42:51 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 08:42:52 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 08:42:52 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 08:42:52 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 08:42:52 INFO - building 'psutil._psutil_posix' extension 08:42:52 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 08:42:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:42:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:42:52 INFO - ^ 08:42:52 INFO - 1 warning generated. 08:42:52 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 08:42:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:42:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:42:52 INFO - Successfully installed psutil 08:42:52 INFO - Cleaning up... 08:42:53 INFO - Return code: 0 08:42:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:42: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')]} 08:42:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42: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')]} 08:42:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1017c73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10173d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67ad61070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015ddd30>, '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 0x101444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d5b20>, '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.KYaAbAsJYf/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.a0YsBKrGIi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 08:42:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:42:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:42:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KYaAbAsJYf/Render', 08:42:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:53 INFO - 'HOME': '/Users/cltbld', 08:42:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:53 INFO - 'LOGNAME': 'cltbld', 08:42:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:53 INFO - 'MOZ_NO_REMOTE': '1', 08:42:53 INFO - 'NO_EM_RESTART': '1', 08:42:53 INFO - 'PAGER': '/bin/cat', 08:42:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:53 INFO - 'PWD': '/builds/slave/test', 08:42:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:53 INFO - 'SHELL': '/bin/bash', 08:42:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a0YsBKrGIi/Listeners', 08:42:53 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 08:42:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:53 INFO - 'USER': 'cltbld', 08:42:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:53 INFO - 'XPC_FLAGS': '0x0', 08:42:53 INFO - 'XPC_SERVICE_NAME': '0', 08:42:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:42:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:42:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:42:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:42:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:42:55 INFO - Installing collected packages: mozsystemmonitor 08:42:55 INFO - Running setup.py install for mozsystemmonitor 08:42:55 INFO - Successfully installed mozsystemmonitor 08:42:55 INFO - Cleaning up... 08:42:56 INFO - Return code: 0 08:42:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:42:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:56 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 0x1017c73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10173d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67ad61070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015ddd30>, '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 0x101444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d5b20>, '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.KYaAbAsJYf/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.a0YsBKrGIi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 08:42:56 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 08:42:56 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 08:42:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KYaAbAsJYf/Render', 08:42:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:56 INFO - 'HOME': '/Users/cltbld', 08:42:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:56 INFO - 'LOGNAME': 'cltbld', 08:42:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:56 INFO - 'MOZ_NO_REMOTE': '1', 08:42:56 INFO - 'NO_EM_RESTART': '1', 08:42:56 INFO - 'PAGER': '/bin/cat', 08:42:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:56 INFO - 'PWD': '/builds/slave/test', 08:42:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:56 INFO - 'SHELL': '/bin/bash', 08:42:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a0YsBKrGIi/Listeners', 08:42:56 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 08:42:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:56 INFO - 'USER': 'cltbld', 08:42:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:56 INFO - 'XPC_FLAGS': '0x0', 08:42:56 INFO - 'XPC_SERVICE_NAME': '0', 08:42:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:56 INFO - Downloading/unpacking jsonschema==2.5.1 08:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:42:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:42:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:42:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:42:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:42:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:59 INFO - Downloading functools32-3.2.3-2.tar.gz 08:42:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:42:59 INFO - warning: no files found matching '*.txt' 08:42:59 INFO - no previously-included directories found matching 'build' 08:42:59 INFO - no previously-included directories found matching 'dist' 08:42:59 INFO - no previously-included directories found matching '.git*' 08:42:59 INFO - Installing collected packages: jsonschema, functools32 08:42:59 INFO - Running setup.py install for functools32 08:42:59 INFO - warning: no files found matching '*.txt' 08:42:59 INFO - no previously-included directories found matching 'build' 08:42:59 INFO - no previously-included directories found matching 'dist' 08:42:59 INFO - no previously-included directories found matching '.git*' 08:42:59 INFO - Successfully installed jsonschema functools32 08:42:59 INFO - Cleaning up... 08:42:59 INFO - Return code: 0 08:42:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:42:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:59 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 0x1017c73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10173d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67ad61070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015ddd30>, '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 0x101444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d5b20>, '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.KYaAbAsJYf/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.a0YsBKrGIi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 08:42:59 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 08:42:59 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 08:42:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KYaAbAsJYf/Render', 08:42:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:59 INFO - 'HOME': '/Users/cltbld', 08:42:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:59 INFO - 'LOGNAME': 'cltbld', 08:42:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:59 INFO - 'MOZ_NO_REMOTE': '1', 08:42:59 INFO - 'NO_EM_RESTART': '1', 08:42:59 INFO - 'PAGER': '/bin/cat', 08:42:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:59 INFO - 'PWD': '/builds/slave/test', 08:42:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:59 INFO - 'SHELL': '/bin/bash', 08:42:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a0YsBKrGIi/Listeners', 08:42:59 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 08:42:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:59 INFO - 'USER': 'cltbld', 08:42:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:59 INFO - 'XPC_FLAGS': '0x0', 08:42:59 INFO - 'XPC_SERVICE_NAME': '0', 08:42:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:43:00 INFO - Cleaning up... 08:43:00 INFO - Return code: 0 08:43:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:43:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1017c73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10173d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67ad61070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015ddd30>, '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 0x101444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d5b20>, '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.KYaAbAsJYf/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.a0YsBKrGIi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 08:43:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:43:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:43:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KYaAbAsJYf/Render', 08:43:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:00 INFO - 'HOME': '/Users/cltbld', 08:43:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:00 INFO - 'LOGNAME': 'cltbld', 08:43:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:00 INFO - 'MOZ_NO_REMOTE': '1', 08:43:00 INFO - 'NO_EM_RESTART': '1', 08:43:00 INFO - 'PAGER': '/bin/cat', 08:43:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:00 INFO - 'PWD': '/builds/slave/test', 08:43:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:00 INFO - 'SHELL': '/bin/bash', 08:43:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a0YsBKrGIi/Listeners', 08:43:00 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 08:43:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:00 INFO - 'USER': 'cltbld', 08:43:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:00 INFO - 'XPC_FLAGS': '0x0', 08:43:00 INFO - 'XPC_SERVICE_NAME': '0', 08:43:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:00 INFO - Downloading/unpacking blobuploader==1.2.4 08:43:00 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 08:43:00 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 08:43:00 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 08:43:00 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 08:43:00 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 08:43:00 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 08:43:02 INFO - Downloading blobuploader-1.2.4.tar.gz 08:43:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:43:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:43:02 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 08:43:02 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 08:43:02 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 08:43:02 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 08:43:02 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 08:43:02 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 08:43:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:43:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:43:03 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 08:43:03 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 08:43:03 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 08:43:03 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 08:43:03 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 08:43:03 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 08:43:04 INFO - Downloading docopt-0.6.1.tar.gz 08:43:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:43:04 INFO - Installing collected packages: blobuploader, requests, docopt 08:43:04 INFO - Running setup.py install for blobuploader 08:43:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:43:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:43:04 INFO - Running setup.py install for requests 08:43:04 INFO - Running setup.py install for docopt 08:43:04 INFO - Successfully installed blobuploader requests docopt 08:43:04 INFO - Cleaning up... 08:43:05 INFO - Return code: 0 08:43:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:43:05 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')]} 08:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:05 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')]} 08:43:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:05 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 0x1017c73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10173d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67ad61070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015ddd30>, '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 0x101444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d5b20>, '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.KYaAbAsJYf/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.a0YsBKrGIi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 08:43:05 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 08:43:05 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 08:43:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KYaAbAsJYf/Render', 08:43:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:05 INFO - 'HOME': '/Users/cltbld', 08:43:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:05 INFO - 'LOGNAME': 'cltbld', 08:43:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:05 INFO - 'MOZ_NO_REMOTE': '1', 08:43:05 INFO - 'NO_EM_RESTART': '1', 08:43:05 INFO - 'PAGER': '/bin/cat', 08:43:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:05 INFO - 'PWD': '/builds/slave/test', 08:43:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:05 INFO - 'SHELL': '/bin/bash', 08:43:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a0YsBKrGIi/Listeners', 08:43:05 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 08:43:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:05 INFO - 'USER': 'cltbld', 08:43:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:05 INFO - 'XPC_FLAGS': '0x0', 08:43:05 INFO - 'XPC_SERVICE_NAME': '0', 08:43:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:43:05 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-BTCQuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:43:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:43:05 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-uA5Med-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:43:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:43:05 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-r0juOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:43:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:43:05 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-0f2YsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:43:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:43:05 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-mIakLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:43:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:43:05 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-bJVoSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:43:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:43:05 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-vx3tPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:43:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:43:06 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-EQzMOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:43:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:43:06 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-_Qfvo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:43:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:43:06 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-gVr1So-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:43:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:43:06 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-NsDhUx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:43:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:43:06 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-1kCJDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:43:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:43:06 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-ruggN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:43:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:43:06 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-cvrXI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:43:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:06 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-swc_Gp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:43:06 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-2AJ9Y3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:43:07 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-IxkZ_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:43:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:43:07 INFO - Running setup.py install for manifestparser 08:43:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:43:07 INFO - Running setup.py install for mozcrash 08:43:07 INFO - Running setup.py install for mozdebug 08:43:07 INFO - Running setup.py install for mozdevice 08:43:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:43:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:43:07 INFO - Running setup.py install for mozfile 08:43:07 INFO - Running setup.py install for mozhttpd 08:43:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:43:07 INFO - Running setup.py install for mozinfo 08:43:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:43:08 INFO - Running setup.py install for mozInstall 08:43:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:43:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:43:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:43:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:43:08 INFO - Running setup.py install for mozleak 08:43:08 INFO - Running setup.py install for mozlog 08:43:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:43:08 INFO - Running setup.py install for moznetwork 08:43:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:43:08 INFO - Running setup.py install for mozprocess 08:43:08 INFO - Running setup.py install for mozprofile 08:43:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:43:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:43:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:43:09 INFO - Running setup.py install for mozrunner 08:43:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:43:09 INFO - Running setup.py install for mozscreenshot 08:43:09 INFO - Running setup.py install for moztest 08:43:09 INFO - Running setup.py install for mozversion 08:43:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:43:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:43:09 INFO - Cleaning up... 08:43:09 INFO - Return code: 0 08:43:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:09 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 0x1017c73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10173d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67ad61070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015ddd30>, '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 0x101444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d5b20>, '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.KYaAbAsJYf/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.a0YsBKrGIi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 08:43:09 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 08:43:09 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 08:43:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KYaAbAsJYf/Render', 08:43:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:09 INFO - 'HOME': '/Users/cltbld', 08:43:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:09 INFO - 'LOGNAME': 'cltbld', 08:43:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:09 INFO - 'MOZ_NO_REMOTE': '1', 08:43:09 INFO - 'NO_EM_RESTART': '1', 08:43:09 INFO - 'PAGER': '/bin/cat', 08:43:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:09 INFO - 'PWD': '/builds/slave/test', 08:43:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:09 INFO - 'SHELL': '/bin/bash', 08:43:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a0YsBKrGIi/Listeners', 08:43:09 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 08:43:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:09 INFO - 'USER': 'cltbld', 08:43:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:09 INFO - 'XPC_FLAGS': '0x0', 08:43:09 INFO - 'XPC_SERVICE_NAME': '0', 08:43:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:43:09 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-pCXRBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:43:10 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)) 08:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:43:10 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-tJbo7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:43:10 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)) 08:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:43:10 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-N8NMXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:43:10 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)) 08:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:43:10 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-QWTshz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:43:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:43:10 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-MT05nV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:43:10 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)) 08:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:43:10 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-mJwFMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:43:10 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)) 08:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:43:10 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-gbItmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:43:10 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)) 08:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:43:10 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-Vbj7nc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:43:10 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)) 08:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:43:10 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-uTx6RF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:43:10 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)) 08:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:43:10 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-B77NCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:43:11 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)) 08:43:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:43:11 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-wCEJoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:43:11 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)) 08:43:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:43:11 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-D5vJ2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:43:11 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)) 08:43:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:43:11 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-Roxu5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:43:11 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)) 08:43:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:43:11 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-RTZDuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:43:11 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)) 08:43:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:11 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-BajtKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:11 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)) 08:43:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:43:11 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-8bodfS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:43:11 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)) 08:43:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:43:11 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-RCEqjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:43:11 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)) 08:43:11 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)) 08:43:11 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)) 08:43:11 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)) 08:43:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:43:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:43:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:43:11 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 08:43:11 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 08:43:11 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 08:43:11 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 08:43:11 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 08:43:11 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 08:43:14 INFO - Downloading blessings-1.6.tar.gz 08:43:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:43:14 INFO - Installing collected packages: blessings 08:43:14 INFO - Running setup.py install for blessings 08:43:14 INFO - Successfully installed blessings 08:43:14 INFO - Cleaning up... 08:43:15 INFO - Return code: 0 08:43:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:43:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:43:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:43:15 INFO - Reading from file tmpfile_stdout 08:43:15 INFO - Current package versions: 08:43:15 INFO - blessings == 1.6 08:43:15 INFO - blobuploader == 1.2.4 08:43:15 INFO - docopt == 0.6.1 08:43:15 INFO - functools32 == 3.2.3-2 08:43:15 INFO - jsonschema == 2.5.1 08:43:15 INFO - manifestparser == 1.1 08:43:15 INFO - mozInstall == 1.12 08:43:15 INFO - mozcrash == 1.0 08:43:15 INFO - mozdebug == 0.1 08:43:15 INFO - mozdevice == 0.49 08:43:15 INFO - mozfile == 1.2 08:43:15 INFO - mozhttpd == 0.7 08:43:15 INFO - mozinfo == 0.9 08:43:15 INFO - mozleak == 0.1 08:43:15 INFO - mozlog == 3.4 08:43:15 INFO - moznetwork == 0.27 08:43:15 INFO - mozprocess == 0.23 08:43:15 INFO - mozprofile == 0.28 08:43:15 INFO - mozrunner == 6.13 08:43:15 INFO - mozscreenshot == 0.1 08:43:15 INFO - mozsystemmonitor == 0.3 08:43:15 INFO - moztest == 0.7 08:43:15 INFO - mozversion == 1.4 08:43:15 INFO - psutil == 3.1.1 08:43:15 INFO - requests == 1.2.3 08:43:15 INFO - wsgiref == 0.1.2 08:43:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:43:15 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')]} 08:43:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:15 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')]} 08:43:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:15 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 0x1017c73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10173d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67ad61070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015ddd30>, '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 0x101444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d5b20>, '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.KYaAbAsJYf/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.a0YsBKrGIi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 08:43:15 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 08:43:15 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 08:43:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KYaAbAsJYf/Render', 08:43:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:15 INFO - 'HOME': '/Users/cltbld', 08:43:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:15 INFO - 'LOGNAME': 'cltbld', 08:43:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:15 INFO - 'MOZ_NO_REMOTE': '1', 08:43:15 INFO - 'NO_EM_RESTART': '1', 08:43:15 INFO - 'PAGER': '/bin/cat', 08:43:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:15 INFO - 'PWD': '/builds/slave/test', 08:43:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:15 INFO - 'SHELL': '/bin/bash', 08:43:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a0YsBKrGIi/Listeners', 08:43:15 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 08:43:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:15 INFO - 'USER': 'cltbld', 08:43:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:15 INFO - 'XPC_FLAGS': '0x0', 08:43:15 INFO - 'XPC_SERVICE_NAME': '0', 08:43:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:15 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)) 08:43:15 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)) 08:43:15 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)) 08:43:15 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)) 08:43:15 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)) 08:43:15 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)) 08:43:15 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)) 08:43:15 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)) 08:43:15 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)) 08:43:15 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)) 08:43:15 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)) 08:43:15 INFO - Cleaning up... 08:43:15 INFO - Return code: 0 08:43:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:43:15 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')]} 08:43:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:15 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')]} 08:43:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:15 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 0x1017c73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10173d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd67ad61070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015ddd30>, '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 0x101444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d5b20>, '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.KYaAbAsJYf/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.a0YsBKrGIi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 08:43:15 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 08:43:15 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 08:43:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KYaAbAsJYf/Render', 08:43:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:15 INFO - 'HOME': '/Users/cltbld', 08:43:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:15 INFO - 'LOGNAME': 'cltbld', 08:43:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:15 INFO - 'MOZ_NO_REMOTE': '1', 08:43:15 INFO - 'NO_EM_RESTART': '1', 08:43:15 INFO - 'PAGER': '/bin/cat', 08:43:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:15 INFO - 'PWD': '/builds/slave/test', 08:43:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:15 INFO - 'SHELL': '/bin/bash', 08:43:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a0YsBKrGIi/Listeners', 08:43:15 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 08:43:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:15 INFO - 'USER': 'cltbld', 08:43:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:15 INFO - 'XPC_FLAGS': '0x0', 08:43:15 INFO - 'XPC_SERVICE_NAME': '0', 08:43:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:43:16 INFO - Cleaning up... 08:43:16 INFO - Return code: 0 08:43:16 INFO - Running post-action listener: _resource_record_post_action 08:43:16 INFO - Running post-action listener: _start_resource_monitoring 08:43:16 INFO - Starting resource monitoring. 08:43:16 INFO - [mozharness: 2016-11-28 16:43:16.269082Z] Finished create-virtualenv step (success) 08:43:16 INFO - [mozharness: 2016-11-28 16:43:16.270080Z] Running install step. 08:43:16 INFO - Running pre-action listener: _resource_record_pre_action 08:43:16 INFO - Running main action method: install 08:43:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:43:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:43:16 INFO - Reading from file tmpfile_stdout 08:43:16 INFO - Detecting whether we're running mozinstall >=1.0... 08:43:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:43:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:43:16 INFO - Reading from file tmpfile_stdout 08:43:16 INFO - Output received: 08:43:16 INFO - Usage: mozinstall [options] installer 08:43:16 INFO - Options: 08:43:16 INFO - -h, --help show this help message and exit 08:43:16 INFO - -d DEST, --destination=DEST 08:43:16 INFO - Directory to install application into. [default: 08:43:16 INFO - "/builds/slave/test"] 08:43:16 INFO - --app=APP Application being installed. [default: firefox] 08:43:16 INFO - mkdir: /builds/slave/test/build/application 08:43:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:43:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:43:49 INFO - Reading from file tmpfile_stdout 08:43:49 INFO - Output received: 08:43:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:43:49 INFO - Running post-action listener: _resource_record_post_action 08:43:49 INFO - [mozharness: 2016-11-28 16:43:49.439970Z] Finished install step (success) 08:43:49 INFO - [mozharness: 2016-11-28 16:43:49.440112Z] Running run-tests step. 08:43:49 INFO - Running pre-action listener: _resource_record_pre_action 08:43:49 INFO - Running main action method: run_tests 08:43:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:43:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:43:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:43:49 INFO - 2016-11-28 08:43:49.454 screenresolution[1622:7309] starting screenresolution argv=screenresolution get 08:43:49 INFO - 2016-11-28 08:43:49.456 screenresolution[1622:7309] Display 0: 1600x1200x32@60 08:43:49 INFO - 2016-11-28 08:43:49.461 screenresolution[1623:7311] starting screenresolution argv=screenresolution list 08:43:49 INFO - Available Modes on Display 0 08:43:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:43:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:43:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:43:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:43:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:43:49 INFO - Intel Iris: 08:43:49 INFO - Chipset Model: Intel Iris 08:43:49 INFO - Type: GPU 08:43:49 INFO - Bus: Built-In 08:43:49 INFO - VRAM (Dynamic, Max): 1536 MB 08:43:49 INFO - Vendor: Intel (0x8086) 08:43:49 INFO - Device ID: 0x0a2e 08:43:49 INFO - Revision ID: 0x0009 08:43:49 INFO - Displays: 08:43:49 INFO - Display: 08:43:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:43:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:43:49 INFO - Display Serial Number: 1600x1200 60 08:43:49 INFO - Main Display: Yes 08:43:49 INFO - Mirror: Off 08:43:49 INFO - Online: Yes 08:43:49 INFO - Rotation: Supported 08:43:49 INFO - Return code: 0 08:43:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:43:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:43:49 INFO - Python 2.7.3 08:43:49 INFO - Return code: 0 08:43:49 INFO - grabbing minidump binary from tooltool 08:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:49 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 0x1015ddd30>, '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 0x101444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:43:49 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 08:43:49 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 08:43:49 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 08:43:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:43:49 INFO - Return code: 0 08:43:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:43:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:43:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:43:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:43:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:43:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0333', '--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 08:43:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0333 --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 08:43:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KYaAbAsJYf/Render', 08:43:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:49 INFO - 'HOME': '/Users/cltbld', 08:43:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:49 INFO - 'LOGNAME': 'cltbld', 08:43:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:43:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:43:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:49 INFO - 'MOZ_NO_REMOTE': '1', 08:43:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:43:49 INFO - 'NO_EM_RESTART': '1', 08:43:49 INFO - 'PAGER': '/bin/cat', 08:43:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:49 INFO - 'PWD': '/builds/slave/test', 08:43:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:43:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:49 INFO - 'SHELL': '/bin/bash', 08:43:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a0YsBKrGIi/Listeners', 08:43:49 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 08:43:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:49 INFO - 'USER': 'cltbld', 08:43:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:49 INFO - 'XPC_FLAGS': '0x0', 08:43:49 INFO - 'XPC_SERVICE_NAME': '0', 08:43:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0333', '--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 08:43:50 INFO - mozversion application_buildid: 20161128062457 08:43:50 INFO - mozversion application_changeset: bc4ed3faa33e589fcc1b86221d837b98af3551b7 08:43:50 INFO - mozversion application_display_name: Nightly 08:43:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:43:50 INFO - mozversion application_name: Firefox 08:43:50 INFO - mozversion application_remotingname: firefox 08:43:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:43:50 INFO - mozversion application_vendor: Mozilla 08:43:50 INFO - mozversion application_version: 53.0a1 08:43:50 INFO - mozversion platform_buildid: 20161128062457 08:43:50 INFO - mozversion platform_changeset: bc4ed3faa33e589fcc1b86221d837b98af3551b7 08:43:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:43:50 INFO - mozversion platform_version: 53.0a1 08:43:50 INFO - using testdate: 1480351430 08:43:50 INFO - actual date: 1480351430 08:43:50 INFO - starting webserver on 'localhost:49237' 08:43:50 INFO - SUITE-START | Running 2 tests 08:43:50 INFO - TEST-START | dromaeo_css 08:43:50 INFO - operating with platform_type : mac_ 08:43:50 INFO - Initialising browser for dromaeo_css test... 08:43:50 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmpHL4J0W/profile http://localhost:49237/getInfo.html) 08:43:53 INFO - PROCESS | 1633 | 2016-11-28 08:43:53.059 plugin-container[1635:7479] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 08:43:53 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:43:53 INFO - PROCESS | 1633 | 2016-11-28 08:43:53.062 plugin-container[1635:7479] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 08:43:53 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:43:53 INFO - PROCESS | 1633 | 2016-11-28 08:43:53.062 plugin-container[1635:7479] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:43:53 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 08:43:53 INFO - PROCESS | 1633 | colorDepth:24 08:43:53 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 08:43:53 INFO - PROCESS | 1633 | __metrics 08:43:53 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 08:43:53 INFO - TEST-INFO | 1633: exit 0 08:43:53 INFO - Browser initialized. 08:43:53 INFO - Running cycle 1/1 for dromaeo_css test... 08:43:53 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmpHL4J0W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:43:56 INFO - PROCESS | 1636 | 2016-11-28 08:43:56.757 plugin-container[1637:7600] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 08:43:56 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:43:56 INFO - PROCESS | 1636 | 2016-11-28 08:43:56.757 plugin-container[1637:7600] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 08:43:56 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:43:56 INFO - PROCESS | 1636 | 2016-11-28 08:43:56.757 plugin-container[1637:7600] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:44:06 INFO - PROCESS | 1636 | RSS: Main: 242565120 08:44:06 INFO - PROCESS | 1636 | 08:44:56 INFO - PROCESS | 1636 | 1480351496010 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] 08:47:09 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49237/tests/dromaeo/cssquery-ext.html) 08:47:11 INFO - PROCESS | 1636 | RSS: Main: 249888768 08:47:11 INFO - PROCESS | 1636 | 08:50:13 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-ext.html (next: http://localhost:49237/tests/dromaeo/cssquery-jquery.html) 08:50:14 INFO - PROCESS | 1636 | RSS: Main: 262062080 08:50:14 INFO - PROCESS | 1636 | 08:53:16 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49237/tests/dromaeo/cssquery-mootools.html) 08:53:16 INFO - PROCESS | 1636 | RSS: Main: 266653696 08:53:16 INFO - PROCESS | 1636 | 08:56:18 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49237/tests/dromaeo/cssquery-prototype.html) 08:56:19 INFO - PROCESS | 1636 | RSS: Main: 265625600 08:56:19 INFO - PROCESS | 1636 | 08:59:22 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49237/tests/dromaeo/cssquery-yui.html) 08:59:22 INFO - PROCESS | 1636 | RSS: Main: 271429632 08:59:22 INFO - PROCESS | 1636 | 09:02:25 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-yui.html (next: http://localhost:49237/tests/dromaeo/cssquery-dojo.html) 09:02:25 INFO - PROCESS | 1636 | RSS: Main: 275107840 09:02:25 INFO - PROCESS | 1636 | 09:02:25 INFO - PROCESS | 1636 | __start_tp_report 09:02:25 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 09:02:25 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 09:02:25 INFO - PROCESS | 1636 | |i|pagename|runs| 09:02:25 INFO - PROCESS | 1636 | |0;dojo.html;3053.0840747775665;3285.211549228186;3301.950470742939;3332.450145725954;3344.347852168827;4565.406497155365;5090.109230884599;5516.448355164482;5577.358603760564;5601.60788744788;4277.508086570046;5425.945740542594;5462.371827239868;5495.862603434916;5519.889602207953;5030.949690503094;5089.109405853979;5103.642745007851;5148.819791307305;5149.6910185389;5085.847424577269;5128.410232823236;5148.021875843597;5156.974215128937;5189.896202075956;5138.254953031808;5140;5150.356205474316;5152.536271735543;5152.562032227265;4014.3978403239457;4234.237837189305;4800.975995120037;5109;5166.715830629314;4167.499900011988;5081.974590127025;5096.694198348074;5098.694078355311;5106.719130447824;5463.945360546423;5568;5652.830415087513;5663.943360566383;5706.28671416073;5894.941050589482;6068.848278793022;6075.149479072933;6081.270247570275;6103.755849766048;5776.902388546205;6038.305594856603;6048.304444988838;6099.207103076616;6133.84665383369;5656;5664.915026274609;5678.14827775837;5687.857803554903;5692.829215123512;6164.352742962014;6174.073888916699;6174.351693072253;6178.7219575119225;6197;5790.826275211751;5852.385499522574;5854.356020837705;5954.136650185786;5987.760489580455;5982.611130276518;6011.549133814981;6062;6083.878322433527;6097.207363042776;6009.188759517497;6063.878722425527;6067.848303792352;6075.27096748393;6130.111133885562;6024.668643224665;6080.422359875804;6086.2087928569;6096.72564734588;6104.328523862371;4712.693674911188;4757.881052973703;4793.424789025339;4820.542048505386;4833.226683730586;4285.550017248206;4350.6736994725525;4357.869263922087;4384.8903777405185;4392.934105988413;5503.862403439946;5714.257275948517;5722.856928576735;5740.942590574125;5766.596338256281;6011.6994150293185;6071.51427885768;6087.239095113111;6159.19930409044;6174.38256174388;5651.491365777018;5674.2339784129435;5688.317401911797;5692.48767610917;5700.00249956256;5233.188855727292;5235.685858848481;5245.580353571705;5296.708681022504;5298.655587386807;5492.313460817398;6195.349488303754;6199.628022318675;6215.471684906653;6236.594621349779;6074.635521868702;6127.20346354971;6133.417325354084;6139.9079013813935;6148.4773794227995;6092.147099406176;6106.673025714385;6118.223266370627;6145.477634401126;6188.938110618837;5991.7603295867675;6000.0999850022845;6020.217371741645;6112.777056696867;6122.571420000557;6077.604955677867;6132.693365331805;6144.2319710036245;6166.81499555014;6181.041938499539;5750.424957504216;5988.820335389945;5994.220751302303;6019.789307374396;6052.848678783065;5869.765209391577;5895.7052147391605;5915.852103697442;5945.940540594539;6042.0332746760305;4932.260160975882;5761.567882408753;5844.795432159854;5874.911876321773;5887.970560147171;5953.374895635896;5988.431099045583;5989.251343582052;6033.75864965414;6036.154938308552;5396.190571414162;5625.718714064198;5699.2590963174525;5720.141978703062;5808.796692115925 09:02:25 INFO - PROCESS | 1636 | |1;ext.html;461.8129657488718;475.7502311286575;480.8317089018844;493.50402845140604;516.9634882061466;33079.50380744291;39617.20765584687;39708.801455992754;39760.204795904065;39930.60069399305;33195.83402082992;34283;40742;41203;41249;8360.916390836104;8620.525871077089;9230.446173229611;9407.247420206368;9428.434293942351;10548.630797922075;10563.524641391155;10986.725331866728;11321.150913681508;11468.71328216793;11304.491297891615;11361.602343917964;11373.658790236306;11382.772344553105;11419.143564232674;11238.887611123906;11258.324500529994;11269;11287.98408143271;11292.096632269398;10989.450527473595;11033.724156896062;11122;11166.939140781613;11178.552857885676;38863.80568097141;38865.22269554621;39066.218675626325;39083.60916390828;39227.01932451683;3338.88313909012;3351.3967485852536;3452.5684289463816;3552.182997910495;3598.748087633867;29237.853810731023;29406.264843378875;29472.11583652494;29577.40845183106;29812.254693632614;3188.952165717516;3199.152224660467;3199.2001999500126;3233.9651311580305;3265.2653153040615;474655;485857;488092;488752.5562372165;490197;56816;56881.862362752516;56979.715101425056;57105.714471427375;57169.42830571724;57812.71093644589;58870.11694824581;59046.40953590495;59205.40794592085;59211.11183332253;14975.176365300013;15440.227988600525;15445.227738613024;15454.845451545565;15501.612459688373;9245.306602004877;10821.783564328669;11116.499757510932;12037.87962120385;12057.57798477049;15222.695546089017;15243;15257.465988690343;15270.38918443272;15313;6248.750049997949;6332.873342533124;6335.239771227482;6373.426391624684;6375.171227740364;847.4322204123253;980.6077568972468;987.2151639446657;1024.2267088348249;1093.1418836213534;29154.416911661647;29226.41547169045;29559.556606650916;29594.704052959194;29907.850460747555;979.951002449889;1012.2964539645023;1069.5294070608907;1081.0486771640906;1088.6026600290995;12799.65603611626;12863.22820630765;14655.340509676877;14663.780043299359;15181.468648597245;551459;641974;652370;659544;659960;55583.88832223333;55611.44388556063;55625.721871390386;55911.44088559144;56209.437905621235;1295.8898493627958;1327.932475980488;1337.5452346202337;1347.0570600579633;1351.9391627376988;27755.167344978876;28098.157055288375;29052.854735726185;29270.853645732488;29522.557161642162;44659.43691970765;49870.75064624654;54182.916341673736;54828;57679.711601441726;508509;508908;509424;509754;512390;20265.088071037135;21178.47053823605;24104.156354527506;24712;25770.711464427077;37062;44750.78376126073;45093.32360014537;45345.77327113343;45704.77147614241;37416;37713.43429848499;37881;38257.80871095627;38389.80805095957;32721.181970449976;34100.65899340975;37079.443808343436;37084.2915708427;38117.42823857589;36232.63767362293;36791.08022299464;37394.43908341431;37397.81301093477;37472.812635937735;29664;32407.51388729218;33422.4959876811;33552.832235838665;33566;20893.89553052225;24307;24346;24782.752172478766;24866.75133248717 09:02:25 INFO - PROCESS | 1636 | |2;jquery.html;306.90485949355696;365.3113880335567;367.5699431664952;380.1617433559003;397.85417996171043;17779;18271.45185644431;19353.806461935375;19487.707684384746;19556.315528956453;20922.26772062978;21274;21444.4638884028;21530.354089377306;21774.911254437295;5039.899202015967;6472.126262954488;6737.595744255336;6755.121834161552;6818.488613353993;6509.1863517060365;6694.966525167391;6698.3971442570155;6713.26154123046;6713.697883595226;6521.08704781331;6657.068010478537;6668.333166683317;6670.81828372151;6695.732170713166;6355.173827402408;6539.08452816606;6599.835004124887;6610.636414997173;6622;6464.224293084813;6492.967535162341;6505.739770409178;6513.511486638521;6533.085368048477;22232.333030009126;27169;27532.862335688194;27947.441051178863;28030.57954130669;1840.4202676156967;2064.23623259395;2440.6583078369044;2453;2749.4913441013477;13604.455821767118;15752.739780817506;16261.105639189958;16429.342826286935;16642.417515387024;1695.6184858406882;1929.0740444586677;1953.540917884296;1957.5204075001573;1980.9405717828345;98304;128945;131344;131756;133813;17214.569635759206;19870.70193947092;21218.469538261666;21223.575528489342;21484.892575537335;30700.692993069784;30973;34140.4636791345;34186.48720269198;34193;9956.253281003954;10132.392056476636;10306.175505959505;10339.896601033894;10398.636047738293;6892.931070689329;7638.50349727266;8412.284955778705;8458;8484.830303393897;9323.393979391318;9583.377080489747;9615.8845054088;9658.227341812639;9699.854502182472;3706.184639379332;3765.491658626105;3770.831042376872;3785.7885476647757;3919.392494163409;808.9433739638287;810.1129263456505;832.0722394530177;852.9573521324033;909.88171537701;18057.458276251735;20131.49671258201;20225.797742022685;22222.555548889253;22247.555048898932;781.417843706431;834.7287131682227;889.3063410539789;907.491804589432;958.4249450329746;10218.846717299246;10490.842637360445;10862.368905015192;10935.28920620173;10999.945000274947;549774;585972;603412;604490;615976;132928;139248;140554;141601;142813;933.6918816790443;957.2389949989774;996.3872218585662;1000.2548101664306;1007.8236308645958;14420.42318307256;16326;18639.068046597346;18761.90619046896;19160.425187244404;54405.45594544004;54874.451255486936;55561;56424;56537;76933;77337.226627733;77625.61187194027;78932.60533697295;79490.20509794827;6963.09479767627;7313.317936874655;7690.846183076195;8044.436889417684;8214.58927053633;11376.772464550497;12470;12511.874741988548;12742.808857867325;12838.358082096045;8346;10715.196360272856;10888.727781805519;10928.672139835817;11053.28153670009;10684.83972740425;10798.460076996276;10807.35155890649;10845.240833141605;11093.944530277297;10862.185336099667;11107.83338249943;11281.492332845206;11301.434928253719;11622.88377116218;6939.826504337432;7586.734464293723;7980.281774640078;8103.027636683636;8155.918440815516;9172.587233574639;9367.344285899922;10869.728256793644;11122.054625356936;11176.664700059011 09:02:25 INFO - PROCESS | 1636 | |3;mootools.html;1384.993075034625;1454.5636309107265;1635.0189886068354;1792.3726695656514;1809.918553665085;7265.49141560091;7472.327490525851;7749.496282741625;8106.554139522331;8112.391570632204;7627.275408836152;7945.562994035326;7946.443748937577;7953.284204421601;7961.4825036372595;2224.5439684864564;2282.4408020035116;2283.098176220392;2287.531056133497;2289.461976435536;2269.614165591853;2291.3813270416977;2304.8847557622134;2305.0203663443053;2308.1113771198125;2242.7532971373134;2254.5829021631052;2269.9886500567472;2275.101334972685;2284.1776960294364;2234.698315727372;2238.7201599800023;2257.9435514112115;2260.92086776962;2264.4904896398343;2261.9934129312464;2285.565742508918;2285.5885940530698;2294.6328587425933;2302.2747834432103;7943.841123177562;7998.12020677725;8031.156728543478;8050.11448740638;8053.4765240259785;4935.950640493585;4981.452040275567;5026.070177017264;5026.8240611578785;5062.898742025139;2865.3839424523826;3141.9685803142133;3184.9203769905707;3391.915202119942;3403.6085850127306;1026.7946410717811;1068.4474123539262;1073.5920350266927;1091.6397588795758;1092.9781404371947;10049.196064314838;10196.796064078751;10210.795784084425;10322.277440579162;10323.535440905178;4161.292580261363;4313.288307429302;4392.802323895432;4476.731396116244;4488.730676159375;8934.553272336487;8987.056359082202;8994.775130621787;8998.955005225063;9025.81948361029;4341.806003349041;4713.033828065239;4891.364122664031;4924.482929292372;4936.48166942473;4100.835966561304;4254.510731265882;4480.753558554311;4544.409226800561;4557.521460246627;3656.2687462507606;3659.652333028358;3758.7971849008054;3762.9087564606507;3763.379042457964;1055.9524821382902;1214.9271043737403;1236.202649291202;1245.1159676629513;1248.76273508035;702.929707029293;765.2500549461558;866.1165611076666;877.5656050255164;887.7558671365426;5379.973100134553;5419.83740487786;5505.504504594605;5529.668219906738;5570.164475328733;438.38407038111507;443.2641814587833;451.7876597998935;456.31324856092033;459.0818363273453;3698.223373091627;3739.7658772604973;3753.2118255166174;3807.428885667172;3821.5032045833864;9251.352405331696;9494.715158545254;9586.37688550256;9598.95200524007;9619.18236949853;9580.66467673628;9581.664641737505;9618.086281803218;9621.711348659552;9656.855147172799;5732.3121225453215;7065.858682826315;7361.527694461022;7483.550986940801;7582.507137036075;4652.860414187579;5217.426083130928;5361.812336568358;5469.453054694499;5502.422245664307;8680.001799792852;9061.320400969822;9067.773305667411;9085;9093.40892841963;7190.640467976685;8884.200421962054;9101.044390338919;9193.908060919573;9231.538423078684;6312.179416675803;6506.967465162644;6719.26088130315;6869.965650171717;6937.098177236927;7451.478396512192;7647.120581133018;7651.61741912891;7679.731209407644;7695.538267703731;6723.294054124247;6823.38589526945;6830;6877.552959057645;6975.511714179958;6664.5334826561675;6701.430378417694;6707.429868461236;6730.32696730323;6765.594064356154;6374.904376434264;6375.330590287953;6403.3276505966205;6452.709628066655;6474.70863811139;4006.258842114218;4318.719283246579;4678.345031695498;4712.117227313911;4812.037592481588;4663.300504924288;4756.8097276108565;4757.976210119065;4805.303231031551;4924.926126108183 09:02:25 INFO - PROCESS | 1636 | |4;prototype.html;256.70607154807743;301.1777846479112;308.33399856310376;310.97356724678406;311.99688003119974;4024;4332.826686932519;4583.449986001685;5124.820631277906;5308.469153084689;4328.718633288834;5007.774650140744;5079.2635067915135;5276.997370499603;5292.153255479124;3409.6760807723226;3654.2326111516613;3861.015441062525;4089.427480152781;4115.609017143382;3584.534010578634;4056.655184309337;4081.9183616327655;4092.6725861930972;4116.897077573055;3080.276135108259;3333.633300336973;3813.245906882837;4052.716309858311;4063.6952228582827;3375.443051896434;4030.8589199378175;4034.515858097018;4048.696347773914;4081.3265811141127;3097.922551936197;3209.422303985282;3485.8322461975017;3555.839987200582;3591.617227367456;4658.627309815241;4679.836205732783;5063.721495317756;5103.489651034867;5164.690118592896;4050.9594904050878;4621.214393553104;4656.743879086649;4658.836940707059;4691.671582989192;4427.690061695683;5561.304836895388;5576.944230557683;5577.8047768327915;5583.916241256385;3535.3459609972238;3593.9820300898327;3651.6362946279205;3817.48463957365;3967.5238971323624;5462.918056229159;5807.477327040544;5854.238948936653;5858.589898707092;5881.617694849864;3089.536569514546;3576.5171701819922;3970.7419017763755;3999.7400168989507;4022.365350695299;3575.284943011356;4021.5375231848125;4026.8993275168355;4029.7985100744845;4035.6973227008093;4452.243118669834;4558.247889098328;4579.4046773919845;4581.40441742578;4625.907481850344;4876.268559716071;4877.6829506081995;5037.697738135724;5581.804636837691;5582.051051321285;1185.6087491127907;1220.6277085489012;1260.2249616485979;1289.7033682253134;1317.5652034828483;875.5315905990265;912.3522299167532;912.7632058560688;916.7387294621001;923.1875949164827;4473.832723204704;4513.68404211702;4521.434820647419;4762.380890484214;5009.799608015638;4497.839498463908;5093;5451.454854514596;5491.023231636607;5508.724563771795;4652.232151782301;4920.532549407801;5004.224345226496;5009.499050095034;5053.5742843435355;2551.821372503907;2651.7613414792763;2863.4086152032028;2875.3961668049524;2882.8414437206156;4447.199504089295;5255.500727430888;5255.605829562798;5583.302087239095;5627.184058311604;4563.459357938872;4610.985583171697;5456.308544351264;5559.916601250984;5602.495775380154;5284;5579.581531385163;5607.635503692247;5611.943880561143;5647.858803529944;4922.32640902095;5065.797368105234;5422.403535611158;5603;5884.411558844253;4860.902781944412;5847.275053859037;5849.678267695318;5855.3559108497175;5891.558133140117;5822.641830058833;5851.6112915313515;6113.449789518964;6119.571629985859;6123.173371594956;3814.3788889722314;3881.4862203741127;3884.572697003271;3895.1820117775583;3912.256671232457;5786.479216870333;5847.210626565529;5866.6480011199465;5886.293644762484;5902;4476.30966210783;5542.3903370628395;5686.317641883001;5734.397888221677;5751.137329400456;4790.640701947298;5582.464822173934;5625.578081643811;5653.2795116076895;5663.1788390682295;4650.883727906829;5177.042247184283;5313.521783039545;5452.5365343946205;5553;5091.719955402488;5527.143292789624;5634.309707087776;5688.40271771458;5716.628419152742;4965.577925876341;5055.9494405055475;5186.9221961669855;5537.846615038721;5609.401320623766 09:02:25 INFO - PROCESS | 1636 | |5;yui.html;676.4250387170905;732.6702983657356;738.634375983888;752.300360664582;810.8945837041184;14124.08193467424;14287.78568321476;14625.341859616316;14666.633334166638;15544.922275388608;11679.240849344787;13827.516036938709;14176.716465670706;14177.432902683906;14222.644433889158;841.8274253777992;865.208334374047;870.8867847179889;873.9519326437044;876.3996662286354;673.6934694713893;695.2456584605684;836.4186890111375;858.7938894665267;872.8385248728973;910.3854897943894;915.0392088307284;917.3486824354716;919.1911118215994;921.6728061538158;801.2028032108061;811.1563973467617;830.0786127398583;895.4895709445618;910.9407888487227;661.2461793555351;665.4609766089483;677.1062197898775;681.9386255237052;682.4642655515446;12318.938405307916;14491.275436228252;14692.191929443876;14716.264186790617;14717.852821471755;11660.30038197711;13825.446982120704;14022.649433764136;14203.360848761831;14224.715505689932;9888.505574721306;9906.455144967023;9943.93450756636;9954.639093574198;10199.705066360084;3659.9569122799876;3693.261347730465;3694.741368104234;3699;3710;92875.60686589706;109235.45382273197;110420;111776;112910.43544782224;10774.49751544486;10863.45682715861;10961.671149865517;10963.122950164126;11265.211435199457;22276;22505.662415063787;22541.323760287218;22551.661725074136;22976.195833146096;6883.105196324445;7287.599182044933;7592.924070759222;7621.085469743666;7666;4110.815013324408;4149.958500415018;4278.44380230568;4286.271333873249;4296.892577685583;3379.766385269359;3460.3252365788935;3475.739319551044;3478.7390945679176;3505.631908649606;602.8553147244629;611.8776244751068;618.604093380237;621.6643012773143;625.9640295311267;545.6344249352863;550.5980634137022;555.4279092534696;590.3948452835878;636.2238069555135;8768.430052046597;8854.690085846965;9140.451572905647;9216.861747073799;9417.387869788441;511.8285374399618;622.7260005597523;654.7642848574509;658.3876994395258;662.3906006474056;3800.56293526248;4490.618297444754;4506.3465797459185;4517.593416592523;4577.679562430664;105722;108752;113414;113888;114246.42876785563;95333.52333238289;97340;97777.51111244399;98102;98128;80316.59841700754;81996.59001705072;82952.58523707463;82967.585162075;83562;8729.25801306896;8865.512396818236;8868.185471992909;8876.556172191495;8959.776005599912;51732.48267517277;52472.47527524676;52832;53116.46883531115;53366;59079.70460147672;77187.228127718;77729.44541109033;78956.60521697355;79077;11391.544338226378;11965.081557517537;11996.160268781101;12008;12089.637310880687;12466.75066498683;12781.872181278068;12824.935875320563;12975.000873048917;13139;12006.579769708018;12142.75714485723;12289;12607.684807879876;12638.684032898884;11995.100367472649;12091.57679481214;12137.21108127969;12137.332446715516;12546.059045571801;11908.702282442662;11953.760924781629;12002.459889304828;12115.601671253811;12409.565665201675;8551.529665868187;9463.668771593237;9875.209983201183;10043.469220029472;10581.52383142745;8790.736277911672;10077.949610252195;10388.480575971322;10392.896071039193;10496 09:02:25 INFO - PROCESS | 1636 | __end_tp_report 09:02:25 INFO - PROCESS | 1636 | __start_cc_report 09:02:25 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,352 09:02:25 INFO - PROCESS | 1636 | __end_cc_report 09:02:25 INFO - PROCESS | 1636 | __startTimestamp1480352545671__endTimestamp 09:02:25 INFO - PROCESS | 1636 | 09:02:25 INFO - PROCESS | 1636 | ------- Summary: start ------- 09:02:25 INFO - PROCESS | 1636 | Number of tests: 6 09:02:25 INFO - PROCESS | 1636 | 09:02:25 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5573.95 Median:5766.60 stddev:664.50 (11.5%) stddev-sans-first:636.43 09:02:25 INFO - PROCESS | 1636 | Values: 3053.1 3285.2 3302.0 3332.5 3344.3 4565.4 5090.1 5516.4 5577.4 5601.6 4277.5 5425.9 5462.4 5495.9 5519.9 5030.9 5089.1 5103.6 5148.8 5149.7 5085.8 5128.4 5148.0 5157.0 5189.9 5138.3 5140.0 5150.4 5152.5 5152.6 4014.4 4234.2 4801.0 5109.0 5166.7 4167.5 5082.0 5096.7 5098.7 5106.7 5463.9 5568.0 5652.8 5663.9 5706.3 5894.9 6068.8 6075.1 6081.3 6103.8 5776.9 6038.3 6048.3 6099.2 6133.8 5656.0 5664.9 5678.1 5687.9 5692.8 6164.4 6174.1 6174.4 6178.7 6197.0 5790.8 5852.4 5854.4 5954.1 5987.8 5982.6 6011.5 6062.0 6083.9 6097.2 6009.2 6063.9 6067.8 6075.3 6130.1 6024.7 6080.4 6086.2 6096.7 6104.3 4712.7 4757.9 4793.4 4820.5 4833.2 4285.6 4350.7 4357.9 4384.9 4392.9 5503.9 5714.3 5722.9 5740.9 5766.6 6011.7 6071.5 6087.2 6159.2 6174.4 5651.5 5674.2 5688.3 5692.5 5700.0 5233.2 5235.7 5245.6 5296.7 5298.7 5492.3 6195.3 6199.6 6215.5 6236.6 6074.6 6127.2 6133.4 6139.9 6148.5 6092.1 6106.7 6118.2 6145.5 6188.9 5991.8 6000.1 6020.2 6112.8 6122.6 6077.6 6132.7 6144.2 6166.8 6181.0 5750.4 5988.8 5994.2 6019.8 6052.8 5869.8 5895.7 5915.9 5945.9 6042.0 4932.3 5761.6 5844.8 5874.9 5888.0 5953.4 5988.4 5989.3 6033.8 6036.2 5396.2 5625.7 5699.3 5720.1 5808.8 09:02:25 INFO - PROCESS | 1636 | 09:02:25 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:67105.31 Median:34191.83 stddev:146203.53 (427.6%) stddev-sans-first:146528.05 09:02:25 INFO - PROCESS | 1636 | Values: 461.8 475.8 480.8 493.5 517.0 33079.5 39617.2 39708.8 39760.2 39930.6 33195.8 34283.0 40742.0 41203.0 41249.0 8360.9 8620.5 9230.4 9407.2 9428.4 10548.6 10563.5 10986.7 11321.2 11468.7 11304.5 11361.6 11373.7 11382.8 11419.1 11238.9 11258.3 11269.0 11288.0 11292.1 10989.5 11033.7 11122.0 11166.9 11178.6 38863.8 38865.2 39066.2 39083.6 39227.0 3338.9 3351.4 3452.6 3552.2 3598.7 29237.9 29406.3 29472.1 29577.4 29812.3 3189.0 3199.2 3199.2 3234.0 3265.3 474655.0 485857.0 488092.0 488752.6 490197.0 56816.0 56881.9 56979.7 57105.7 57169.4 57812.7 58870.1 59046.4 59205.4 59211.1 14975.2 15440.2 15445.2 15454.8 15501.6 9245.3 10821.8 11116.5 12037.9 12057.6 15222.7 15243.0 15257.5 15270.4 15313.0 6248.8 6332.9 6335.2 6373.4 6375.2 847.4 980.6 987.2 1024.2 1093.1 29154.4 29226.4 29559.6 29594.7 29907.9 980.0 1012.3 1069.5 1081.0 1088.6 12799.7 12863.2 14655.3 14663.8 15181.5 551459.0 641974.0 652370.0 659544.0 659960.0 55583.9 55611.4 55625.7 55911.4 56209.4 1295.9 1327.9 1337.5 1347.1 1351.9 27755.2 28098.2 29052.9 29270.9 29522.6 44659.4 49870.8 54182.9 54828.0 57679.7 508509.0 508908.0 509424.0 509754.0 512390.0 20265.1 21178.5 24104.2 24712.0 25770.7 37062.0 44750.8 45093.3 45345.8 45704.8 37416.0 37713.4 37881.0 38257.8 38389.8 32721.2 34100.7 37079.4 37084.3 38117.4 36232.6 36791.1 37394.4 37397.8 37472.8 29664.0 32407.5 33422.5 33552.8 33566.0 20893.9 24307.0 24346.0 24782.8 24866.8 09:02:25 INFO - PROCESS | 1636 | 09:02:25 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:36565.25 Median:34163.48 stddev:99232.37 (290.5%) stddev-sans-first:99473.39 09:02:25 INFO - PROCESS | 1636 | Values: 306.9 365.3 367.6 380.2 397.9 17779.0 18271.5 19353.8 19487.7 19556.3 20922.3 21274.0 21444.5 21530.4 21774.9 5039.9 6472.1 6737.6 6755.1 6818.5 6509.2 6695.0 6698.4 6713.3 6713.7 6521.1 6657.1 6668.3 6670.8 6695.7 6355.2 6539.1 6599.8 6610.6 6622.0 6464.2 6493.0 6505.7 6513.5 6533.1 22232.3 27169.0 27532.9 27947.4 28030.6 1840.4 2064.2 2440.7 2453.0 2749.5 13604.5 15752.7 16261.1 16429.3 16642.4 1695.6 1929.1 1953.5 1957.5 1980.9 98304.0 128945.0 131344.0 131756.0 133813.0 17214.6 19870.7 21218.5 21223.6 21484.9 30700.7 30973.0 34140.5 34186.5 34193.0 9956.3 10132.4 10306.2 10339.9 10398.6 6892.9 7638.5 8412.3 8458.0 8484.8 9323.4 9583.4 9615.9 9658.2 9699.9 3706.2 3765.5 3770.8 3785.8 3919.4 808.9 810.1 832.1 853.0 909.9 18057.5 20131.5 20225.8 22222.6 22247.6 781.4 834.7 889.3 907.5 958.4 10218.8 10490.8 10862.4 10935.3 10999.9 549774.0 585972.0 603412.0 604490.0 615976.0 132928.0 139248.0 140554.0 141601.0 142813.0 933.7 957.2 996.4 1000.3 1007.8 14420.4 16326.0 18639.1 18761.9 19160.4 54405.5 54874.5 55561.0 56424.0 56537.0 76933.0 77337.2 77625.6 78932.6 79490.2 6963.1 7313.3 7690.8 8044.4 8214.6 11376.8 12470.0 12511.9 12742.8 12838.4 8346.0 10715.2 10888.7 10928.7 11053.3 10684.8 10798.5 10807.4 10845.2 11093.9 10862.2 11107.8 11281.5 11301.4 11622.9 6939.8 7586.7 7980.3 8103.0 8155.9 9172.6 9367.3 10869.7 11122.1 11176.7 09:02:25 INFO - PROCESS | 1636 | 09:02:25 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5187.45 Median:4936.22 stddev:2829.74 (57.3%) stddev-sans-first:2823.25 09:02:25 INFO - PROCESS | 1636 | Values: 1385.0 1454.6 1635.0 1792.4 1809.9 7265.5 7472.3 7749.5 8106.6 8112.4 7627.3 7945.6 7946.4 7953.3 7961.5 2224.5 2282.4 2283.1 2287.5 2289.5 2269.6 2291.4 2304.9 2305.0 2308.1 2242.8 2254.6 2270.0 2275.1 2284.2 2234.7 2238.7 2257.9 2260.9 2264.5 2262.0 2285.6 2285.6 2294.6 2302.3 7943.8 7998.1 8031.2 8050.1 8053.5 4936.0 4981.5 5026.1 5026.8 5062.9 2865.4 3142.0 3184.9 3391.9 3403.6 1026.8 1068.4 1073.6 1091.6 1093.0 10049.2 10196.8 10210.8 10322.3 10323.5 4161.3 4313.3 4392.8 4476.7 4488.7 8934.6 8987.1 8994.8 8999.0 9025.8 4341.8 4713.0 4891.4 4924.5 4936.5 4100.8 4254.5 4480.8 4544.4 4557.5 3656.3 3659.7 3758.8 3762.9 3763.4 1056.0 1214.9 1236.2 1245.1 1248.8 702.9 765.3 866.1 877.6 887.8 5380.0 5419.8 5505.5 5529.7 5570.2 438.4 443.3 451.8 456.3 459.1 3698.2 3739.8 3753.2 3807.4 3821.5 9251.4 9494.7 9586.4 9599.0 9619.2 9580.7 9581.7 9618.1 9621.7 9656.9 5732.3 7065.9 7361.5 7483.6 7582.5 4652.9 5217.4 5361.8 5469.5 5502.4 8680.0 9061.3 9067.8 9085.0 9093.4 7190.6 8884.2 9101.0 9193.9 9231.5 6312.2 6507.0 6719.3 6870.0 6937.1 7451.5 7647.1 7651.6 7679.7 7695.5 6723.3 6823.4 6830.0 6877.6 6975.5 6664.5 6701.4 6707.4 6730.3 6765.6 6374.9 6375.3 6403.3 6452.7 6474.7 4006.3 4318.7 4678.3 4712.1 4812.0 4663.3 4756.8 4758.0 4805.3 4924.9 09:02:25 INFO - PROCESS | 1636 | 09:02:25 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4440.26 Median:4876.98 stddev:1378.86 (28.3%) stddev-sans-first:1346.50 09:02:25 INFO - PROCESS | 1636 | Values: 256.7 301.2 308.3 311.0 312.0 4024.0 4332.8 4583.4 5124.8 5308.5 4328.7 5007.8 5079.3 5277.0 5292.2 3409.7 3654.2 3861.0 4089.4 4115.6 3584.5 4056.7 4081.9 4092.7 4116.9 3080.3 3333.6 3813.2 4052.7 4063.7 3375.4 4030.9 4034.5 4048.7 4081.3 3097.9 3209.4 3485.8 3555.8 3591.6 4658.6 4679.8 5063.7 5103.5 5164.7 4051.0 4621.2 4656.7 4658.8 4691.7 4427.7 5561.3 5576.9 5577.8 5583.9 3535.3 3594.0 3651.6 3817.5 3967.5 5462.9 5807.5 5854.2 5858.6 5881.6 3089.5 3576.5 3970.7 3999.7 4022.4 3575.3 4021.5 4026.9 4029.8 4035.7 4452.2 4558.2 4579.4 4581.4 4625.9 4876.3 4877.7 5037.7 5581.8 5582.1 1185.6 1220.6 1260.2 1289.7 1317.6 875.5 912.4 912.8 916.7 923.2 4473.8 4513.7 4521.4 4762.4 5009.8 4497.8 5093.0 5451.5 5491.0 5508.7 4652.2 4920.5 5004.2 5009.5 5053.6 2551.8 2651.8 2863.4 2875.4 2882.8 4447.2 5255.5 5255.6 5583.3 5627.2 4563.5 4611.0 5456.3 5559.9 5602.5 5284.0 5579.6 5607.6 5611.9 5647.9 4922.3 5065.8 5422.4 5603.0 5884.4 4860.9 5847.3 5849.7 5855.4 5891.6 5822.6 5851.6 6113.4 6119.6 6123.2 3814.4 3881.5 3884.6 3895.2 3912.3 5786.5 5847.2 5866.6 5886.3 5902.0 4476.3 5542.4 5686.3 5734.4 5751.1 4790.6 5582.5 5625.6 5653.3 5663.2 4650.9 5177.0 5313.5 5452.5 5553.0 5091.7 5527.1 5634.3 5688.4 5716.6 4965.6 5055.9 5186.9 5537.8 5609.4 09:02:25 INFO - PROCESS | 1636 | 09:02:25 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:20939.39 Median:2544.75 stddev:31523.64 (1238.8%) stddev-sans-first:31575.35 09:02:25 INFO - PROCESS | 1636 | Values: 676.4 732.7 738.6 752.3 810.9 14124.1 14287.8 14625.3 14666.6 15544.9 11679.2 13827.5 14176.7 14177.4 14222.6 841.8 865.2 870.9 874.0 876.4 673.7 695.2 836.4 858.8 872.8 910.4 915.0 917.3 919.2 921.7 801.2 811.2 830.1 895.5 910.9 661.2 665.5 677.1 681.9 682.5 12318.9 14491.3 14692.2 14716.3 14717.9 11660.3 13825.4 14022.6 14203.4 14224.7 9888.5 9906.5 9943.9 9954.6 10199.7 3660.0 3693.3 3694.7 3699.0 3710.0 92875.6 109235.5 110420.0 111776.0 112910.4 10774.5 10863.5 10961.7 10963.1 11265.2 22276.0 22505.7 22541.3 22551.7 22976.2 6883.1 7287.6 7592.9 7621.1 7666.0 4110.8 4150.0 4278.4 4286.3 4296.9 3379.8 3460.3 3475.7 3478.7 3505.6 602.9 611.9 618.6 621.7 626.0 545.6 550.6 555.4 590.4 636.2 8768.4 8854.7 9140.5 9216.9 9417.4 511.8 622.7 654.8 658.4 662.4 3800.6 4490.6 4506.3 4517.6 4577.7 105722.0 108752.0 113414.0 113888.0 114246.4 95333.5 97340.0 97777.5 98102.0 98128.0 80316.6 81996.6 82952.6 82967.6 83562.0 8729.3 8865.5 8868.2 8876.6 8959.8 51732.5 52472.5 52832.0 53116.5 53366.0 59079.7 77187.2 77729.4 78956.6 79077.0 11391.5 11965.1 11996.2 12008.0 12089.6 12466.8 12781.9 12824.9 12975.0 13139.0 12006.6 12142.8 12289.0 12607.7 12638.7 11995.1 12091.6 12137.2 12137.3 12546.1 11908.7 11953.8 12002.5 12115.6 12409.6 8551.5 9463.7 9875.2 10043.5 10581.5 8790.7 10077.9 10388.5 10392.9 10496.0 09:02:25 INFO - PROCESS | 1636 | -------- Summary: end -------- 09:02:25 INFO - PROCESS | 1636 | 09:02:26 INFO - TEST-INFO | 1636: exit 0 09:02:26 INFO - TEST-OK | dromaeo_css | took 1116383ms 09:02:26 INFO - TEST-START | kraken 09:02:26 INFO - operating with platform_type : mac_ 09:02:26 INFO - Initialising browser for kraken test... 09:02:26 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmpYZQvCl/profile http://localhost:49237/getInfo.html) 09:02:29 INFO - PROCESS | 1647 | 2016-11-28 09:02:29.660 plugin-container[1648:9970] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 09:02:29 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:02:29 INFO - PROCESS | 1647 | 2016-11-28 09:02:29.663 plugin-container[1648:9970] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 09:02:29 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:02:29 INFO - PROCESS | 1647 | 2016-11-28 09:02:29.663 plugin-container[1648:9970] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:02:29 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 09:02:29 INFO - PROCESS | 1647 | colorDepth:24 09:02:29 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 09:02:29 INFO - PROCESS | 1647 | __metrics 09:02:29 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 09:02:30 INFO - TEST-INFO | 1647: exit 0 09:02:30 INFO - Browser initialized. 09:02:30 INFO - Running cycle 1/1 for kraken test... 09:02:30 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmpYZQvCl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:02:33 INFO - PROCESS | 1649 | 2016-11-28 09:02:33.428 plugin-container[1650:10087] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443b, name = 'com.apple.tsm.portname' 09:02:33 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:02:33 INFO - PROCESS | 1649 | 2016-11-28 09:02:33.429 plugin-container[1650:10087] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.CFPasteboardClient' 09:02:33 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:02:33 INFO - PROCESS | 1649 | 2016-11-28 09:02:33.429 plugin-container[1650:10087] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:02:43 INFO - PROCESS | 1649 | RSS: Main: 242896896 09:02:43 INFO - PROCESS | 1649 | 09:02:46 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection) 09:02:47 INFO - PROCESS | 1649 | RSS: Main: 254926848 09:02:47 INFO - PROCESS | 1649 | 09:02:49 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-dft) 09:02:50 INFO - PROCESS | 1649 | RSS: Main: 262557696 09:02:50 INFO - PROCESS | 1649 | 09:02:53 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-fft) 09:02:54 INFO - PROCESS | 1649 | RSS: Main: 265211904 09:02:54 INFO - PROCESS | 1649 | 09:02:56 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator) 09:02:56 INFO - PROCESS | 1649 | RSS: Main: 267452416 09:02:56 INFO - PROCESS | 1649 | 09:02:58 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:02:59 INFO - PROCESS | 1649 | RSS: Main: 268111872 09:02:59 INFO - PROCESS | 1649 | 09:03:02 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom) 09:03:02 INFO - PROCESS | 1649 | RSS: Main: 274489344 09:03:02 INFO - PROCESS | 1649 | 09:03:05 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate) 09:03:06 INFO - PROCESS | 1649 | RSS: Main: 273403904 09:03:06 INFO - PROCESS | 1649 | 09:03:09 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial) 09:03:10 INFO - PROCESS | 1649 | RSS: Main: 273534976 09:03:10 INFO - PROCESS | 1649 | 09:03:11 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:03:12 INFO - PROCESS | 1649 | RSS: Main: 272588800 09:03:12 INFO - PROCESS | 1649 | 09:03:14 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:03:15 INFO - PROCESS | 1649 | RSS: Main: 272695296 09:03:15 INFO - PROCESS | 1649 | 09:03:17 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:03:17 INFO - PROCESS | 1649 | RSS: Main: 272691200 09:03:17 INFO - PROCESS | 1649 | 09:03:20 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:03:21 INFO - PROCESS | 1649 | RSS: Main: 272973824 09:03:21 INFO - PROCESS | 1649 | 09:03:24 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:03:25 INFO - PROCESS | 1649 | RSS: Main: 273080320 09:03:25 INFO - PROCESS | 1649 | 09:03:27 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49237/tests/kraken/driver.html?testName=ai-astar) 09:03:27 INFO - PROCESS | 1649 | RSS: Main: 274960384 09:03:27 INFO - PROCESS | 1649 | 09:03:27 INFO - PROCESS | 1649 | __start_tp_report 09:03:27 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 09:03:27 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 09:03:27 INFO - PROCESS | 1649 | |i|pagename|runs| 09:03:27 INFO - PROCESS | 1649 | |0;ai-astar;104;118;125;119;117;118;120;119;119;119 09:03:27 INFO - PROCESS | 1649 | |1;audio-beat-detection;151;125;117;150;117;117;124;148;170;119 09:03:27 INFO - PROCESS | 1649 | |2;audio-dft;205;213;201;197;195;192;199;195;199;197 09:03:27 INFO - PROCESS | 1649 | |3;audio-fft;78;59;58;83;57;56;57;58;72;59 09:03:27 INFO - PROCESS | 1649 | |4;audio-oscillator;88;71;82;72;69;82;83;69;71;84 09:03:27 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;90;87;88;98;91;75;130;125;89;85 09:03:27 INFO - PROCESS | 1649 | |6;imaging-darkroom;94;121;97;95;132;95;95;116;153;146 09:03:27 INFO - PROCESS | 1649 | |7;imaging-desaturate;93;113;128;124;90;90;98;147;149;93 09:03:27 INFO - PROCESS | 1649 | |8;json-parse-financial;58;57;56;58;58;59;56;60;57;57 09:03:27 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;68;74;77;69;62;93;89;72;74;71 09:03:27 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;89;105;119;110;109;87;87;87;87;91 09:03:27 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;151;150;152;148;147;152;136;163;188;161 09:03:27 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;173;172;174;171;179;179;177;177;174;172 09:03:27 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;70;71;72;72;71;71;75;84;89;88 09:03:27 INFO - PROCESS | 1649 | __end_tp_report 09:03:27 INFO - PROCESS | 1649 | __start_cc_report 09:03:27 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,941 09:03:27 INFO - PROCESS | 1649 | __end_cc_report 09:03:27 INFO - PROCESS | 1649 | __startTimestamp1480352607299__endTimestamp 09:03:27 INFO - PROCESS | 1649 | 09:03:27 INFO - PROCESS | 1649 | ------- Summary: start ------- 09:03:27 INFO - PROCESS | 1649 | Number of tests: 14 09:03:27 INFO - PROCESS | 1649 | 09:03:27 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:117.80 Median:119.00 stddev:5.31 (4.5%) stddev-sans-first:2.29 09:03:27 INFO - PROCESS | 1649 | Values: 104.0 118.0 125.0 119.0 117.0 118.0 120.0 119.0 119.0 119.0 09:03:27 INFO - PROCESS | 1649 | 09:03:27 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:133.80 Median:136.50 stddev:19.18 (14.0%) stddev-sans-first:19.30 09:03:27 INFO - PROCESS | 1649 | Values: 151.0 125.0 117.0 150.0 117.0 117.0 124.0 148.0 170.0 119.0 09:03:27 INFO - PROCESS | 1649 | 09:03:27 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:199.30 Median:199.00 stddev:6.00 (3.0%) stddev-sans-first:6.00 09:03:27 INFO - PROCESS | 1649 | Values: 205.0 213.0 201.0 197.0 195.0 192.0 199.0 195.0 199.0 197.0 09:03:27 INFO - PROCESS | 1649 | 09:03:27 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:63.70 Median:59.00 stddev:10.02 (17.0%) stddev-sans-first:9.20 09:03:27 INFO - PROCESS | 1649 | Values: 78.0 59.0 58.0 83.0 57.0 56.0 57.0 58.0 72.0 59.0 09:03:27 INFO - PROCESS | 1649 | 09:03:27 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:82.00 stddev:7.31 (8.9%) stddev-sans-first:6.60 09:03:27 INFO - PROCESS | 1649 | Values: 88.0 71.0 82.0 72.0 69.0 82.0 83.0 69.0 71.0 84.0 09:03:27 INFO - PROCESS | 1649 | 09:03:27 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:95.80 Median:88.50 stddev:17.69 (20.0%) stddev-sans-first:18.64 09:03:27 INFO - PROCESS | 1649 | Values: 90.0 87.0 88.0 98.0 91.0 75.0 130.0 125.0 89.0 85.0 09:03:27 INFO - PROCESS | 1649 | 09:03:27 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:114.40 Median:94.50 stddev:22.83 (24.2%) stddev-sans-first:22.99 09:03:27 INFO - PROCESS | 1649 | Values: 94.0 121.0 97.0 95.0 132.0 95.0 95.0 116.0 153.0 146.0 09:03:27 INFO - PROCESS | 1649 | 09:03:27 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:112.50 Median:90.00 stddev:23.28 (25.9%) stddev-sans-first:23.60 09:03:27 INFO - PROCESS | 1649 | Values: 93.0 113.0 128.0 124.0 90.0 90.0 98.0 147.0 149.0 93.0 09:03:27 INFO - PROCESS | 1649 | 09:03:27 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:58.00 stddev:1.26 (2.2%) stddev-sans-first:1.33 09:03:27 INFO - PROCESS | 1649 | Values: 58.0 57.0 56.0 58.0 58.0 59.0 56.0 60.0 57.0 57.0 09:03:27 INFO - PROCESS | 1649 | 09:03:27 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:74.90 Median:74.00 stddev:9.46 (12.8%) stddev-sans-first:9.70 09:03:27 INFO - PROCESS | 1649 | Values: 68.0 74.0 77.0 69.0 62.0 93.0 89.0 72.0 74.0 71.0 09:03:27 INFO - PROCESS | 1649 | 09:03:27 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:97.10 Median:87.00 stddev:12.30 (14.1%) stddev-sans-first:12.69 09:03:27 INFO - PROCESS | 1649 | Values: 89.0 105.0 119.0 110.0 109.0 87.0 87.0 87.0 87.0 91.0 09:03:27 INFO - PROCESS | 1649 | 09:03:27 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:154.80 Median:152.00 stddev:13.83 (9.1%) stddev-sans-first:14.60 09:03:27 INFO - PROCESS | 1649 | Values: 151.0 150.0 152.0 148.0 147.0 152.0 136.0 163.0 188.0 161.0 09:03:27 INFO - PROCESS | 1649 | 09:03:27 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.80 Median:175.50 stddev:2.97 (1.7%) stddev-sans-first:3.08 09:03:27 INFO - PROCESS | 1649 | Values: 173.0 172.0 174.0 171.0 179.0 179.0 177.0 177.0 174.0 172.0 09:03:27 INFO - PROCESS | 1649 | 09:03:27 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.30 Median:73.50 stddev:7.60 (10.3%) stddev-sans-first:7.71 09:03:27 INFO - PROCESS | 1649 | Values: 70.0 71.0 72.0 72.0 71.0 71.0 75.0 84.0 89.0 88.0 09:03:27 INFO - PROCESS | 1649 | -------- Summary: end -------- 09:03:27 INFO - PROCESS | 1649 | 09:03:28 INFO - TEST-INFO | 1649: exit 0 09:03:28 INFO - TEST-OK | kraken | took 61908ms 09:03:28 INFO - SUITE-END | took 1178s 09:03:28 INFO - Completed test suite (00:19:38) 09:03:28 INFO - javascript benchmark 09:03:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7173.987314329051, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3053.0840747775665, 3285.211549228186, 3301.950470742939, 3332.450145725954, 3344.347852168827, 4565.406497155365, 5090.109230884599, 5516.448355164482, 5577.358603760564, 5601.60788744788, 4277.508086570046, 5425.945740542594, 5462.371827239868, 5495.862603434916, 5519.889602207953, 5030.949690503094, 5089.109405853979, 5103.642745007851, 5148.819791307305, 5149.6910185389, 5085.847424577269, 5128.410232823236, 5148.021875843597, 5156.974215128937, 5189.896202075956, 5138.254953031808, 5140.0, 5150.356205474316, 5152.536271735543, 5152.562032227265, 4014.3978403239457, 4234.237837189305, 4800.975995120037, 5109.0, 5166.715830629314, 4167.499900011988, 5081.974590127025, 5096.694198348074, 5098.694078355311, 5106.719130447824, 5463.945360546423, 5568.0, 5652.830415087513, 5663.943360566383, 5706.28671416073, 5894.941050589482, 6068.848278793022, 6075.149479072933, 6081.270247570275, 6103.755849766048, 5776.902388546205, 6038.305594856603, 6048.304444988838, 6099.207103076616, 6133.84665383369, 5656.0, 5664.915026274609, 5678.14827775837, 5687.857803554903, 5692.829215123512, 6164.352742962014, 6174.073888916699, 6174.351693072253, 6178.7219575119225, 6197.0, 5790.826275211751, 5852.385499522574, 5854.356020837705, 5954.136650185786, 5987.760489580455, 5982.611130276518, 6011.549133814981, 6062.0, 6083.878322433527, 6097.207363042776, 6009.188759517497, 6063.878722425527, 6067.848303792352, 6075.27096748393, 6130.111133885562, 6024.668643224665, 6080.422359875804, 6086.2087928569, 6096.72564734588, 6104.328523862371, 4712.693674911188, 4757.881052973703, 4793.424789025339, 4820.542048505386, 4833.226683730586, 4285.550017248206, 4350.6736994725525, 4357.869263922087, 4384.8903777405185, 4392.934105988413, 5503.862403439946, 5714.257275948517, 5722.856928576735, 5740.942590574125, 5766.596338256281, 6011.6994150293185, 6071.51427885768, 6087.239095113111, 6159.19930409044, 6174.38256174388, 5651.491365777018, 5674.2339784129435, 5688.317401911797, 5692.48767610917, 5700.00249956256, 5233.188855727292, 5235.685858848481, 5245.580353571705, 5296.708681022504, 5298.655587386807, 5492.313460817398, 6195.349488303754, 6199.628022318675, 6215.471684906653, 6236.594621349779, 6074.635521868702, 6127.20346354971, 6133.417325354084, 6139.9079013813935, 6148.4773794227995, 6092.147099406176, 6106.673025714385, 6118.223266370627, 6145.477634401126, 6188.938110618837, 5991.7603295867675, 6000.0999850022845, 6020.217371741645, 6112.777056696867, 6122.571420000557, 6077.604955677867, 6132.693365331805, 6144.2319710036245, 6166.81499555014, 6181.041938499539, 5750.424957504216, 5988.820335389945, 5994.220751302303, 6019.789307374396, 6052.848678783065, 5869.765209391577, 5895.7052147391605, 5915.852103697442, 5945.940540594539, 6042.0332746760305, 4932.260160975882, 5761.567882408753, 5844.795432159854, 5874.911876321773, 5887.970560147171, 5953.374895635896, 5988.431099045583, 5989.251343582052, 6033.75864965414, 6036.154938308552, 5396.190571414162, 5625.718714064198, 5699.2590963174525, 5720.141978703062, 5808.796692115925], "value": 5530.95810044063, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.8129657488718, 475.7502311286575, 480.8317089018844, 493.50402845140604, 516.9634882061466, 33079.50380744291, 39617.20765584687, 39708.801455992754, 39760.204795904065, 39930.60069399305, 33195.83402082992, 34283.0, 40742.0, 41203.0, 41249.0, 8360.916390836104, 8620.525871077089, 9230.446173229611, 9407.247420206368, 9428.434293942351, 10548.630797922075, 10563.524641391155, 10986.725331866728, 11321.150913681508, 11468.71328216793, 11304.491297891615, 11361.602343917964, 11373.658790236306, 11382.772344553105, 11419.143564232674, 11238.887611123906, 11258.324500529994, 11269.0, 11287.98408143271, 11292.096632269398, 10989.450527473595, 11033.724156896062, 11122.0, 11166.939140781613, 11178.552857885676, 38863.80568097141, 38865.22269554621, 39066.218675626325, 39083.60916390828, 39227.01932451683, 3338.88313909012, 3351.3967485852536, 3452.5684289463816, 3552.182997910495, 3598.748087633867, 29237.853810731023, 29406.264843378875, 29472.11583652494, 29577.40845183106, 29812.254693632614, 3188.952165717516, 3199.152224660467, 3199.2001999500126, 3233.9651311580305, 3265.2653153040615, 474655.0, 485857.0, 488092.0, 488752.5562372165, 490197.0, 56816.0, 56881.862362752516, 56979.715101425056, 57105.714471427375, 57169.42830571724, 57812.71093644589, 58870.11694824581, 59046.40953590495, 59205.40794592085, 59211.11183332253, 14975.176365300013, 15440.227988600525, 15445.227738613024, 15454.845451545565, 15501.612459688373, 9245.306602004877, 10821.783564328669, 11116.499757510932, 12037.87962120385, 12057.57798477049, 15222.695546089017, 15243.0, 15257.465988690343, 15270.38918443272, 15313.0, 6248.750049997949, 6332.873342533124, 6335.239771227482, 6373.426391624684, 6375.171227740364, 847.4322204123253, 980.6077568972468, 987.2151639446657, 1024.2267088348249, 1093.1418836213534, 29154.416911661647, 29226.41547169045, 29559.556606650916, 29594.704052959194, 29907.850460747555, 979.951002449889, 1012.2964539645023, 1069.5294070608907, 1081.0486771640906, 1088.6026600290995, 12799.65603611626, 12863.22820630765, 14655.340509676877, 14663.780043299359, 15181.468648597245, 551459.0, 641974.0, 652370.0, 659544.0, 659960.0, 55583.88832223333, 55611.44388556063, 55625.721871390386, 55911.44088559144, 56209.437905621235, 1295.8898493627958, 1327.932475980488, 1337.5452346202337, 1347.0570600579633, 1351.9391627376988, 27755.167344978876, 28098.157055288375, 29052.854735726185, 29270.853645732488, 29522.557161642162, 44659.43691970765, 49870.75064624654, 54182.916341673736, 54828.0, 57679.711601441726, 508509.0, 508908.0, 509424.0, 509754.0, 512390.0, 20265.088071037135, 21178.47053823605, 24104.156354527506, 24712.0, 25770.711464427077, 37062.0, 44750.78376126073, 45093.32360014537, 45345.77327113343, 45704.77147614241, 37416.0, 37713.43429848499, 37881.0, 38257.80871095627, 38389.80805095957, 32721.181970449976, 34100.65899340975, 37079.443808343436, 37084.2915708427, 38117.42823857589, 36232.63767362293, 36791.08022299464, 37394.43908341431, 37397.81301093477, 37472.812635937735, 29664.0, 32407.51388729218, 33422.4959876811, 33552.832235838665, 33566.0, 20893.89553052225, 24307.0, 24346.0, 24782.752172478766, 24866.75133248717], "value": 19349.867261454645, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.90485949355696, 365.3113880335567, 367.5699431664952, 380.1617433559003, 397.85417996171043, 17779.0, 18271.45185644431, 19353.806461935375, 19487.707684384746, 19556.315528956453, 20922.26772062978, 21274.0, 21444.4638884028, 21530.354089377306, 21774.911254437295, 5039.899202015967, 6472.126262954488, 6737.595744255336, 6755.121834161552, 6818.488613353993, 6509.1863517060365, 6694.966525167391, 6698.3971442570155, 6713.26154123046, 6713.697883595226, 6521.08704781331, 6657.068010478537, 6668.333166683317, 6670.81828372151, 6695.732170713166, 6355.173827402408, 6539.08452816606, 6599.835004124887, 6610.636414997173, 6622.0, 6464.224293084813, 6492.967535162341, 6505.739770409178, 6513.511486638521, 6533.085368048477, 22232.333030009126, 27169.0, 27532.862335688194, 27947.441051178863, 28030.57954130669, 1840.4202676156967, 2064.23623259395, 2440.6583078369044, 2453.0, 2749.4913441013477, 13604.455821767118, 15752.739780817506, 16261.105639189958, 16429.342826286935, 16642.417515387024, 1695.6184858406882, 1929.0740444586677, 1953.540917884296, 1957.5204075001573, 1980.9405717828345, 98304.0, 128945.0, 131344.0, 131756.0, 133813.0, 17214.569635759206, 19870.70193947092, 21218.469538261666, 21223.575528489342, 21484.892575537335, 30700.692993069784, 30973.0, 34140.4636791345, 34186.48720269198, 34193.0, 9956.253281003954, 10132.392056476636, 10306.175505959505, 10339.896601033894, 10398.636047738293, 6892.931070689329, 7638.50349727266, 8412.284955778705, 8458.0, 8484.830303393897, 9323.393979391318, 9583.377080489747, 9615.8845054088, 9658.227341812639, 9699.854502182472, 3706.184639379332, 3765.491658626105, 3770.831042376872, 3785.7885476647757, 3919.392494163409, 808.9433739638287, 810.1129263456505, 832.0722394530177, 852.9573521324033, 909.88171537701, 18057.458276251735, 20131.49671258201, 20225.797742022685, 22222.555548889253, 22247.555048898932, 781.417843706431, 834.7287131682227, 889.3063410539789, 907.491804589432, 958.4249450329746, 10218.846717299246, 10490.842637360445, 10862.368905015192, 10935.28920620173, 10999.945000274947, 549774.0, 585972.0, 603412.0, 604490.0, 615976.0, 132928.0, 139248.0, 140554.0, 141601.0, 142813.0, 933.6918816790443, 957.2389949989774, 996.3872218585662, 1000.2548101664306, 1007.8236308645958, 14420.42318307256, 16326.0, 18639.068046597346, 18761.90619046896, 19160.425187244404, 54405.45594544004, 54874.451255486936, 55561.0, 56424.0, 56537.0, 76933.0, 77337.226627733, 77625.61187194027, 78932.60533697295, 79490.20509794827, 6963.09479767627, 7313.317936874655, 7690.846183076195, 8044.436889417684, 8214.58927053633, 11376.772464550497, 12470.0, 12511.874741988548, 12742.808857867325, 12838.358082096045, 8346.0, 10715.196360272856, 10888.727781805519, 10928.672139835817, 11053.28153670009, 10684.83972740425, 10798.460076996276, 10807.35155890649, 10845.240833141605, 11093.944530277297, 10862.185336099667, 11107.83338249943, 11281.492332845206, 11301.434928253719, 11622.88377116218, 6939.826504337432, 7586.734464293723, 7980.281774640078, 8103.027636683636, 8155.918440815516, 9172.587233574639, 9367.344285899922, 10869.728256793644, 11122.054625356936, 11176.664700059011], "value": 10580.641226062451, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1384.993075034625, 1454.5636309107265, 1635.0189886068354, 1792.3726695656514, 1809.918553665085, 7265.49141560091, 7472.327490525851, 7749.496282741625, 8106.554139522331, 8112.391570632204, 7627.275408836152, 7945.562994035326, 7946.443748937577, 7953.284204421601, 7961.4825036372595, 2224.5439684864564, 2282.4408020035116, 2283.098176220392, 2287.531056133497, 2289.461976435536, 2269.614165591853, 2291.3813270416977, 2304.8847557622134, 2305.0203663443053, 2308.1113771198125, 2242.7532971373134, 2254.5829021631052, 2269.9886500567472, 2275.101334972685, 2284.1776960294364, 2234.698315727372, 2238.7201599800023, 2257.9435514112115, 2260.92086776962, 2264.4904896398343, 2261.9934129312464, 2285.565742508918, 2285.5885940530698, 2294.6328587425933, 2302.2747834432103, 7943.841123177562, 7998.12020677725, 8031.156728543478, 8050.11448740638, 8053.4765240259785, 4935.950640493585, 4981.452040275567, 5026.070177017264, 5026.8240611578785, 5062.898742025139, 2865.3839424523826, 3141.9685803142133, 3184.9203769905707, 3391.915202119942, 3403.6085850127306, 1026.7946410717811, 1068.4474123539262, 1073.5920350266927, 1091.6397588795758, 1092.9781404371947, 10049.196064314838, 10196.796064078751, 10210.795784084425, 10322.277440579162, 10323.535440905178, 4161.292580261363, 4313.288307429302, 4392.802323895432, 4476.731396116244, 4488.730676159375, 8934.553272336487, 8987.056359082202, 8994.775130621787, 8998.955005225063, 9025.81948361029, 4341.806003349041, 4713.033828065239, 4891.364122664031, 4924.482929292372, 4936.48166942473, 4100.835966561304, 4254.510731265882, 4480.753558554311, 4544.409226800561, 4557.521460246627, 3656.2687462507606, 3659.652333028358, 3758.7971849008054, 3762.9087564606507, 3763.379042457964, 1055.9524821382902, 1214.9271043737403, 1236.202649291202, 1245.1159676629513, 1248.76273508035, 702.929707029293, 765.2500549461558, 866.1165611076666, 877.5656050255164, 887.7558671365426, 5379.973100134553, 5419.83740487786, 5505.504504594605, 5529.668219906738, 5570.164475328733, 438.38407038111507, 443.2641814587833, 451.7876597998935, 456.31324856092033, 459.0818363273453, 3698.223373091627, 3739.7658772604973, 3753.2118255166174, 3807.428885667172, 3821.5032045833864, 9251.352405331696, 9494.715158545254, 9586.37688550256, 9598.95200524007, 9619.18236949853, 9580.66467673628, 9581.664641737505, 9618.086281803218, 9621.711348659552, 9656.855147172799, 5732.3121225453215, 7065.858682826315, 7361.527694461022, 7483.550986940801, 7582.507137036075, 4652.860414187579, 5217.426083130928, 5361.812336568358, 5469.453054694499, 5502.422245664307, 8680.001799792852, 9061.320400969822, 9067.773305667411, 9085.0, 9093.40892841963, 7190.640467976685, 8884.200421962054, 9101.044390338919, 9193.908060919573, 9231.538423078684, 6312.179416675803, 6506.967465162644, 6719.26088130315, 6869.965650171717, 6937.098177236927, 7451.478396512192, 7647.120581133018, 7651.61741912891, 7679.731209407644, 7695.538267703731, 6723.294054124247, 6823.38589526945, 6830.0, 6877.552959057645, 6975.511714179958, 6664.5334826561675, 6701.430378417694, 6707.429868461236, 6730.32696730323, 6765.594064356154, 6374.904376434264, 6375.330590287953, 6403.3276505966205, 6452.709628066655, 6474.70863811139, 4006.258842114218, 4318.719283246579, 4678.345031695498, 4712.117227313911, 4812.037592481588, 4663.300504924288, 4756.8097276108565, 4757.976210119065, 4805.303231031551, 4924.926126108183], "value": 4164.547775394523, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.70607154807743, 301.1777846479112, 308.33399856310376, 310.97356724678406, 311.99688003119974, 4024.0, 4332.826686932519, 4583.449986001685, 5124.820631277906, 5308.469153084689, 4328.718633288834, 5007.774650140744, 5079.2635067915135, 5276.997370499603, 5292.153255479124, 3409.6760807723226, 3654.2326111516613, 3861.015441062525, 4089.427480152781, 4115.609017143382, 3584.534010578634, 4056.655184309337, 4081.9183616327655, 4092.6725861930972, 4116.897077573055, 3080.276135108259, 3333.633300336973, 3813.245906882837, 4052.716309858311, 4063.6952228582827, 3375.443051896434, 4030.8589199378175, 4034.515858097018, 4048.696347773914, 4081.3265811141127, 3097.922551936197, 3209.422303985282, 3485.8322461975017, 3555.839987200582, 3591.617227367456, 4658.627309815241, 4679.836205732783, 5063.721495317756, 5103.489651034867, 5164.690118592896, 4050.9594904050878, 4621.214393553104, 4656.743879086649, 4658.836940707059, 4691.671582989192, 4427.690061695683, 5561.304836895388, 5576.944230557683, 5577.8047768327915, 5583.916241256385, 3535.3459609972238, 3593.9820300898327, 3651.6362946279205, 3817.48463957365, 3967.5238971323624, 5462.918056229159, 5807.477327040544, 5854.238948936653, 5858.589898707092, 5881.617694849864, 3089.536569514546, 3576.5171701819922, 3970.7419017763755, 3999.7400168989507, 4022.365350695299, 3575.284943011356, 4021.5375231848125, 4026.8993275168355, 4029.7985100744845, 4035.6973227008093, 4452.243118669834, 4558.247889098328, 4579.4046773919845, 4581.40441742578, 4625.907481850344, 4876.268559716071, 4877.6829506081995, 5037.697738135724, 5581.804636837691, 5582.051051321285, 1185.6087491127907, 1220.6277085489012, 1260.2249616485979, 1289.7033682253134, 1317.5652034828483, 875.5315905990265, 912.3522299167532, 912.7632058560688, 916.7387294621001, 923.1875949164827, 4473.832723204704, 4513.68404211702, 4521.434820647419, 4762.380890484214, 5009.799608015638, 4497.839498463908, 5093.0, 5451.454854514596, 5491.023231636607, 5508.724563771795, 4652.232151782301, 4920.532549407801, 5004.224345226496, 5009.499050095034, 5053.5742843435355, 2551.821372503907, 2651.7613414792763, 2863.4086152032028, 2875.3961668049524, 2882.8414437206156, 4447.199504089295, 5255.500727430888, 5255.605829562798, 5583.302087239095, 5627.184058311604, 4563.459357938872, 4610.985583171697, 5456.308544351264, 5559.916601250984, 5602.495775380154, 5284.0, 5579.581531385163, 5607.635503692247, 5611.943880561143, 5647.858803529944, 4922.32640902095, 5065.797368105234, 5422.403535611158, 5603.0, 5884.411558844253, 4860.902781944412, 5847.275053859037, 5849.678267695318, 5855.3559108497175, 5891.558133140117, 5822.641830058833, 5851.6112915313515, 6113.449789518964, 6119.571629985859, 6123.173371594956, 3814.3788889722314, 3881.4862203741127, 3884.572697003271, 3895.1820117775583, 3912.256671232457, 5786.479216870333, 5847.210626565529, 5866.6480011199465, 5886.293644762484, 5902.0, 4476.30966210783, 5542.3903370628395, 5686.317641883001, 5734.397888221677, 5751.137329400456, 4790.640701947298, 5582.464822173934, 5625.578081643811, 5653.2795116076895, 5663.1788390682295, 4650.883727906829, 5177.042247184283, 5313.521783039545, 5452.5365343946205, 5553.0, 5091.719955402488, 5527.143292789624, 5634.309707087776, 5688.40271771458, 5716.628419152742, 4965.577925876341, 5055.9494405055475, 5186.9221961669855, 5537.846615038721, 5609.401320623766], "value": 4004.181612504696, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.4250387170905, 732.6702983657356, 738.634375983888, 752.300360664582, 810.8945837041184, 14124.08193467424, 14287.78568321476, 14625.341859616316, 14666.633334166638, 15544.922275388608, 11679.240849344787, 13827.516036938709, 14176.716465670706, 14177.432902683906, 14222.644433889158, 841.8274253777992, 865.208334374047, 870.8867847179889, 873.9519326437044, 876.3996662286354, 673.6934694713893, 695.2456584605684, 836.4186890111375, 858.7938894665267, 872.8385248728973, 910.3854897943894, 915.0392088307284, 917.3486824354716, 919.1911118215994, 921.6728061538158, 801.2028032108061, 811.1563973467617, 830.0786127398583, 895.4895709445618, 910.9407888487227, 661.2461793555351, 665.4609766089483, 677.1062197898775, 681.9386255237052, 682.4642655515446, 12318.938405307916, 14491.275436228252, 14692.191929443876, 14716.264186790617, 14717.852821471755, 11660.30038197711, 13825.446982120704, 14022.649433764136, 14203.360848761831, 14224.715505689932, 9888.505574721306, 9906.455144967023, 9943.93450756636, 9954.639093574198, 10199.705066360084, 3659.9569122799876, 3693.261347730465, 3694.741368104234, 3699.0, 3710.0, 92875.60686589706, 109235.45382273197, 110420.0, 111776.0, 112910.43544782224, 10774.49751544486, 10863.45682715861, 10961.671149865517, 10963.122950164126, 11265.211435199457, 22276.0, 22505.662415063787, 22541.323760287218, 22551.661725074136, 22976.195833146096, 6883.105196324445, 7287.599182044933, 7592.924070759222, 7621.085469743666, 7666.0, 4110.815013324408, 4149.958500415018, 4278.44380230568, 4286.271333873249, 4296.892577685583, 3379.766385269359, 3460.3252365788935, 3475.739319551044, 3478.7390945679176, 3505.631908649606, 602.8553147244629, 611.8776244751068, 618.604093380237, 621.6643012773143, 625.9640295311267, 545.6344249352863, 550.5980634137022, 555.4279092534696, 590.3948452835878, 636.2238069555135, 8768.430052046597, 8854.690085846965, 9140.451572905647, 9216.861747073799, 9417.387869788441, 511.8285374399618, 622.7260005597523, 654.7642848574509, 658.3876994395258, 662.3906006474056, 3800.56293526248, 4490.618297444754, 4506.3465797459185, 4517.593416592523, 4577.679562430664, 105722.0, 108752.0, 113414.0, 113888.0, 114246.42876785563, 95333.52333238289, 97340.0, 97777.51111244399, 98102.0, 98128.0, 80316.59841700754, 81996.59001705072, 82952.58523707463, 82967.585162075, 83562.0, 8729.25801306896, 8865.512396818236, 8868.185471992909, 8876.556172191495, 8959.776005599912, 51732.48267517277, 52472.47527524676, 52832.0, 53116.46883531115, 53366.0, 59079.70460147672, 77187.228127718, 77729.44541109033, 78956.60521697355, 79077.0, 11391.544338226378, 11965.081557517537, 11996.160268781101, 12008.0, 12089.637310880687, 12466.75066498683, 12781.872181278068, 12824.935875320563, 12975.000873048917, 13139.0, 12006.579769708018, 12142.75714485723, 12289.0, 12607.684807879876, 12638.684032898884, 11995.100367472649, 12091.57679481214, 12137.21108127969, 12137.332446715516, 12546.059045571801, 11908.702282442662, 11953.760924781629, 12002.459889304828, 12115.601671253811, 12409.565665201675, 8551.529665868187, 9463.668771593237, 9875.209983201183, 10043.469220029472, 10581.52383142745, 8790.736277911672, 10077.949610252195, 10388.480575971322, 10392.896071039193, 10496.0], "value": 7218.384012013765, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1549.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 118.0, 125.0, 119.0, 117.0, 118.0, 120.0, 119.0, 119.0, 119.0], "value": 117.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 125.0, 117.0, 150.0, 117.0, 117.0, 124.0, 148.0, 170.0, 119.0], "value": 133.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 213.0, 201.0, 197.0, 195.0, 192.0, 199.0, 195.0, 199.0, 197.0], "value": 199.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 58.0, 83.0, 57.0, 56.0, 57.0, 58.0, 72.0, 59.0], "value": 63.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 82.0, 72.0, 69.0, 82.0, 83.0, 69.0, 71.0, 84.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 88.0, 98.0, 91.0, 75.0, 130.0, 125.0, 89.0, 85.0], "value": 95.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 121.0, 97.0, 95.0, 132.0, 95.0, 95.0, 116.0, 153.0, 146.0], "value": 114.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 113.0, 128.0, 124.0, 90.0, 90.0, 98.0, 147.0, 149.0, 93.0], "value": 112.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 56.0, 58.0, 58.0, 59.0, 56.0, 60.0, 57.0, 57.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 77.0, 69.0, 62.0, 93.0, 89.0, 72.0, 74.0, 71.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 105.0, 119.0, 110.0, 109.0, 87.0, 87.0, 87.0, 87.0, 91.0], "value": 97.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 152.0, 148.0, 147.0, 152.0, 136.0, 163.0, 188.0, 161.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 172.0, 174.0, 171.0, 179.0, 179.0, 177.0, 177.0, 174.0, 172.0], "value": 174.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 72.0, 72.0, 71.0, 71.0, 75.0, 84.0, 89.0, 88.0], "value": 76.3, "unit": "score"}]}]} 09:03:28 INFO - Return code: 0 09:03:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:03:28 ERROR - # TBPL SUCCESS # 09:03:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:03:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:03:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:03:28 INFO - 2016-11-28 09:03:28.618 screenresolution[1654:10471] starting screenresolution argv=screenresolution get 09:03:28 INFO - 2016-11-28 09:03:28.620 screenresolution[1654:10471] Display 0: 1600x1200x32@60 09:03:28 INFO - 2016-11-28 09:03:28.626 screenresolution[1655:10474] starting screenresolution argv=screenresolution list 09:03:28 INFO - Available Modes on Display 0 09:03:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:03:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:03:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:03:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:03:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:03:28 INFO - Intel Iris: 09:03:28 INFO - Chipset Model: Intel Iris 09:03:28 INFO - Type: GPU 09:03:28 INFO - Bus: Built-In 09:03:28 INFO - VRAM (Dynamic, Max): 1536 MB 09:03:28 INFO - Vendor: Intel (0x8086) 09:03:28 INFO - Device ID: 0x0a2e 09:03:28 INFO - Revision ID: 0x0009 09:03:28 INFO - Displays: 09:03:28 INFO - Display: 09:03:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:03:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:03:28 INFO - Display Serial Number: 1600x1200 60 09:03:28 INFO - Main Display: Yes 09:03:28 INFO - Mirror: Off 09:03:28 INFO - Online: Yes 09:03:28 INFO - Rotation: Supported 09:03:28 INFO - Return code: 0 09:03:28 INFO - Running post-action listener: _resource_record_post_action 09:03:28 INFO - [mozharness: 2016-11-28 17:03:28.869360Z] Finished run-tests step (success) 09:03:28 INFO - Running post-run listener: _resource_record_post_run 09:03:28 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 38537728; Write bytes: 696874496; Read time: 252; Write time: 2007 09:03:28 INFO - TinderboxPrint: CPU usage
54.7% 09:03:28 INFO - TinderboxPrint: I/O read bytes / time
38,537,728 / 252 09:03:28 INFO - TinderboxPrint: I/O write bytes / time
696,874,496 / 2,007 09:03:28 INFO - TinderboxPrint: CPU idle
2,198.2 (45.3%) 09:03:28 INFO - TinderboxPrint: CPU system
981.8 (20.2%) 09:03:28 INFO - TinderboxPrint: CPU user
1,669.7 (34.4%) 09:03:28 INFO - TinderboxPrint: Swap in / out
422,076,416 / 0 09:03:28 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 384471552; Write bytes: 385746944; Read time: 29333; Write time: 806 09:03:28 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 35318272; Write bytes: 309652992; Read time: 226; Write time: 1195 09:03:29 INFO - Running post-run listener: _upload_blobber_files 09:03:29 INFO - Blob upload gear active. 09:03:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:03:29 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:03:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:03:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:03:29 INFO - (blobuploader) - INFO - Open directory for files ... 09:03:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:03:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:03:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:03:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:03:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:03:31 INFO - (blobuploader) - INFO - Done attempting. 09:03:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:03:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:03:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:03:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:03:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:03:32 INFO - (blobuploader) - INFO - Done attempting. 09:03:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:03:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:03:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:03:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:03:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:03:34 INFO - (blobuploader) - INFO - Done attempting. 09:03:34 INFO - (blobuploader) - INFO - Iteration through files over. 09:03:34 INFO - Return code: 0 09:03:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:03:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:03:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/982e8986661dee72d594e5abc3a8e9747759040a702a1845721f15eff82cb1571c03b00a2ed42e89bd5a9fdb0e51a6fbd2eea48d861acef88f3d294628d2ea91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1147a8f90f83af664a607cc4b79f797f25eb6af26a61856500c201e10cae2722cf8c0b69d4c9b132ad1e0e4ecd69ca6bbdd631b6d418dd0e171175e8a64e28f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55db4bcd78b857b094395bfd3ddd034e6d2fb83748ccd44d6dbd4a74549c4542c832f87586b98441fa1832d90973562b6c6fcc20c87f8a5545b1ef6a9c4b5666"} 09:03:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:03:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:03:34 INFO - Contents: 09:03:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/982e8986661dee72d594e5abc3a8e9747759040a702a1845721f15eff82cb1571c03b00a2ed42e89bd5a9fdb0e51a6fbd2eea48d861acef88f3d294628d2ea91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1147a8f90f83af664a607cc4b79f797f25eb6af26a61856500c201e10cae2722cf8c0b69d4c9b132ad1e0e4ecd69ca6bbdd631b6d418dd0e171175e8a64e28f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55db4bcd78b857b094395bfd3ddd034e6d2fb83748ccd44d6dbd4a74549c4542c832f87586b98441fa1832d90973562b6c6fcc20c87f8a5545b1ef6a9c4b5666"} 09:03:34 INFO - Running post-run listener: copy_logs_to_upload_dir 09:03:34 INFO - Copying logs to upload dir... 09:03:34 INFO - mkdir: /builds/slave/test/build/upload/logs 09:03:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.319811 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-28 09:03:34.722878) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-28 09:03:34.726367) ========= 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.KYaAbAsJYf/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.a0YsBKrGIi/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/982e8986661dee72d594e5abc3a8e9747759040a702a1845721f15eff82cb1571c03b00a2ed42e89bd5a9fdb0e51a6fbd2eea48d861acef88f3d294628d2ea91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1147a8f90f83af664a607cc4b79f797f25eb6af26a61856500c201e10cae2722cf8c0b69d4c9b132ad1e0e4ecd69ca6bbdd631b6d418dd0e171175e8a64e28f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55db4bcd78b857b094395bfd3ddd034e6d2fb83748ccd44d6dbd4a74549c4542c832f87586b98441fa1832d90973562b6c6fcc20c87f8a5545b1ef6a9c4b5666"} build_url:https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011153 build_url: 'https://queue.taskcluster.net/v1/task/OFE6JImoTVuBURdHd91GZA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/982e8986661dee72d594e5abc3a8e9747759040a702a1845721f15eff82cb1571c03b00a2ed42e89bd5a9fdb0e51a6fbd2eea48d861acef88f3d294628d2ea91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1147a8f90f83af664a607cc4b79f797f25eb6af26a61856500c201e10cae2722cf8c0b69d4c9b132ad1e0e4ecd69ca6bbdd631b6d418dd0e171175e8a64e28f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55db4bcd78b857b094395bfd3ddd034e6d2fb83748ccd44d6dbd4a74549c4542c832f87586b98441fa1832d90973562b6c6fcc20c87f8a5545b1ef6a9c4b5666"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-28 09:03:34.760778) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 09:03:34.761120) ========= 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.KYaAbAsJYf/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.a0YsBKrGIi/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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.005268 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 09:03:34.809888) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-28 09:03:34.810258) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-28 09:03:34.810567) ========= ========= Total master_lag: 0.18 =========