builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0109 starttime: 1483980526.66 results: success (0) buildid: 20170109063808 builduid: a7a9b49f365d4acaa2c116a54b2d1900 revision: bb6a56511146b31c57a2a3c792ccd464891b0593 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:46.661098) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:46.661566) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:46.661854) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:46.692689) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:46.693081) ========= 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.fsgAFdmdYk/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.AKvXAJuBfc/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 --2017-01-09 08:48:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 142M=0s 2017-01-09 08:48:47 (142 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.536712 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:47.254283) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:47.254624) ========= 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.fsgAFdmdYk/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.AKvXAJuBfc/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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.026622 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:47.303695) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 08:48:47.304084) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bb6a56511146b31c57a2a3c792ccd464891b0593 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bb6a56511146b31c57a2a3c792ccd464891b0593 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fsgAFdmdYk/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.AKvXAJuBfc/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 2017-01-09 08:48:47,396 truncating revision to first 12 chars 2017-01-09 08:48:47,396 Setting DEBUG logging. 2017-01-09 08:48:47,396 attempt 1/10 2017-01-09 08:48:47,396 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/bb6a56511146?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-09 08:48:48,416 unpacking tar archive at: mozilla-aurora-bb6a56511146/testing/mozharness/ program finished with exit code 0 elapsedTime=1.293147 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 08:48:48.620809) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:48.621132) ========= script_repo_revision: bb6a56511146b31c57a2a3c792ccd464891b0593 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:48.621507) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:48.621784) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:48.642512) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-09 08:48:48.642978) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fsgAFdmdYk/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.AKvXAJuBfc/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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:48:48 INFO - MultiFileLogger online at 20170109 08:48:48 in /builds/slave/test 08:48:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:48:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:48:48 INFO - {'append_to_log': False, 08:48:48 INFO - 'base_work_dir': '/builds/slave/test', 08:48:48 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:48:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:48:48 INFO - 'branch': 'Mozilla-Aurora', 08:48:48 INFO - 'buildbot_json_path': 'buildprops.json', 08:48:48 INFO - 'config_files': ('talos/mac_config.py',), 08:48:48 INFO - 'default_actions': ('clobber', 08:48:48 INFO - 'read-buildbot-config', 08:48:48 INFO - 'download-and-extract', 08:48:48 INFO - 'populate-webroot', 08:48:48 INFO - 'create-virtualenv', 08:48:48 INFO - 'install', 08:48:48 INFO - 'run-tests'), 08:48:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:48:48 INFO - 'download_minidump_stackwalk': True, 08:48:48 INFO - 'download_symbols': 'ondemand', 08:48:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:48:48 INFO - 'tooltool.py': '/tools/tooltool.py', 08:48:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:48:48 INFO - '/tools/misc-python/virtualenv.py')}, 08:48:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:48:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:48:48 INFO - 'installer_path': 'installer.exe', 08:48:48 INFO - 'log_level': 'info', 08:48:48 INFO - 'log_name': 'talos', 08:48:48 INFO - 'log_to_console': True, 08:48:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:48:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:48:48 INFO - 'opt_config_files': (), 08:48:48 INFO - 'pip_index': False, 08:48:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:48:48 INFO - 'cmd': ('bash', 08:48:48 INFO - '-c', 08:48:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:48:48 INFO - 'enabled': True, 08:48:48 INFO - 'halt_on_failure': False, 08:48:48 INFO - 'name': 'check_screen_resolution'},), 08:48:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:48:48 INFO - 'cmd': ('bash', 08:48:48 INFO - '-c', 08:48:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:48:48 INFO - 'enabled': True, 08:48:48 INFO - 'halt_on_failure': False, 08:48:48 INFO - 'name': 'check_screen_resolution'},), 08:48:48 INFO - 'run_cmd_checks_enabled': True, 08:48:48 INFO - 'sps_profile': False, 08:48:48 INFO - 'sps_profile_interval': 0, 08:48:48 INFO - 'suite': 'dromaeojs-e10s', 08:48:48 INFO - 'system_bits': '32', 08:48:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:48:48 INFO - 'title': 't-yosemite-r7-0109', 08:48:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:48:48 INFO - 'use_talos_json': True, 08:48:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:48:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:48:48 INFO - 'work_dir': 'build'} 08:48:48 INFO - [mozharness: 2017-01-09 16:48:48.847436Z] Running clobber step. 08:48:48 INFO - Running pre-action listener: _resource_record_pre_action 08:48:48 INFO - Running main action method: clobber 08:48:48 INFO - rmtree: /builds/slave/test/build 08:48:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:48:51 INFO - Running post-action listener: _resource_record_post_action 08:48:51 INFO - [mozharness: 2017-01-09 16:48:51.699324Z] Finished clobber step (success) 08:48:51 INFO - [mozharness: 2017-01-09 16:48:51.699521Z] Running read-buildbot-config step. 08:48:51 INFO - Running pre-action listener: _resource_record_pre_action 08:48:51 INFO - Running main action method: read_buildbot_config 08:48:51 INFO - Using buildbot properties: 08:48:51 INFO - { 08:48:51 INFO - "project": "", 08:48:51 INFO - "product": "firefox", 08:48:51 INFO - "script_repo_revision": "production", 08:48:51 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:48:51 INFO - "repository": "", 08:48:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:48:51 INFO - "buildid": "20170109063808", 08:48:51 INFO - "pgo_build": "False", 08:48:51 INFO - "basedir": "/builds/slave/test", 08:48:51 INFO - "buildnumber": 71, 08:48:51 INFO - "slavename": "t-yosemite-r7-0109", 08:48:51 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:48:51 INFO - "platform": "yosemite_r7", 08:48:51 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:48:51 INFO - "branch": "mozilla-aurora", 08:48:51 INFO - "repo_path": "releases/mozilla-aurora", 08:48:51 INFO - "revision": "bb6a56511146b31c57a2a3c792ccd464891b0593", 08:48:51 INFO - "stage_platform": "macosx64", 08:48:51 INFO - "builduid": "a7a9b49f365d4acaa2c116a54b2d1900", 08:48:51 INFO - "slavebuilddir": "test" 08:48:51 INFO - } 08:48:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:48:51 INFO - Running post-action listener: _resource_record_post_action 08:48:51 INFO - [mozharness: 2017-01-09 16:48:51.702967Z] Finished read-buildbot-config step (success) 08:48:51 INFO - [mozharness: 2017-01-09 16:48:51.703086Z] Running download-and-extract step. 08:48:51 INFO - Running pre-action listener: _resource_record_pre_action 08:48:51 INFO - Running main action method: download_and_extract 08:48:51 INFO - mkdir: /builds/slave/test/build/tests 08:48:51 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:48:51 INFO - trying https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:48:51 INFO - Downloading https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:48:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 08:48:52 INFO - Downloaded 1199 bytes. 08:48:52 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:48:52 INFO - Using the following test package requirements: 08:48:52 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:48:52 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:48:52 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:48:52 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:48:52 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:48:52 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:48:52 INFO - u'jsshell-mac.zip'], 08:48:52 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:48:52 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:48:52 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:48:52 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:48:52 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:48:52 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:48:52 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:48:52 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:48:52 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:48:52 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:48:52 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:48:52 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:48:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:48:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:48:52 INFO - Fetch https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:48:53 INFO - Http code: 200 08:48:53 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 08:48:53 INFO - x-amz-cf-id: vN3qbLNwdB5O3IZf1uoi-wfJexv3pWt-9gtVmt6lgyjBku8Zcd5NMQ== 08:48:53 INFO - x-amz-version-id: NEu8X23VXLeR.nPTViJJCWOk2LfHj.EO 08:48:53 INFO - Expected file size: 16270978 08:48:53 INFO - Obtained file size: 16270978 08:49:00 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:49:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:49:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:49:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:49:00 INFO - Http code: 200 08:49:00 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 08:49:00 INFO - x-amz-cf-id: MkszIRs8SXz3Gj_zWjCEylz2x_XEp2Dx2A12qfNJVhtMeL58rTaoPA== 08:49:00 INFO - x-amz-version-id: NEu8X23VXLeR.nPTViJJCWOk2LfHj.EO 08:49:01 INFO - Expected file size: 16270978 08:49:01 INFO - Obtained file size: 16270978 08:49:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:49:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:49:07 INFO - Fetch https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:49:08 INFO - Http code: 200 08:49:08 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 08:49:08 INFO - x-amz-cf-id: 0ZVljd4GlKQCdd4EQ0IJdSO3c0hHDuKppb-IRPMtjGlkGiDlnmuR-Q== 08:49:08 INFO - x-amz-version-id: HNzXtXxd.nDsejlPuDYaAqmDiAN.zIkZ 08:49:08 INFO - Expected file size: 14409038 08:49:08 INFO - Obtained file size: 14409038 08:49: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:49:09 INFO - trying https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:49:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:49:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 08:49:11 INFO - Downloaded 109877319 bytes. 08:49:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:49:11 INFO - mkdir: /builds/slave/test/properties 08:49:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:49:11 INFO - Writing to file /builds/slave/test/properties/build_url 08:49:11 INFO - Contents: 08:49:11 INFO - build_url:https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:49:12 INFO - Running post-action listener: _resource_record_post_action 08:49:12 INFO - Running post-action listener: set_extra_try_arguments 08:49:12 INFO - [mozharness: 2017-01-09 16:49:12.521927Z] Finished download-and-extract step (success) 08:49:12 INFO - [mozharness: 2017-01-09 16:49:12.522055Z] Running populate-webroot step. 08:49:12 INFO - Running pre-action listener: _resource_record_pre_action 08:49:12 INFO - Running main action method: populate_webroot 08:49:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:49:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:49:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:49:12 INFO - '--tppagecycles', 08:49:12 INFO - '7'], 08:49:12 INFO - 'tests': ['tsvgm']}}, 08:49:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:49:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:49:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:49:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:49:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:49:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:49:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:12 INFO - 'talos_options': ['--disable-e10s'], 08:49:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:49:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:49:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:12 INFO - 'talos_options': ['--disable-e10s'], 08:49:12 INFO - 'tests': ['damp', 'tps']}, 08:49:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:12 INFO - 'tests': ['damp', 'tps']}, 08:49:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:49:12 INFO - 'tests': ['dromaeo_dom']}, 08:49:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:49:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:49:12 INFO - 'tests': ['basic_compositor_video']}, 08:49:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:49:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:49:12 INFO - 'tests': ['a11yr', 08:49:12 INFO - 'ts_paint', 08:49:12 INFO - 'tpaint', 08:49:12 INFO - 'sessionrestore', 08:49:12 INFO - 'sessionrestore_no_auto_restore', 08:49:12 INFO - 'tabpaint']}, 08:49:12 INFO - 'other-e10s': {'tests': ['a11yr', 08:49:12 INFO - 'ts_paint', 08:49:12 INFO - 'tpaint', 08:49:12 INFO - 'sessionrestore', 08:49:12 INFO - 'sessionrestore_no_auto_restore', 08:49:12 INFO - 'tabpaint']}, 08:49:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:49:12 INFO - 'ts_paint', 08:49:12 INFO - 'tpaint', 08:49:12 INFO - 'sessionrestore', 08:49:12 INFO - 'sessionrestore_no_auto_restore', 08:49:12 INFO - 'tabpaint']}, 08:49:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:49:12 INFO - 'ts_paint', 08:49:12 INFO - 'tpaint', 08:49:12 INFO - 'sessionrestore', 08:49:12 INFO - 'sessionrestore_no_auto_restore', 08:49:12 INFO - 'tabpaint']}, 08:49:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:49:12 INFO - 'tests': ['a11yr', 08:49:12 INFO - 'ts_paint', 08:49:12 INFO - 'tpaint', 08:49:12 INFO - 'sessionrestore', 08:49:12 INFO - 'sessionrestore_no_auto_restore', 08:49:12 INFO - 'tabpaint']}, 08:49:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:49:12 INFO - 'tests': ['a11yr', 08:49:12 INFO - 'ts_paint', 08:49:12 INFO - 'tpaint', 08:49:12 INFO - 'sessionrestore', 08:49:12 INFO - 'sessionrestore_no_auto_restore', 08:49:12 INFO - 'tabpaint']}, 08:49:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:49:12 INFO - 'tests': ['tsvgx', 08:49:12 INFO - 'tsvgr_opacity', 08:49:12 INFO - 'tart', 08:49:12 INFO - 'tscrollx', 08:49:12 INFO - 'cart']}, 08:49:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:49:12 INFO - 'tsvgr_opacity', 08:49:12 INFO - 'tart', 08:49:12 INFO - 'tscrollx', 08:49:12 INFO - 'cart']}, 08:49:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:12 INFO - 'talos_options': ['--disable-e10s'], 08:49:12 INFO - 'tests': ['tp5o']}, 08:49:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:12 INFO - 'tests': ['tp5o']}, 08:49:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:12 INFO - 'talos_options': ['--disable-e10s', 08:49:12 INFO - '--xperf_path', 08:49:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:49:12 INFO - 'tests': ['tp5n']}, 08:49:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:12 INFO - 'talos_options': ['--xperf_path', 08:49:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:49:12 INFO - 'tests': ['tp5n']}}, 08:49:12 INFO - 'talos.zip': {'path': '', 08:49:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:49:12 INFO - Running post-action listener: _resource_record_post_action 08:49:12 INFO - [mozharness: 2017-01-09 16:49:12.540927Z] Finished populate-webroot step (success) 08:49:12 INFO - [mozharness: 2017-01-09 16:49:12.541036Z] Running create-virtualenv step. 08:49:12 INFO - Running pre-action listener: _resource_record_pre_action 08:49:12 INFO - Running main action method: create_virtualenv 08:49:12 INFO - Creating virtualenv /builds/slave/test/build/venv 08:49:12 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:49:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:49:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:49:12 INFO - Using real prefix '/tools/python27' 08:49:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:49:13 INFO - Installing distribute.............................................................................................................................................................................................done. 08:49:16 INFO - Installing pip.................done. 08:49:16 INFO - Return code: 0 08:49:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:49:16 INFO - Reading from file tmpfile_stdout 08:49:16 INFO - Output received: 08:49:16 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:49:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:49:16 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:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:16 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:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:16 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 0x101ef9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e6f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb251cce4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d0dd30>, '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 0x101b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d05b20>, '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.fsgAFdmdYk/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.AKvXAJuBfc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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:49:16 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:49:16 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:49:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsgAFdmdYk/Render', 08:49:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:16 INFO - 'HOME': '/Users/cltbld', 08:49:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:16 INFO - 'LOGNAME': 'cltbld', 08:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:16 INFO - 'MOZ_NO_REMOTE': '1', 08:49:16 INFO - 'NO_EM_RESTART': '1', 08:49:16 INFO - 'PAGER': '/bin/cat', 08:49:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:16 INFO - 'PWD': '/builds/slave/test', 08:49:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:16 INFO - 'SHELL': '/bin/bash', 08:49:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AKvXAJuBfc/Listeners', 08:49:16 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 08:49:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:16 INFO - 'USER': 'cltbld', 08:49:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:16 INFO - 'XPC_FLAGS': '0x0', 08:49:16 INFO - 'XPC_SERVICE_NAME': '0', 08:49:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:16 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:49:16 INFO - Cleaning up... 08:49:16 INFO - Return code: 0 08:49:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:49:16 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:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:16 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:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:16 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 0x101ef9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e6f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb251cce4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d0dd30>, '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 0x101b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d05b20>, '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.fsgAFdmdYk/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.AKvXAJuBfc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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:49:16 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:49:16 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:49:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsgAFdmdYk/Render', 08:49:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:16 INFO - 'HOME': '/Users/cltbld', 08:49:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:16 INFO - 'LOGNAME': 'cltbld', 08:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:16 INFO - 'MOZ_NO_REMOTE': '1', 08:49:16 INFO - 'NO_EM_RESTART': '1', 08:49:16 INFO - 'PAGER': '/bin/cat', 08:49:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:16 INFO - 'PWD': '/builds/slave/test', 08:49:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:16 INFO - 'SHELL': '/bin/bash', 08:49:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AKvXAJuBfc/Listeners', 08:49:16 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 08:49:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:16 INFO - 'USER': 'cltbld', 08:49:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:16 INFO - 'XPC_FLAGS': '0x0', 08:49:16 INFO - 'XPC_SERVICE_NAME': '0', 08:49:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:17 INFO - Downloading/unpacking psutil>=3.1.1 08:49:17 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:49:17 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:49:17 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:49:17 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:49:17 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:49:17 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:49:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:49:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:49:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:49:21 INFO - Installing collected packages: psutil 08:49:21 INFO - Running setup.py install for psutil 08:49:21 INFO - building 'psutil._psutil_osx' extension 08:49:21 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:49:21 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:49:21 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:49:21 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:49:22 INFO - building 'psutil._psutil_posix' extension 08:49:22 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:49:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:49:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:49:22 INFO - ^ 08:49:22 INFO - 1 warning generated. 08:49:22 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:49:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:49:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:49:22 INFO - Successfully installed psutil 08:49:22 INFO - Cleaning up... 08:49:22 INFO - Return code: 0 08:49:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:49:22 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:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:22 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:49:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:22 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 0x101ef9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e6f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb251cce4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d0dd30>, '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 0x101b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d05b20>, '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.fsgAFdmdYk/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.AKvXAJuBfc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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:49:22 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:49:22 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:49:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsgAFdmdYk/Render', 08:49:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:22 INFO - 'HOME': '/Users/cltbld', 08:49:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:22 INFO - 'LOGNAME': 'cltbld', 08:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:22 INFO - 'MOZ_NO_REMOTE': '1', 08:49:22 INFO - 'NO_EM_RESTART': '1', 08:49:22 INFO - 'PAGER': '/bin/cat', 08:49:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:22 INFO - 'PWD': '/builds/slave/test', 08:49:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:22 INFO - 'SHELL': '/bin/bash', 08:49:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AKvXAJuBfc/Listeners', 08:49:22 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 08:49:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:22 INFO - 'USER': 'cltbld', 08:49:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:22 INFO - 'XPC_FLAGS': '0x0', 08:49:22 INFO - 'XPC_SERVICE_NAME': '0', 08:49:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:49:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:49:25 INFO - Installing collected packages: mozsystemmonitor 08:49:25 INFO - Running setup.py install for mozsystemmonitor 08:49:25 INFO - Successfully installed mozsystemmonitor 08:49:25 INFO - Cleaning up... 08:49:25 INFO - Return code: 0 08:49:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:49:25 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:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:25 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:49:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:25 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 0x101ef9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e6f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb251cce4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d0dd30>, '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 0x101b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d05b20>, '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.fsgAFdmdYk/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.AKvXAJuBfc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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:49:25 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:49:25 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:49:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsgAFdmdYk/Render', 08:49:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:25 INFO - 'HOME': '/Users/cltbld', 08:49:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:25 INFO - 'LOGNAME': 'cltbld', 08:49:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:25 INFO - 'MOZ_NO_REMOTE': '1', 08:49:25 INFO - 'NO_EM_RESTART': '1', 08:49:25 INFO - 'PAGER': '/bin/cat', 08:49:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:25 INFO - 'PWD': '/builds/slave/test', 08:49:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:25 INFO - 'SHELL': '/bin/bash', 08:49:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AKvXAJuBfc/Listeners', 08:49:25 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 08:49:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:25 INFO - 'USER': 'cltbld', 08:49:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:25 INFO - 'XPC_FLAGS': '0x0', 08:49:25 INFO - 'XPC_SERVICE_NAME': '0', 08:49:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:25 INFO - Downloading/unpacking jsonschema==2.5.1 08:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:49:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:28 INFO - Downloading functools32-3.2.3-2.tar.gz 08:49:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:49:28 INFO - warning: no files found matching '*.txt' 08:49:28 INFO - no previously-included directories found matching 'build' 08:49:28 INFO - no previously-included directories found matching 'dist' 08:49:28 INFO - no previously-included directories found matching '.git*' 08:49:28 INFO - Installing collected packages: jsonschema, functools32 08:49:28 INFO - Running setup.py install for functools32 08:49:29 INFO - warning: no files found matching '*.txt' 08:49:29 INFO - no previously-included directories found matching 'build' 08:49:29 INFO - no previously-included directories found matching 'dist' 08:49:29 INFO - no previously-included directories found matching '.git*' 08:49:29 INFO - Successfully installed jsonschema functools32 08:49:29 INFO - Cleaning up... 08:49:29 INFO - Return code: 0 08:49:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:49:29 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:49:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:29 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:49:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:29 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 0x101ef9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e6f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb251cce4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d0dd30>, '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 0x101b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d05b20>, '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.fsgAFdmdYk/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.AKvXAJuBfc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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:49:29 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:49:29 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:49:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsgAFdmdYk/Render', 08:49:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:29 INFO - 'HOME': '/Users/cltbld', 08:49:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:29 INFO - 'LOGNAME': 'cltbld', 08:49:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:29 INFO - 'MOZ_NO_REMOTE': '1', 08:49:29 INFO - 'NO_EM_RESTART': '1', 08:49:29 INFO - 'PAGER': '/bin/cat', 08:49:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:29 INFO - 'PWD': '/builds/slave/test', 08:49:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:29 INFO - 'SHELL': '/bin/bash', 08:49:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AKvXAJuBfc/Listeners', 08:49:29 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 08:49:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:29 INFO - 'USER': 'cltbld', 08:49:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:29 INFO - 'XPC_FLAGS': '0x0', 08:49:29 INFO - 'XPC_SERVICE_NAME': '0', 08:49:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:49:29 INFO - Cleaning up... 08:49:29 INFO - Return code: 0 08:49:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:49:29 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:49:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:29 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:49:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:29 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 0x101ef9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e6f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb251cce4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d0dd30>, '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 0x101b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d05b20>, '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.fsgAFdmdYk/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.AKvXAJuBfc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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:49:29 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:49:29 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:49:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsgAFdmdYk/Render', 08:49:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:29 INFO - 'HOME': '/Users/cltbld', 08:49:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:29 INFO - 'LOGNAME': 'cltbld', 08:49:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:29 INFO - 'MOZ_NO_REMOTE': '1', 08:49:29 INFO - 'NO_EM_RESTART': '1', 08:49:29 INFO - 'PAGER': '/bin/cat', 08:49:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:29 INFO - 'PWD': '/builds/slave/test', 08:49:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:29 INFO - 'SHELL': '/bin/bash', 08:49:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AKvXAJuBfc/Listeners', 08:49:29 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 08:49:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:29 INFO - 'USER': 'cltbld', 08:49:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:29 INFO - 'XPC_FLAGS': '0x0', 08:49:29 INFO - 'XPC_SERVICE_NAME': '0', 08:49:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:29 INFO - Downloading/unpacking blobuploader==1.2.4 08:49:29 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:49:29 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:49:29 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:49:29 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:49:29 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:49:29 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:49:32 INFO - Downloading blobuploader-1.2.4.tar.gz 08:49:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:49:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:49:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:49:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:33 INFO - Downloading docopt-0.6.1.tar.gz 08:49:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:49:33 INFO - Installing collected packages: blobuploader, requests, docopt 08:49:33 INFO - Running setup.py install for blobuploader 08:49:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:49:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:49:33 INFO - Running setup.py install for requests 08:49:34 INFO - Running setup.py install for docopt 08:49:34 INFO - Successfully installed blobuploader requests docopt 08:49:34 INFO - Cleaning up... 08:49:34 INFO - Return code: 0 08:49:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:34 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 0x101ef9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e6f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb251cce4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d0dd30>, '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 0x101b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d05b20>, '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.fsgAFdmdYk/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.AKvXAJuBfc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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:49:34 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:49:34 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:49:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsgAFdmdYk/Render', 08:49:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:34 INFO - 'HOME': '/Users/cltbld', 08:49:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:34 INFO - 'LOGNAME': 'cltbld', 08:49:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:34 INFO - 'MOZ_NO_REMOTE': '1', 08:49:34 INFO - 'NO_EM_RESTART': '1', 08:49:34 INFO - 'PAGER': '/bin/cat', 08:49:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:34 INFO - 'PWD': '/builds/slave/test', 08:49:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:34 INFO - 'SHELL': '/bin/bash', 08:49:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AKvXAJuBfc/Listeners', 08:49:34 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 08:49:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:34 INFO - 'USER': 'cltbld', 08:49:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:34 INFO - 'XPC_FLAGS': '0x0', 08:49:34 INFO - 'XPC_SERVICE_NAME': '0', 08:49:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:49:34 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-ocGBGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:49:34 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-VgwjTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:34 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-Sq3EqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:35 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-ZAngXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:35 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-Dzgd8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:35 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-Gc2SVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:35 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-qA7nap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:35 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-ht8DLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:35 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-WChcHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:35 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-qMpKtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:35 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-RI39_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:35 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-lTjlRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:35 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-qWGNR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:36 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-k647BC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:36 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-wy76hN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:36 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-L5_nSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:36 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-zkzD5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:49:36 INFO - Running setup.py install for manifestparser 08:49:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:49:36 INFO - Running setup.py install for mozcrash 08:49:36 INFO - Running setup.py install for mozdebug 08:49:36 INFO - Running setup.py install for mozdevice 08:49:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:49:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:49:37 INFO - Running setup.py install for mozfile 08:49:37 INFO - Running setup.py install for mozhttpd 08:49:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:49:37 INFO - Running setup.py install for mozinfo 08:49:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:49:37 INFO - Running setup.py install for mozInstall 08:49:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:49:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:49:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:49:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:49:37 INFO - Running setup.py install for mozleak 08:49:37 INFO - Running setup.py install for mozlog 08:49:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:49:38 INFO - Running setup.py install for moznetwork 08:49:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:49:38 INFO - Running setup.py install for mozprocess 08:49:38 INFO - Running setup.py install for mozprofile 08:49:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:49:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:49:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:49:38 INFO - Running setup.py install for mozrunner 08:49:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:49:38 INFO - Running setup.py install for mozscreenshot 08:49:38 INFO - Running setup.py install for moztest 08:49:38 INFO - Running setup.py install for mozversion 08:49:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:49:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:49:39 INFO - Cleaning up... 08:49:39 INFO - Return code: 0 08:49:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:39 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 0x101ef9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e6f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb251cce4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d0dd30>, '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 0x101b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d05b20>, '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.fsgAFdmdYk/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.AKvXAJuBfc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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:49:39 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:49:39 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:49:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsgAFdmdYk/Render', 08:49:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:39 INFO - 'HOME': '/Users/cltbld', 08:49:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:39 INFO - 'LOGNAME': 'cltbld', 08:49:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:39 INFO - 'MOZ_NO_REMOTE': '1', 08:49:39 INFO - 'NO_EM_RESTART': '1', 08:49:39 INFO - 'PAGER': '/bin/cat', 08:49:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:39 INFO - 'PWD': '/builds/slave/test', 08:49:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:39 INFO - 'SHELL': '/bin/bash', 08:49:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AKvXAJuBfc/Listeners', 08:49:39 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 08:49:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:39 INFO - 'USER': 'cltbld', 08:49:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:39 INFO - 'XPC_FLAGS': '0x0', 08:49:39 INFO - 'XPC_SERVICE_NAME': '0', 08:49:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:49:39 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-pWVgaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:49:39 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:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:49:39 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-yQKQBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49:39 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:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:39 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-2ZPiAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49:39 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:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:39 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-yHbcsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:39 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-aonp8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49:39 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:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:39 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-DOyxkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49:40 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:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:40 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-_4ijJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49:40 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:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:40 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-xpXyCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:40 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:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:40 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-0yTEiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:40 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:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:40 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-hwjJtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49:40 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:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:40 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-WmyViT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49:40 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:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:40 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-4G0TK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49:40 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:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:40 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-peo1Eu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49:40 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:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:40 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-LuAwsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49:40 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:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:40 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-PFISXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:40 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:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:40 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-D4mxLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:41 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-uBiwBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49:41 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:49:41 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:49:41 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:49:41 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:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:49:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:43 INFO - Downloading blessings-1.6.tar.gz 08:49:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:49:43 INFO - Installing collected packages: blessings 08:49:43 INFO - Running setup.py install for blessings 08:49:44 INFO - Successfully installed blessings 08:49:44 INFO - Cleaning up... 08:49:44 INFO - Return code: 0 08:49:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:49:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:44 INFO - Reading from file tmpfile_stdout 08:49:44 INFO - Current package versions: 08:49:44 INFO - blessings == 1.6 08:49:44 INFO - blobuploader == 1.2.4 08:49:44 INFO - docopt == 0.6.1 08:49:44 INFO - functools32 == 3.2.3-2 08:49:44 INFO - jsonschema == 2.5.1 08:49:44 INFO - manifestparser == 1.1 08:49:44 INFO - mozInstall == 1.12 08:49:44 INFO - mozcrash == 1.0 08:49:44 INFO - mozdebug == 0.1 08:49:44 INFO - mozdevice == 0.48 08:49:44 INFO - mozfile == 1.2 08:49:44 INFO - mozhttpd == 0.7 08:49:44 INFO - mozinfo == 0.9 08:49:44 INFO - mozleak == 0.1 08:49:44 INFO - mozlog == 3.4 08:49:44 INFO - moznetwork == 0.27 08:49:44 INFO - mozprocess == 0.23 08:49:44 INFO - mozprofile == 0.28 08:49:44 INFO - mozrunner == 6.13 08:49:44 INFO - mozscreenshot == 0.1 08:49:44 INFO - mozsystemmonitor == 0.3 08:49:44 INFO - moztest == 0.8 08:49:44 INFO - mozversion == 1.4 08:49:44 INFO - psutil == 3.1.1 08:49:44 INFO - requests == 1.2.3 08:49:44 INFO - wsgiref == 0.1.2 08:49:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:44 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 0x101ef9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e6f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb251cce4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d0dd30>, '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 0x101b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d05b20>, '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.fsgAFdmdYk/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.AKvXAJuBfc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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:49:44 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:49:44 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:49:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsgAFdmdYk/Render', 08:49:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:44 INFO - 'HOME': '/Users/cltbld', 08:49:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:44 INFO - 'LOGNAME': 'cltbld', 08:49:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:44 INFO - 'MOZ_NO_REMOTE': '1', 08:49:44 INFO - 'NO_EM_RESTART': '1', 08:49:44 INFO - 'PAGER': '/bin/cat', 08:49:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:44 INFO - 'PWD': '/builds/slave/test', 08:49:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:44 INFO - 'SHELL': '/bin/bash', 08:49:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AKvXAJuBfc/Listeners', 08:49:44 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 08:49:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:44 INFO - 'USER': 'cltbld', 08:49:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:44 INFO - 'XPC_FLAGS': '0x0', 08:49:44 INFO - 'XPC_SERVICE_NAME': '0', 08:49:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:44 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:49:44 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:49:44 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:49:44 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:49:44 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:49:44 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:49:44 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:49:44 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:49:44 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:49:44 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:49:44 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:49:44 INFO - Cleaning up... 08:49:44 INFO - Return code: 0 08:49:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ef9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e6f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb251cce4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d0dd30>, '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 0x101b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d05b20>, '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.fsgAFdmdYk/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.AKvXAJuBfc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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:49:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:49:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:49:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsgAFdmdYk/Render', 08:49:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:44 INFO - 'HOME': '/Users/cltbld', 08:49:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:44 INFO - 'LOGNAME': 'cltbld', 08:49:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:44 INFO - 'MOZ_NO_REMOTE': '1', 08:49:44 INFO - 'NO_EM_RESTART': '1', 08:49:44 INFO - 'PAGER': '/bin/cat', 08:49:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:44 INFO - 'PWD': '/builds/slave/test', 08:49:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:44 INFO - 'SHELL': '/bin/bash', 08:49:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AKvXAJuBfc/Listeners', 08:49:44 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 08:49:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:44 INFO - 'USER': 'cltbld', 08:49:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:44 INFO - 'XPC_FLAGS': '0x0', 08:49:44 INFO - 'XPC_SERVICE_NAME': '0', 08:49:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:49:45 INFO - Cleaning up... 08:49:45 INFO - Return code: 0 08:49:45 INFO - Running post-action listener: _resource_record_post_action 08:49:45 INFO - Running post-action listener: _start_resource_monitoring 08:49:45 INFO - Starting resource monitoring. 08:49:45 INFO - [mozharness: 2017-01-09 16:49:45.047038Z] Finished create-virtualenv step (success) 08:49:45 INFO - [mozharness: 2017-01-09 16:49:45.048030Z] Running install step. 08:49:45 INFO - Running pre-action listener: _resource_record_pre_action 08:49:45 INFO - Running main action method: install 08:49:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:45 INFO - Reading from file tmpfile_stdout 08:49:45 INFO - Detecting whether we're running mozinstall >=1.0... 08:49:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:49:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:49:45 INFO - Reading from file tmpfile_stdout 08:49:45 INFO - Output received: 08:49:45 INFO - Usage: mozinstall [options] installer 08:49:45 INFO - Options: 08:49:45 INFO - -h, --help show this help message and exit 08:49:45 INFO - -d DEST, --destination=DEST 08:49:45 INFO - Directory to install application into. [default: 08:49:45 INFO - "/builds/slave/test"] 08:49:45 INFO - --app=APP Application being installed. [default: firefox] 08:49:45 INFO - mkdir: /builds/slave/test/build/application 08:49:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:49:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:50:16 INFO - Reading from file tmpfile_stdout 08:50:16 INFO - Output received: 08:50:16 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:50:16 INFO - Running post-action listener: _resource_record_post_action 08:50:16 INFO - [mozharness: 2017-01-09 16:50:16.369728Z] Finished install step (success) 08:50:16 INFO - [mozharness: 2017-01-09 16:50:16.369867Z] Running run-tests step. 08:50:16 INFO - Running pre-action listener: _resource_record_pre_action 08:50:16 INFO - Running main action method: run_tests 08:50:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:50:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:50:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:50:16 INFO - 2017-01-09 08:50:16.384 screenresolution[1634:7986] starting screenresolution argv=screenresolution get 08:50:16 INFO - 2017-01-09 08:50:16.387 screenresolution[1634:7986] Display 0: 1600x1200x32@60 08:50:16 INFO - 2017-01-09 08:50:16.392 screenresolution[1635:7989] starting screenresolution argv=screenresolution list 08:50:16 INFO - Available Modes on Display 0 08:50:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:50:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:50:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:50:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:50:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:50:16 INFO - Intel Iris: 08:50:16 INFO - Chipset Model: Intel Iris 08:50:16 INFO - Type: GPU 08:50:16 INFO - Bus: Built-In 08:50:16 INFO - VRAM (Dynamic, Max): 1536 MB 08:50:16 INFO - Vendor: Intel (0x8086) 08:50:16 INFO - Device ID: 0x0a2e 08:50:16 INFO - Revision ID: 0x0009 08:50:16 INFO - Displays: 08:50:16 INFO - Display: 08:50:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:50:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:50:16 INFO - Display Serial Number: 1600x1200 60 08:50:16 INFO - Main Display: Yes 08:50:16 INFO - Mirror: Off 08:50:16 INFO - Online: Yes 08:50:16 INFO - Rotation: Supported 08:50:16 INFO - Return code: 0 08:50:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:50:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:50:16 INFO - Python 2.7.3 08:50:16 INFO - Return code: 0 08:50:16 INFO - grabbing minidump binary from tooltool 08:50:16 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:50:16 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 0x101d0dd30>, '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 0x101b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d05b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:50:16 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:50:16 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:50:16 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:50:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:50:16 INFO - Return code: 0 08:50:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:50:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:50:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:50:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:50:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:50:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0109', '--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:50:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0109 --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:50:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fsgAFdmdYk/Render', 08:50:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:16 INFO - 'HOME': '/Users/cltbld', 08:50:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:16 INFO - 'LOGNAME': 'cltbld', 08:50:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:50:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:50:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:16 INFO - 'MOZ_NO_REMOTE': '1', 08:50:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:50:16 INFO - 'NO_EM_RESTART': '1', 08:50:16 INFO - 'PAGER': '/bin/cat', 08:50:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:16 INFO - 'PWD': '/builds/slave/test', 08:50:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:50:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:16 INFO - 'SHELL': '/bin/bash', 08:50:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AKvXAJuBfc/Listeners', 08:50:16 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 08:50:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:16 INFO - 'USER': 'cltbld', 08:50:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:16 INFO - 'XPC_FLAGS': '0x0', 08:50:16 INFO - 'XPC_SERVICE_NAME': '0', 08:50:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0109', '--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:50:16 INFO - mozversion application_buildid: 20170109063808 08:50:16 INFO - mozversion application_changeset: bb6a56511146b31c57a2a3c792ccd464891b0593 08:50:16 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:50:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:50:16 INFO - mozversion application_name: Firefox 08:50:16 INFO - mozversion application_remotingname: firefox-dev 08:50:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:50:16 INFO - mozversion application_vendor: Mozilla 08:50:16 INFO - mozversion application_version: 52.0a2 08:50:16 INFO - mozversion platform_buildid: 20170109063808 08:50:16 INFO - mozversion platform_changeset: bb6a56511146b31c57a2a3c792ccd464891b0593 08:50:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:50:16 INFO - mozversion platform_version: 52.0a2 08:50:16 INFO - using testdate: 1483980616 08:50:16 INFO - actual date: 1483980616 08:50:16 INFO - starting webserver on 'localhost:49243' 08:50:16 INFO - SUITE-START | Running 2 tests 08:50:16 INFO - TEST-START | dromaeo_css 08:50:16 INFO - operating with platform_type : mac_ 08:50:16 INFO - Initialising browser for dromaeo_css test... 08:50:16 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpY4pEyd/profile http://localhost:49243/getInfo.html) 08:50:19 INFO - PROCESS | 1645 | 2017-01-09 08:50:19.926 plugin-container[1648:8170] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4b, name = 'com.apple.tsm.portname' 08:50:19 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:19 INFO - PROCESS | 1645 | 2017-01-09 08:50:19.929 plugin-container[1648:8170] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 08:50:19 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:19 INFO - PROCESS | 1645 | 2017-01-09 08:50:19.929 plugin-container[1648:8170] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:50:19 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 08:50:19 INFO - PROCESS | 1645 | colorDepth:24 08:50:19 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 08:50:19 INFO - PROCESS | 1645 | __metrics 08:50:20 ERROR - PROCESS | 1645 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:50:21 INFO - TEST-INFO | 1645: exit 0 08:50:21 INFO - Browser initialized. 08:50:21 INFO - Running cycle 1/1 for dromaeo_css test... 08:50:21 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpY4pEyd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:50:23 INFO - PROCESS | 1649 | 2017-01-09 08:50:23.558 plugin-container[1650:8296] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8343, name = 'com.apple.tsm.portname' 08:50:23 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:23 INFO - PROCESS | 1649 | 2017-01-09 08:50:23.558 plugin-container[1650:8296] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451b, name = 'com.apple.CFPasteboardClient' 08:50:23 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:23 INFO - PROCESS | 1649 | 2017-01-09 08:50:23.559 plugin-container[1650:8296] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:50:33 INFO - PROCESS | 1649 | RSS: Main: 229826560 08:50:33 INFO - PROCESS | 1649 | 08:53:37 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 08:53:39 INFO - PROCESS | 1649 | RSS: Main: 232218624 08:53:39 INFO - PROCESS | 1649 | 08:56:41 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 08:56:41 INFO - PROCESS | 1649 | RSS: Main: 243339264 08:56:41 INFO - PROCESS | 1649 | 08:59:43 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 08:59:44 INFO - PROCESS | 1649 | RSS: Main: 247242752 08:59:44 INFO - PROCESS | 1649 | 09:02:46 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 09:02:46 INFO - PROCESS | 1649 | RSS: Main: 249659392 09:02:46 INFO - PROCESS | 1649 | 09:05:49 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 09:05:50 INFO - PROCESS | 1649 | RSS: Main: 253095936 09:05:50 INFO - PROCESS | 1649 | 09:08:53 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 09:08:53 INFO - PROCESS | 1649 | RSS: Main: 254451712 09:08:53 INFO - PROCESS | 1649 | 09:08:53 INFO - PROCESS | 1649 | __start_tp_report 09:08:53 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 09:08:53 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 09:08:53 INFO - PROCESS | 1649 | |i|pagename|runs| 09:08:53 INFO - PROCESS | 1649 | |0;dojo.html;3351.899443016709;3380.3915295246848;3382.7124694400973;3426.7772594781336;3441.5353927219826;5016.974915125428;5604.887902241952;5782.450667186621;5806.477417032466;5867.237259156309;5012.949870501294;5703.5722320826;5791.565632577553;5838.532917366611;5847.356790753014;4426.159029784347;5190.584753219733;5255.1329030709985;5312.362516498026;5351;4827.51724827518;4871.782054486378;4951.975240123812;4989.301497790312;5428.4842939920645;4659.881628409191;5015.874603134915;5203.791848326063;5204.401493828202;5340.706261155635;4447.599716025557;5158.252053452247;5251;5295.655782374172;5320.893582128375;5157.149070403379;5225.320708307933;5237.083510385667;5264;5307.734613269321;5942.524598032147;5949.583529152961;5954.07711804671;5967.224260846106;5992.790252341147;4586.243269860469;5597.076482380402;6052.909206361908;6250.1874756281395;6329.955557333081;6115.818436018264;6117.15760190703;6147.077938309245;6181.443670069715;6185.319614842364;5827.155062515912;5859.736311866019;5900.793472228537;5900.911486327708;5912.054071348563;5108.59131269505;6315.778947736807;6317.684115794192;6325.304216536178;6335.334789847092;6109;6131.785387511416;6136.846578835565;6143.020634992025;6158.568900176944;6217.222847144107;6224.657643829542;6234.594751341148;6238.812835614939;6242.375762423721;6120.326764055951;6207.162033125561;6239.78160764371;6241.843953901098;6244.000959846403;5264.868378290574;5987.1318658794235;6078.510764862637;6084.908726369108;6213.316535181127;4988.775505102279;4992.176290911959;5018.974905125524;5019.899602008012;5062.265971434122;3740.6259374062374;4438.533953934855;4462.397576327219;4463.642908567307;4467.705768664144;5233.398159211663;5701.116326969326;5904.1734157218025;5966;5978.432048955342;6137.285055221596;6138.263408391022;6171.907421388682;6224.097505861715;6227.252729672377;5831.358550559435;5977.193078934288;5982.790602328984;5989.490893274034;6003.339632640406;5424.349078110652;5436.157395603688;5443.972780136074;5477.123660214426;5503.6147469677535;6159.23009623797;6434.356564343622;6446.580972236789;6448.451881590117;6465.127207827071;6109.495762817132;6114.388610881841;6142.529470848956;6160.907586386297;6242.344553821965;6411.551191416556;6417.614943103429;6420.06908998202;6424.357564243538;6447.161868957006;6213.65824879636;6218.9378106218355;6225.719842607003;6241.78153764616;6258.155149054819;6127.816165515042;6135.385782596045;6155.169052177898;6175.073738939195;6206.131141640084;6158.199434073542;6158.507319414526;6158.784442544489;6173.259208894961;6182.041783523561;4513.819447222074;6030.276366836008;6092.847678808065;6225.844353891189;6254.186955695731;5611.775528978795;5677.063284558001;5816.563757718103;5894.351621321565;5924.348321684697;5059.241113832954;6042.90935635957;6056.152138700673;6109.43503133753;6119.015143218386;5094.802721360551;6043.909341359795;6066.605670631395;6085.300190478151;6105.84735381619 09:08:53 INFO - PROCESS | 1649 | |1;ext.html;478.6840685147801;513.8278676643324;520.9270702101707;528.4134610582253;545.8553483326916;31702;37867.81066094669;39683.80158099213;39705.4044189337;41998.37002444966;32055.35889282213;33526.329473410515;40682.38976415356;40986;41305.7934710326;9217.723468295944;9378.718638440823;9419.058094190561;9422.764430889214;9447.905520944805;9356.345055846094;9442.10300021497;9444.716658500256;9474.573644185994;9510.239180865548;9407.20038796703;9449.055094490564;9483.19392851605;9485.288603354742;9534.237261019136;9393.906060939371;9451.574679139387;9459.432434053944;9474.431534107907;9478.668246611336;9275.165235128803;9358.344915855892;9375.203107735852;9394.436333819993;9395.906040939572;38999.220015599814;39104.804475977435;39364.81905542839;39372;39503.012424689325;3179.1257404213793;3448.6551344865566;3610.548681414823;3643.981780091083;3834.405667121601;23467;28921.1669770329;29300;29512.8524357379;30222.395552089056;3139.419207446607;3186.2193762528327;3195.744340452758;3204.6154461464776;3206.8396580170897;497365;501058;501629;504509;504810;56947.43052569421;57057.714711426175;57170;57212;57253.71373143191;58533.70733146392;59195;59237;59241.703791480766;59340.10989835156;15696.372145114296;15940.601484962968;15975.920120399323;15981.520554383387;15983.881128321138;12350.876491235153;12355.75288494225;12425.005999520017;12456.439460224312;12471.937640311922;12950.222986620833;14581;15259.542213733606;15292;15392.230388480755;3228.8224147672104;6582.506312026617;6799.660016999131;6831.863362732817;6834.282400347991;832.5004997001871;994.751312171957;1015.3603229965222;1025.4821315235758;1096.5997410944947;29688.109356719335;29741.702582974325;29746.107616771533;30041.849790750908;30190;1032.3444612671008;1041.6406339812752;1044.6448207609449;1050.5010120193;1081.9080378167787;15107.622309442351;15278.694426111415;15294.388224471119;15304.540863774104;15412.691746165014;682712;958365;972634;976431;978668;56123.71938140283;56391;56516.717416412655;56643.43356566464;56737.71631141818;1246.5823948977195;1368.6988862450246;1388.4723804954053;1405.0235086614705;1410.9929450352683;25107.87446062831;28884.855575721987;29877;30287;30471.847640761654;53245.733771332445;53940.730296348265;54131;54181;54351.184732228256;631371;634195;642241;642720;642888.7855560692;35782;40825;41758.37362439505;41776.373354399104;41992.79003605084;37900.86297411082;45223.7738811317;45230.095398091195;45231.54768452273;45672;33933.49099763555;34658.78694245689;37604.623953761205;38021.04947376338;39458.60541394549;33422.32888355621;37543.24913501769;37876.62123378731;38607.42088868725;39008.21983560256;29817.10548683543;37031.62968370282;37336.253274933806;37620.62379376171;37691.62308376991;31932.680673192972;32716;33920.15199620029;34087.4886876702;34138.65861341355;21762;25444.872775636002;25523.74476255288;25694.743052569982;26086.478270434105 09:08:53 INFO - PROCESS | 1649 | |2;jquery.html;353.9557555305587;373.3037884345694;386.4512392402789;386.94582758413844;403.9373897045959;18216.271349146034;19355.322563710273;20171.697424538626;20332.491687707778;20339.694904576445;18862.811371886277;19319.710204346946;19467.221311147572;19899.60200795983;20329.085191166396;5799.391063938278;5822.534197264209;5857.502112320458;6010.75956961721;6025.608335458203;5827.708614569276;5910.822675319747;5954.4938680212235;5962.880742385171;5968.4329988651225;5784.959106323546;5835.854103647401;5878.294604647448;5893.38119497453;5900.645961242318;5690.345610254812;5881.970655135334;5885.823425297248;5890.734916928748;5890.734916928748;4973;5132.255822905658;5175.741212939338;5184.611154163415;5200.843974680765;24083.277501674802;24312.149074782297;24494.755052449604;24717.25848224556;24789.752102478924;2301.2981040782556;2442.096424322994;2456.520978409211;2584.3022383956413;3096.349766549027;15063.623409414742;15111.39554417822;15196;15333.775276754544;15853.60365990848;1833.6241070580631;1850.5743678953781;1861.3206179744432;1885.8962757048357;1923.6345094432013;107753;111106;111563.44218278857;111887;112067;8777.824443511094;10900.291481053706;11231.708353539447;11380.487878045507;11461.197716159788;11479.426028698532;13854.722905541832;13868.653283667989;13979.720405591832;14008.08947418435;8529.104444033288;8561.229489345864;8564;8636.611352489153;8666.263367613697;7397.482176247611;8333.916660833314;8513.531755753493;8551.187637174458;8706.608202630898;6576.408123268929;6769;6780.525363224527;6786.287439818748;6821.317868213238;1869.4391682495198;1897.2790339670837;1920.9519762006062;1938.1569017477443;1943.9611207776047;848.249299370062;876.4697358098387;891.1934699097327;920.9355345125911;1021.7649940513724;20482.283120091022;21590.568188636138;21625.45936351572;21934.780652193593;21969.340919772112;807.1610693890166;944.3521055757609;969.9272554558436;971.3734641156556;1001.6344034427382;8948.8657670135;9571.952140239255;9751.829780426395;11235.427040214376;11511.942440287912;526473;568576;577971;599879.0006050029;622139;105955.47022264994;109580;124059;125950;127145;426.3370458936322;459.226203846513;461.8614415675284;463.7657982718638;465.8160026789399;10933.687957445158;10950.452477376259;11244.718882028015;11555.537778488766;11571.13216508782;38993;51936;52235.694107647614;52683.47316526939;52889.735551321995;75831.86252206111;77582;77991;78122;78438.82341764764;8176.141505141874;8198.631061602362;8248.422610417214;8505.106963768963;8563.657453701782;9265.305102117234;12290.385480725748;12491.500339986298;12847.807282890577;13090.14914030585;11285.37930413835;11308.886911131112;11478.02436792882;11661.591844285409;11781.52873885035;10536.262461627612;11032.227744057838;11262.887371126511;11278.943605281922;11435.428228588371;11437.542498300307;11494.94252528732;11496.597619083292;11517.366544839779;11821.527138914691;7937.920620793718;8250.669973201246;8436.704715334934;8456.196661317164;8540.871886921825;8096.433249672466;9436.669716559887;11327.32036077838;11343.092552595608;11435.913588209107 09:08:53 INFO - PROCESS | 1649 | |3;mootools.html;1471.1834931612955;1514.765211392234;1690.8900921440106;1803.5400972751947;1828.305244007277;7613.923860761391;7889.053313602376;8144.348452123831;8555.78610534737;8576.97076350838;7116.430685545157;8030.718924837632;8402.705905293316;8448.577571121436;8534.061253262153;1792.9193186306647;2001.269536619138;2343.0979073056824;2344.742078371378;2351.75306592807;2315.4674424882287;2345.542619189259;2372.620380739082;2373.2405630198346;2381.273711517986;2318.675385446039;2329.871857047862;2345.308134100436;2363.243761996162;2365.6924599802;2326.348622385735;2332.195392589554;2340.040583360831;2343.168175297762;2350.1304517328526;2025.574629327843;2341.285907798121;2359.775821296991;2363.1847012780563;2372.9169479068323;6535.346465353474;7909.24862138096;8514.148585141436;8514.829703405898;8541.743747687578;4151.97924010378;5158.40678321994;5164.251183578398;5164.715940623302;5183.585313174937;3330.417176994041;3490.7905525668543;3591.28174365128;3625.166211771281;3638.308721342963;1054.9208809339254;1069;1089.6186334782842;1106.1537923488538;1114.587602587048;9575.850897892227;10987.670369888916;10988.340699557972;11152.052075573585;11159.832602510887;4542.841000564964;4692.577668009895;4724.787384567703;4742.762861856962;4787.234042553175;9467.857982130274;9511.57197926095;9517.714468565953;9531.38046027006;9549.331546791796;5215.895682086413;5240.475952404805;5249.107651699221;5257.211418287211;5266.210068489757;4144.585541445831;4769.57073863354;4806.903861922812;4813.831515896927;4891.559759621651;3206.583144191287;3841.7118716096406;3861.6717579005535;3913.8630147944687;3936.5472970608175;1279.3795009420423;1279.6672865054966;1286.8584455709865;1295.656650987489;1298.5519995601503;749.3443237167478;849.3163003782015;885.7652722028668;904.5884122724144;907.4691305586174;5597.188407680864;5691.857703557444;5706.914396284059;5738;5937.317208520989;890.7416849113685;893.6461261189304;895.2793001633747;904.7604781449324;926.7683079230193;3930.4890364252465;3992.7005474589523;4025.4364388985564;4029.4157347184496;4040.8787736367367;10042.598296068221;10228.6419975302;10244.436555989343;10265.897341026643;10301.484925753683;10372.688819335432;10377.481125943672;10384.013518715861;10394.48027598617;10525.789484210272;7471.215522370181;7804.2585954334245;7812.609369531614;7906.52560846351;8052.5571093590415;5790.247267855152;5862.794802181903;5920.496757775637;5972.731227094704;5978.312494063064;9258;9374.906250937403;9409.529523523659;9535.38020028696;9569.186619137174;9472.431654100776;9627.326087173831;9640.035996400304;9757.80484390294;9801.362911410735;7232.204457509775;7275.345218930322;7296;7360.447966402434;7393.778186654409;7879;7969.282764551218;7975.641096150571;7999.320057795153;8000.999875015623;7095.219525852049;7187.6765545551625;7261.455390845602;7301.5984120871735;7334.779956601311;6962.268961758942;7056.294370562902;7075.893861592183;7183.964080179566;7193.100862392201;5734.311882574118;6114.419130182626;6728.6972086255255;6785.762498312535;6801.489888258301;4087.6116768906904;4111.09555897702;4416.4258646375765;4876.878078048077;4887.34020907173;4854.660173787801;4871.123197824284;4909.828156014522;4993.600511958962;5018.42288136869 09:08:53 INFO - PROCESS | 1649 | |4;prototype.html;297.99702002979967;303.55680706168994;308.9830059346735;312.838887972694;319.2895806829804;4549.840755573556;4851.708897466155;5338.06583847826;5513.779448822047;5604.663720176787;4553.975355545001;5244.973775131129;5336.946630533703;5393.896380411633;5469.671819690821;4136.979315103428;4189.853355132571;4194.916101677964;4211.241976444238;4216.2199993001395;3867.671247943928;3914.256291304657;4218;4220.894477638069;4254.362520045363;3588.946165807502;3605.531280933475;3770.944135642025;4116.794160291988;4138.33786594145;3278.491833765758;3674.669279764834;4049.007993041694;4095.8122144578037;4142.917141657165;3283.4582293921476;3290.424175769255;3530.5586801649793;3558.857645694169;3562.4834399011997;5098.872528186788;5182.922256166123;5190.494756520605;5323.467653234646;5383.865403364908;4106.301928672133;4401.205234675972;4620.561046700592;4726.361941137937;4727.45634252062;4786.904261914777;5760.2511673482595;5787.363390027136;5801.7389217485315;5815;3677.9080522986874;3876.3604005339084;3907.2576210520197;3915.902102447433;3923.8234279457206;5098.974505127488;5518.944810551923;5795.855533452607;6167.885217964944;6168.444839964424;3539.805310707936;3982.944519702281;3983.8008099594904;3991.982044578614;4003.6997225208224;3392.4741665041956;3918.4725851825415;4030;4035.9798201008807;4040.1313717550865;4697.365855609517;4700.835470758507;4703.048234982512;4728.787204575802;4749.216379297377;5808.854778630568;5837.912431313449;5877.764889404461;5894.934633990301;5904.022152578934;1060.9522571484301;1193.4987305331608;1239.7210627608715;1241.2924632959307;1255;788.873780195166;792.298815548244;844.9197326253995;859.9183077607618;889.8042430665244;5072.706459852788;5086.796528138833;5103.974480127576;5106.412762532282;5114.925865568198;5062.443131255559;5594.41258667834;5616.522595579339;5623.859403514863;5646.774129034792;4238.3430568261965;4725.771299462096;5080.796768129233;5107.591392688576;5135.948640513622;2399.83201175916;2928.5443177050856;2931.6921723218757;2945.262295245815;2953.867075981586;5670.603057785998;5785.466851284413;5787.102999035156;5842.5618078644275;5881.823545293648;4828.927566086511;4916.778744956486;5215.009148261818;5432.402435732066;5450.836474905759;4921.729304888194;5158.63889527737;5217.973910130425;5437.048516509674;5811.854703632443;4560.771961401983;4705.941163238244;4849;5284.339457567804;5433.402325744086;5070.467600901852;5254.845513339513;5530.751116199861;5572.91640625399;5578.852141925307;4913;5036.546710795899;5488;5571.777128914798;5897.3807750185615;3642.3990041642833;3765.472833803215;3780.773153610792;3791.9052023698523;3803.7337386382687;4937.35814344133;5083.898322033465;5100.795968161232;5123.436421993503;5557.249771280825;4379.3504929517185;4727.787249573847;5113.64204505681;5434.3207099112615;5670.971645141748;5106.038497671268;5263.499967503081;5824.184614153937;5829.417058294137;5832;4975.68144441716;5162.793488260428;5301.840944771663;5597.916031259453;5732.942670573241;5309.831836995855;5616.315105468261;5752.741126649395;5790.481140703807;5804.796832110856;4956.89332033877;5189.455107213839;5581.6930068846605;5653.40639232891;5711.857203569944 09:08:53 INFO - PROCESS | 1649 | |5;yui.html;691.1188235000374;702.1539045450234;743.8884167374894;762.8131107878571;794.4161041634397;13988.09077409969;14809.111453312782;14816.481423150191;14823.110613363206;14850.108993460386;13409.66475838102;13431.59705208843;13446.26045567496;13459.730805383886;13474.797878031814;643.5655932245738;670.3095811314354;796.9481983671072;822.5517093184229;841.8232171244016;829.9460535065201;830.4933990265932;830.8919840420767;832.0930186097153;833.2792134803382;874.8425283448977;877.2017464107663;878.6221924572432;882.2809410330592;883.041899539001;852.8848605438279;868.4398562926876;871.2072014466836;875.6322344615277;877.7147427086222;554.2960440240912;618.8143556932947;642.7300533775825;647.197475130839;653.8005908197998;11444.542218311257;12539.808718171758;12562.623121306375;13767.655808604764;13985.58043258704;12779.936100319439;13245.271510066943;13330.066895317332;13354.332283385791;13356.998225133057;9425.34022618417;11021;11052.723681907935;11490.770184596346;11775.857596136562;3514.3147086318177;3558.217192217708;3646.6717995380545;3659.451082337644;3788.1666033472866;84358.15641843564;84998.57500712457;86971.69542456868;87969;89307;8773.956130219436;9978.351407158512;10153;10365.792684146274;10598.046175844056;19904.90047549782;19974.90012549957;20100;20179.697304540445;20205.39383818488;6895.34494223048;7133.785986420416;7587.886181707278;7592.468527203153;7618.4667073305445;3562.305350456688;4119.114390406017;4271.658267338606;4279.529251782302;4295.548967358445;3331.3170799985946;3416.1118109291765;3416.84624191907;3423.760336776402;3465.5321531593418;643.1317721076509;647.7506160128385;649.0750680280581;660.2242365220847;662.2715013485127;581.6190395291092;589.8820235952741;626.1859582542676;647.6044124910825;679.2392520377209;8825.955870220609;8994.86507702385;9028.322875784343;9133.68032118886;9384.577694003787;659.4262991197593;662.4336192555392;680.2245440198178;680.5236334565725;692.1486571517062;4090.734102283342;4212.7472351659;4280.229558679405;4597.816087356535;4618.307253911873;93851.53074234586;102708;105234;106578;110060;67148.66425667937;84911.5754421224;93159.53420232855;97816.51091744496;98444;84567.57716211502;86203;86368;87465.25069498575;88387;8526.531040792815;8824.647014119364;9549.713508594754;9630;9796.265280103878;40959.79520102381;46695.533044669115;47078;48529.75735121302;49319;71764.28235717576;73908.89136663062;76101.09747256363;76436.61781691056;77160.61419692865;12022.819657705317;12197.939010304892;12250.632481025226;12360.320182390054;12561.811572826233;13610.863891361356;13838.169709817443;14221.64445888861;14381.496647617283;14602.977791554915;12760.553380631633;13560.11859229147;13630.591082267549;14048.719025619635;14215.076020058663;13451;13584.592462226148;13945;14176.716465670423;14187.929060354632;13356.732865342834;13384.799228011392;13408.061435699407;13599.184048956698;13841.861581384057;7891.881621775563;8856.601452934763;10272.743181420525;11750.353730544903;12067.069268916932;11207.943960280147;11491.367974761133;11620.244684095846;11628.825567616323;11770.17608767378 09:08:53 INFO - PROCESS | 1649 | __end_tp_report 09:08:53 INFO - PROCESS | 1649 | __start_cc_report 09:08:53 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,363 09:08:53 INFO - PROCESS | 1649 | __end_cc_report 09:08:53 INFO - PROCESS | 1649 | __startTimestamp1483981733487__endTimestamp 09:08:53 INFO - PROCESS | 1649 | 09:08:53 INFO - PROCESS | 1649 | ------- Summary: start ------- 09:08:53 INFO - PROCESS | 1649 | Number of tests: 6 09:08:53 INFO - PROCESS | 1649 | 09:08:53 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5700.02 Median:5978.43 stddev:683.80 (11.4%) stddev-sans-first:660.62 09:08:53 INFO - PROCESS | 1649 | Values: 3351.9 3380.4 3382.7 3426.8 3441.5 5017.0 5604.9 5782.5 5806.5 5867.2 5012.9 5703.6 5791.6 5838.5 5847.4 4426.2 5190.6 5255.1 5312.4 5351.0 4827.5 4871.8 4952.0 4989.3 5428.5 4659.9 5015.9 5203.8 5204.4 5340.7 4447.6 5158.3 5251.0 5295.7 5320.9 5157.1 5225.3 5237.1 5264.0 5307.7 5942.5 5949.6 5954.1 5967.2 5992.8 4586.2 5597.1 6052.9 6250.2 6330.0 6115.8 6117.2 6147.1 6181.4 6185.3 5827.2 5859.7 5900.8 5900.9 5912.1 5108.6 6315.8 6317.7 6325.3 6335.3 6109.0 6131.8 6136.8 6143.0 6158.6 6217.2 6224.7 6234.6 6238.8 6242.4 6120.3 6207.2 6239.8 6241.8 6244.0 5264.9 5987.1 6078.5 6084.9 6213.3 4988.8 4992.2 5019.0 5019.9 5062.3 3740.6 4438.5 4462.4 4463.6 4467.7 5233.4 5701.1 5904.2 5966.0 5978.4 6137.3 6138.3 6171.9 6224.1 6227.3 5831.4 5977.2 5982.8 5989.5 6003.3 5424.3 5436.2 5444.0 5477.1 5503.6 6159.2 6434.4 6446.6 6448.5 6465.1 6109.5 6114.4 6142.5 6160.9 6242.3 6411.6 6417.6 6420.1 6424.4 6447.2 6213.7 6218.9 6225.7 6241.8 6258.2 6127.8 6135.4 6155.2 6175.1 6206.1 6158.2 6158.5 6158.8 6173.3 6182.0 4513.8 6030.3 6092.8 6225.8 6254.2 5611.8 5677.1 5816.6 5894.4 5924.3 5059.2 6042.9 6056.2 6109.4 6119.0 5094.8 6043.9 6066.6 6085.3 6105.8 09:08:53 INFO - PROCESS | 1649 | 09:08:53 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:79384.93 Median:39234.81 stddev:191428.40 (487.9%) stddev-sans-first:191873.73 09:08:53 INFO - PROCESS | 1649 | Values: 478.7 513.8 520.9 528.4 545.9 31702.0 37867.8 39683.8 39705.4 41998.4 32055.4 33526.3 40682.4 40986.0 41305.8 9217.7 9378.7 9419.1 9422.8 9447.9 9356.3 9442.1 9444.7 9474.6 9510.2 9407.2 9449.1 9483.2 9485.3 9534.2 9393.9 9451.6 9459.4 9474.4 9478.7 9275.2 9358.3 9375.2 9394.4 9395.9 38999.2 39104.8 39364.8 39372.0 39503.0 3179.1 3448.7 3610.5 3644.0 3834.4 23467.0 28921.2 29300.0 29512.9 30222.4 3139.4 3186.2 3195.7 3204.6 3206.8 497365.0 501058.0 501629.0 504509.0 504810.0 56947.4 57057.7 57170.0 57212.0 57253.7 58533.7 59195.0 59237.0 59241.7 59340.1 15696.4 15940.6 15975.9 15981.5 15983.9 12350.9 12355.8 12425.0 12456.4 12471.9 12950.2 14581.0 15259.5 15292.0 15392.2 3228.8 6582.5 6799.7 6831.9 6834.3 832.5 994.8 1015.4 1025.5 1096.6 29688.1 29741.7 29746.1 30041.8 30190.0 1032.3 1041.6 1044.6 1050.5 1081.9 15107.6 15278.7 15294.4 15304.5 15412.7 682712.0 958365.0 972634.0 976431.0 978668.0 56123.7 56391.0 56516.7 56643.4 56737.7 1246.6 1368.7 1388.5 1405.0 1411.0 25107.9 28884.9 29877.0 30287.0 30471.8 53245.7 53940.7 54131.0 54181.0 54351.2 631371.0 634195.0 642241.0 642720.0 642888.8 35782.0 40825.0 41758.4 41776.4 41992.8 37900.9 45223.8 45230.1 45231.5 45672.0 33933.5 34658.8 37604.6 38021.0 39458.6 33422.3 37543.2 37876.6 38607.4 39008.2 29817.1 37031.6 37336.3 37620.6 37691.6 31932.7 32716.0 33920.2 34087.5 34138.7 21762.0 25444.9 25523.7 25694.7 26086.5 09:08:53 INFO - PROCESS | 1649 | 09:08:53 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:33791.21 Median:363.63 stddev:96572.43 (26557.9%) stddev-sans-first:96810.71 09:08:53 INFO - PROCESS | 1649 | Values: 354.0 373.3 386.5 386.9 403.9 18216.3 19355.3 20171.7 20332.5 20339.7 18862.8 19319.7 19467.2 19899.6 20329.1 5799.4 5822.5 5857.5 6010.8 6025.6 5827.7 5910.8 5954.5 5962.9 5968.4 5785.0 5835.9 5878.3 5893.4 5900.6 5690.3 5882.0 5885.8 5890.7 5890.7 4973.0 5132.3 5175.7 5184.6 5200.8 24083.3 24312.1 24494.8 24717.3 24789.8 2301.3 2442.1 2456.5 2584.3 3096.3 15063.6 15111.4 15196.0 15333.8 15853.6 1833.6 1850.6 1861.3 1885.9 1923.6 107753.0 111106.0 111563.4 111887.0 112067.0 8777.8 10900.3 11231.7 11380.5 11461.2 11479.4 13854.7 13868.7 13979.7 14008.1 8529.1 8561.2 8564.0 8636.6 8666.3 7397.5 8333.9 8513.5 8551.2 8706.6 6576.4 6769.0 6780.5 6786.3 6821.3 1869.4 1897.3 1921.0 1938.2 1944.0 848.2 876.5 891.2 920.9 1021.8 20482.3 21590.6 21625.5 21934.8 21969.3 807.2 944.4 969.9 971.4 1001.6 8948.9 9572.0 9751.8 11235.4 11511.9 526473.0 568576.0 577971.0 599879.0 622139.0 105955.5 109580.0 124059.0 125950.0 127145.0 426.3 459.2 461.9 463.8 465.8 10933.7 10950.5 11244.7 11555.5 11571.1 38993.0 51936.0 52235.7 52683.5 52889.7 75831.9 77582.0 77991.0 78122.0 78438.8 8176.1 8198.6 8248.4 8505.1 8563.7 9265.3 12290.4 12491.5 12847.8 13090.1 11285.4 11308.9 11478.0 11661.6 11781.5 10536.3 11032.2 11262.9 11278.9 11435.4 11437.5 11494.9 11496.6 11517.4 11821.5 7937.9 8250.7 8436.7 8456.2 8540.9 8096.4 9436.7 11327.3 11343.1 11435.9 09:08:53 INFO - PROCESS | 1649 | 09:08:53 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5481.87 Median:5161.33 stddev:3011.82 (58.4%) stddev-sans-first:3005.19 09:08:53 INFO - PROCESS | 1649 | Values: 1471.2 1514.8 1690.9 1803.5 1828.3 7613.9 7889.1 8144.3 8555.8 8577.0 7116.4 8030.7 8402.7 8448.6 8534.1 1792.9 2001.3 2343.1 2344.7 2351.8 2315.5 2345.5 2372.6 2373.2 2381.3 2318.7 2329.9 2345.3 2363.2 2365.7 2326.3 2332.2 2340.0 2343.2 2350.1 2025.6 2341.3 2359.8 2363.2 2372.9 6535.3 7909.2 8514.1 8514.8 8541.7 4152.0 5158.4 5164.3 5164.7 5183.6 3330.4 3490.8 3591.3 3625.2 3638.3 1054.9 1069.0 1089.6 1106.2 1114.6 9575.9 10987.7 10988.3 11152.1 11159.8 4542.8 4692.6 4724.8 4742.8 4787.2 9467.9 9511.6 9517.7 9531.4 9549.3 5215.9 5240.5 5249.1 5257.2 5266.2 4144.6 4769.6 4806.9 4813.8 4891.6 3206.6 3841.7 3861.7 3913.9 3936.5 1279.4 1279.7 1286.9 1295.7 1298.6 749.3 849.3 885.8 904.6 907.5 5597.2 5691.9 5706.9 5738.0 5937.3 890.7 893.6 895.3 904.8 926.8 3930.5 3992.7 4025.4 4029.4 4040.9 10042.6 10228.6 10244.4 10265.9 10301.5 10372.7 10377.5 10384.0 10394.5 10525.8 7471.2 7804.3 7812.6 7906.5 8052.6 5790.2 5862.8 5920.5 5972.7 5978.3 9258.0 9374.9 9409.5 9535.4 9569.2 9472.4 9627.3 9640.0 9757.8 9801.4 7232.2 7275.3 7296.0 7360.4 7393.8 7879.0 7969.3 7975.6 7999.3 8001.0 7095.2 7187.7 7261.5 7301.6 7334.8 6962.3 7056.3 7075.9 7184.0 7193.1 5734.3 6114.4 6728.7 6785.8 6801.5 4087.6 4111.1 4416.4 4876.9 4887.3 4854.7 4871.1 4909.8 4993.6 5018.4 09:08:53 INFO - PROCESS | 1649 | 09:08:53 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4493.08 Median:5071.59 stddev:1376.87 (27.1%) stddev-sans-first:1344.25 09:08:53 INFO - PROCESS | 1649 | Values: 298.0 303.6 309.0 312.8 319.3 4549.8 4851.7 5338.1 5513.8 5604.7 4554.0 5245.0 5336.9 5393.9 5469.7 4137.0 4189.9 4194.9 4211.2 4216.2 3867.7 3914.3 4218.0 4220.9 4254.4 3588.9 3605.5 3770.9 4116.8 4138.3 3278.5 3674.7 4049.0 4095.8 4142.9 3283.5 3290.4 3530.6 3558.9 3562.5 5098.9 5182.9 5190.5 5323.5 5383.9 4106.3 4401.2 4620.6 4726.4 4727.5 4786.9 5760.3 5787.4 5801.7 5815.0 3677.9 3876.4 3907.3 3915.9 3923.8 5099.0 5518.9 5795.9 6167.9 6168.4 3539.8 3982.9 3983.8 3992.0 4003.7 3392.5 3918.5 4030.0 4036.0 4040.1 4697.4 4700.8 4703.0 4728.8 4749.2 5808.9 5837.9 5877.8 5894.9 5904.0 1061.0 1193.5 1239.7 1241.3 1255.0 788.9 792.3 844.9 859.9 889.8 5072.7 5086.8 5104.0 5106.4 5114.9 5062.4 5594.4 5616.5 5623.9 5646.8 4238.3 4725.8 5080.8 5107.6 5135.9 2399.8 2928.5 2931.7 2945.3 2953.9 5670.6 5785.5 5787.1 5842.6 5881.8 4828.9 4916.8 5215.0 5432.4 5450.8 4921.7 5158.6 5218.0 5437.0 5811.9 4560.8 4705.9 4849.0 5284.3 5433.4 5070.5 5254.8 5530.8 5572.9 5578.9 4913.0 5036.5 5488.0 5571.8 5897.4 3642.4 3765.5 3780.8 3791.9 3803.7 4937.4 5083.9 5100.8 5123.4 5557.2 4379.4 4727.8 5113.6 5434.3 5671.0 5106.0 5263.5 5824.2 5829.4 5832.0 4975.7 5162.8 5301.8 5597.9 5732.9 5309.8 5616.3 5752.7 5790.5 5804.8 4956.9 5189.5 5581.7 5653.4 5711.9 09:08:53 INFO - PROCESS | 1649 | 09:08:53 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:19974.50 Median:46886.77 stddev:28941.36 (61.7%) stddev-sans-first:28986.33 09:08:53 INFO - PROCESS | 1649 | Values: 691.1 702.2 743.9 762.8 794.4 13988.1 14809.1 14816.5 14823.1 14850.1 13409.7 13431.6 13446.3 13459.7 13474.8 643.6 670.3 796.9 822.6 841.8 829.9 830.5 830.9 832.1 833.3 874.8 877.2 878.6 882.3 883.0 852.9 868.4 871.2 875.6 877.7 554.3 618.8 642.7 647.2 653.8 11444.5 12539.8 12562.6 13767.7 13985.6 12779.9 13245.3 13330.1 13354.3 13357.0 9425.3 11021.0 11052.7 11490.8 11775.9 3514.3 3558.2 3646.7 3659.5 3788.2 84358.2 84998.6 86971.7 87969.0 89307.0 8774.0 9978.4 10153.0 10365.8 10598.0 19904.9 19974.9 20100.0 20179.7 20205.4 6895.3 7133.8 7587.9 7592.5 7618.5 3562.3 4119.1 4271.7 4279.5 4295.5 3331.3 3416.1 3416.8 3423.8 3465.5 643.1 647.8 649.1 660.2 662.3 581.6 589.9 626.2 647.6 679.2 8826.0 8994.9 9028.3 9133.7 9384.6 659.4 662.4 680.2 680.5 692.1 4090.7 4212.7 4280.2 4597.8 4618.3 93851.5 102708.0 105234.0 106578.0 110060.0 67148.7 84911.6 93159.5 97816.5 98444.0 84567.6 86203.0 86368.0 87465.3 88387.0 8526.5 8824.6 9549.7 9630.0 9796.3 40959.8 46695.5 47078.0 48529.8 49319.0 71764.3 73908.9 76101.1 76436.6 77160.6 12022.8 12197.9 12250.6 12360.3 12561.8 13610.9 13838.2 14221.6 14381.5 14603.0 12760.6 13560.1 13630.6 14048.7 14215.1 13451.0 13584.6 13945.0 14176.7 14187.9 13356.7 13384.8 13408.1 13599.2 13841.9 7891.9 8856.6 10272.7 11750.4 12067.1 11207.9 11491.4 11620.2 11628.8 11770.2 09:08:53 INFO - PROCESS | 1649 | -------- Summary: end -------- 09:08:53 INFO - PROCESS | 1649 | 09:08:54 INFO - TEST-INFO | 1649: exit 0 09:08:54 INFO - TEST-OK | dromaeo_css | took 1117172ms 09:08:54 INFO - TEST-START | kraken 09:08:54 INFO - operating with platform_type : mac_ 09:08:54 INFO - Initialising browser for kraken test... 09:08:54 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpuYvDmO/profile http://localhost:49243/getInfo.html) 09:08:57 INFO - PROCESS | 1658 | 2017-01-09 09:08:57.151 plugin-container[1659:10797] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x852b, name = 'com.apple.tsm.portname' 09:08:57 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:08:57 INFO - PROCESS | 1658 | 2017-01-09 09:08:57.154 plugin-container[1659:10797] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x420f, name = 'com.apple.CFPasteboardClient' 09:08:57 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:08:57 INFO - PROCESS | 1658 | 2017-01-09 09:08:57.154 plugin-container[1659:10797] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:08:57 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 09:08:57 INFO - PROCESS | 1658 | colorDepth:24 09:08:57 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 09:08:57 INFO - PROCESS | 1658 | __metrics 09:08:57 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 09:08:58 INFO - TEST-INFO | 1658: exit 0 09:08:58 INFO - Browser initialized. 09:08:58 INFO - Running cycle 1/1 for kraken test... 09:08:58 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpuYvDmO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:09:00 INFO - PROCESS | 1660 | 2017-01-09 09:09:00.839 plugin-container[1661:10919] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 09:09:00 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:09:00 INFO - PROCESS | 1660 | 2017-01-09 09:09:00.839 plugin-container[1661:10919] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 09:09:00 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:09:00 INFO - PROCESS | 1660 | 2017-01-09 09:09:00.839 plugin-container[1661:10919] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:09:10 INFO - PROCESS | 1660 | RSS: Main: 229441536 09:09:10 INFO - PROCESS | 1660 | 09:09:13 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 09:09:14 INFO - PROCESS | 1660 | RSS: Main: 241729536 09:09:14 INFO - PROCESS | 1660 | 09:09:17 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 09:09:17 INFO - PROCESS | 1660 | RSS: Main: 249417728 09:09:17 INFO - PROCESS | 1660 | 09:09:21 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 09:09:21 INFO - PROCESS | 1660 | RSS: Main: 253194240 09:09:21 INFO - PROCESS | 1660 | 09:09:23 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 09:09:24 INFO - PROCESS | 1660 | RSS: Main: 253349888 09:09:24 INFO - PROCESS | 1660 | 09:09:26 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:09:27 INFO - PROCESS | 1660 | RSS: Main: 253362176 09:09:27 INFO - PROCESS | 1660 | 09:09:30 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 09:09:30 INFO - PROCESS | 1660 | RSS: Main: 257404928 09:09:30 INFO - PROCESS | 1660 | 09:09:33 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 09:09:34 INFO - PROCESS | 1660 | RSS: Main: 258035712 09:09:34 INFO - PROCESS | 1660 | 09:09:37 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 09:09:37 INFO - PROCESS | 1660 | RSS: Main: 258449408 09:09:37 INFO - PROCESS | 1660 | 09:09:39 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:09:39 INFO - PROCESS | 1660 | RSS: Main: 257114112 09:09:39 INFO - PROCESS | 1660 | 09:09:41 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:09:42 INFO - PROCESS | 1660 | RSS: Main: 257212416 09:09:42 INFO - PROCESS | 1660 | 09:09:44 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:09:45 INFO - PROCESS | 1660 | RSS: Main: 257232896 09:09:45 INFO - PROCESS | 1660 | 09:09:48 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:09:48 INFO - PROCESS | 1660 | RSS: Main: 257220608 09:09:48 INFO - PROCESS | 1660 | 09:09:51 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:09:52 INFO - PROCESS | 1660 | RSS: Main: 257650688 09:09:52 INFO - PROCESS | 1660 | 09:09:53 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 09:09:54 INFO - PROCESS | 1660 | RSS: Main: 259403776 09:09:54 INFO - PROCESS | 1660 | 09:09:54 INFO - PROCESS | 1660 | __start_tp_report 09:09:54 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 09:09:54 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 09:09:54 INFO - PROCESS | 1660 | |i|pagename|runs| 09:09:54 INFO - PROCESS | 1660 | |0;ai-astar;105;118;119;136;177;124;118;119;117;118 09:09:54 INFO - PROCESS | 1660 | |1;audio-beat-detection;148;140;143;179;112;111;111;113;140;111 09:09:54 INFO - PROCESS | 1660 | |2;audio-dft;198;195;197;198;193;194;196;225;272;271 09:09:54 INFO - PROCESS | 1660 | |3;audio-fft;79;60;66;106;86;86;86;61;78;55 09:09:54 INFO - PROCESS | 1660 | |4;audio-oscillator;87;69;88;71;79;104;91;69;72;89 09:09:54 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;136;133;89;129;91;89;89;86;89;75 09:09:54 INFO - PROCESS | 1660 | |6;imaging-darkroom;96;105;96;95;96;97;95;95;94;96 09:09:54 INFO - PROCESS | 1660 | |7;imaging-desaturate;88;99;87;88;92;89;112;121;89;87 09:09:54 INFO - PROCESS | 1660 | |8;json-parse-financial;54;57;57;61;60;61;60;61;60;60 09:09:54 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;67;75;75;77;76;67;72;73;71;71 09:09:54 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;86;84;100;85;85;86;85;83;88;85 09:09:54 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;148;157;145;140;145;144;146;153;197;142 09:09:54 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;170;164;167;184;166;169;164;167;163;167 09:09:54 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;70;69;69;68;68;69;71;68;68;67 09:09:54 INFO - PROCESS | 1660 | __end_tp_report 09:09:54 INFO - PROCESS | 1660 | __start_cc_report 09:09:54 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,969 09:09:54 INFO - PROCESS | 1660 | __end_cc_report 09:09:54 INFO - PROCESS | 1660 | __startTimestamp1483981794174__endTimestamp 09:09:54 INFO - PROCESS | 1660 | 09:09:54 INFO - PROCESS | 1660 | ------- Summary: start ------- 09:09:54 INFO - PROCESS | 1660 | Number of tests: 14 09:09:54 INFO - PROCESS | 1660 | 09:09:54 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:125.10 Median:119.00 stddev:19.75 (16.6%) stddev-sans-first:19.56 09:09:54 INFO - PROCESS | 1660 | Values: 105.0 118.0 119.0 136.0 177.0 124.0 118.0 119.0 117.0 118.0 09:09:54 INFO - PROCESS | 1660 | 09:09:54 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:130.80 Median:140.00 stddev:23.05 (16.5%) stddev-sans-first:23.60 09:09:54 INFO - PROCESS | 1660 | Values: 148.0 140.0 143.0 179.0 112.0 111.0 111.0 113.0 140.0 111.0 09:09:54 INFO - PROCESS | 1660 | 09:09:54 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:213.90 Median:198.00 stddev:31.73 (16.0%) stddev-sans-first:33.13 09:09:54 INFO - PROCESS | 1660 | Values: 198.0 195.0 197.0 198.0 193.0 194.0 196.0 225.0 272.0 271.0 09:09:54 INFO - PROCESS | 1660 | 09:09:54 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:76.30 Median:78.50 stddev:15.76 (20.1%) stddev-sans-first:16.68 09:09:54 INFO - PROCESS | 1660 | Values: 79.0 60.0 66.0 106.0 86.0 86.0 86.0 61.0 78.0 55.0 09:09:54 INFO - PROCESS | 1660 | 09:09:54 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:81.90 Median:83.00 stddev:11.75 (14.2%) stddev-sans-first:12.32 09:09:54 INFO - PROCESS | 1660 | Values: 87.0 69.0 88.0 71.0 79.0 104.0 91.0 69.0 72.0 89.0 09:09:54 INFO - PROCESS | 1660 | 09:09:54 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:100.60 Median:89.00 stddev:22.63 (25.4%) stddev-sans-first:20.05 09:09:54 INFO - PROCESS | 1660 | Values: 136.0 133.0 89.0 129.0 91.0 89.0 89.0 86.0 89.0 75.0 09:09:54 INFO - PROCESS | 1660 | 09:09:54 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:96.50 Median:96.00 stddev:3.10 (3.2%) stddev-sans-first:3.28 09:09:54 INFO - PROCESS | 1660 | Values: 96.0 105.0 96.0 95.0 96.0 97.0 95.0 95.0 94.0 96.0 09:09:54 INFO - PROCESS | 1660 | 09:09:54 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:88.50 stddev:11.96 (13.5%) stddev-sans-first:12.40 09:09:54 INFO - PROCESS | 1660 | Values: 88.0 99.0 87.0 88.0 92.0 89.0 112.0 121.0 89.0 87.0 09:09:54 INFO - PROCESS | 1660 | 09:09:54 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:59.10 Median:60.00 stddev:2.33 (3.9%) stddev-sans-first:1.58 09:09:54 INFO - PROCESS | 1660 | Values: 54.0 57.0 57.0 61.0 60.0 61.0 60.0 61.0 60.0 60.0 09:09:54 INFO - PROCESS | 1660 | 09:09:54 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:74.00 stddev:3.50 (4.7%) stddev-sans-first:3.12 09:09:54 INFO - PROCESS | 1660 | Values: 67.0 75.0 75.0 77.0 76.0 67.0 72.0 73.0 71.0 71.0 09:09:54 INFO - PROCESS | 1660 | 09:09:54 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:85.00 stddev:4.85 (5.7%) stddev-sans-first:5.14 09:09:54 INFO - PROCESS | 1660 | Values: 86.0 84.0 100.0 85.0 85.0 86.0 85.0 83.0 88.0 85.0 09:09:54 INFO - PROCESS | 1660 | 09:09:54 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:151.70 Median:147.00 stddev:16.69 (11.4%) stddev-sans-first:17.65 09:09:54 INFO - PROCESS | 1660 | Values: 148.0 157.0 145.0 140.0 145.0 144.0 146.0 153.0 197.0 142.0 09:09:54 INFO - PROCESS | 1660 | 09:09:54 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.10 Median:167.00 stddev:6.01 (3.6%) stddev-sans-first:6.33 09:09:54 INFO - PROCESS | 1660 | Values: 170.0 164.0 167.0 184.0 166.0 169.0 164.0 167.0 163.0 167.0 09:09:54 INFO - PROCESS | 1660 | 09:09:54 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.00 stddev:1.16 (1.7%) stddev-sans-first:1.13 09:09:54 INFO - PROCESS | 1660 | Values: 70.0 69.0 69.0 68.0 68.0 69.0 71.0 68.0 68.0 67.0 09:09:54 INFO - PROCESS | 1660 | -------- Summary: end -------- 09:09:54 INFO - PROCESS | 1660 | 09:09:55 INFO - TEST-INFO | 1660: exit 0 09:09:55 INFO - TEST-OK | kraken | took 61113ms 09:09:55 INFO - SUITE-END | took 1178s 09:09:55 INFO - Completed test suite (00:19:38) 09:09:55 INFO - javascript benchmark 09:09:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7167.33661501575, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3351.899443016709, 3380.3915295246848, 3382.7124694400973, 3426.7772594781336, 3441.5353927219826, 5016.974915125428, 5604.887902241952, 5782.450667186621, 5806.477417032466, 5867.237259156309, 5012.949870501294, 5703.5722320826, 5791.565632577553, 5838.532917366611, 5847.356790753014, 4426.159029784347, 5190.584753219733, 5255.1329030709985, 5312.362516498026, 5351.0, 4827.51724827518, 4871.782054486378, 4951.975240123812, 4989.301497790312, 5428.4842939920645, 4659.881628409191, 5015.874603134915, 5203.791848326063, 5204.401493828202, 5340.706261155635, 4447.599716025557, 5158.252053452247, 5251.0, 5295.655782374172, 5320.893582128375, 5157.149070403379, 5225.320708307933, 5237.083510385667, 5264.0, 5307.734613269321, 5942.524598032147, 5949.583529152961, 5954.07711804671, 5967.224260846106, 5992.790252341147, 4586.243269860469, 5597.076482380402, 6052.909206361908, 6250.1874756281395, 6329.955557333081, 6115.818436018264, 6117.15760190703, 6147.077938309245, 6181.443670069715, 6185.319614842364, 5827.155062515912, 5859.736311866019, 5900.793472228537, 5900.911486327708, 5912.054071348563, 5108.59131269505, 6315.778947736807, 6317.684115794192, 6325.304216536178, 6335.334789847092, 6109.0, 6131.785387511416, 6136.846578835565, 6143.020634992025, 6158.568900176944, 6217.222847144107, 6224.657643829542, 6234.594751341148, 6238.812835614939, 6242.375762423721, 6120.326764055951, 6207.162033125561, 6239.78160764371, 6241.843953901098, 6244.000959846403, 5264.868378290574, 5987.1318658794235, 6078.510764862637, 6084.908726369108, 6213.316535181127, 4988.775505102279, 4992.176290911959, 5018.974905125524, 5019.899602008012, 5062.265971434122, 3740.6259374062374, 4438.533953934855, 4462.397576327219, 4463.642908567307, 4467.705768664144, 5233.398159211663, 5701.116326969326, 5904.1734157218025, 5966.0, 5978.432048955342, 6137.285055221596, 6138.263408391022, 6171.907421388682, 6224.097505861715, 6227.252729672377, 5831.358550559435, 5977.193078934288, 5982.790602328984, 5989.490893274034, 6003.339632640406, 5424.349078110652, 5436.157395603688, 5443.972780136074, 5477.123660214426, 5503.6147469677535, 6159.23009623797, 6434.356564343622, 6446.580972236789, 6448.451881590117, 6465.127207827071, 6109.495762817132, 6114.388610881841, 6142.529470848956, 6160.907586386297, 6242.344553821965, 6411.551191416556, 6417.614943103429, 6420.06908998202, 6424.357564243538, 6447.161868957006, 6213.65824879636, 6218.9378106218355, 6225.719842607003, 6241.78153764616, 6258.155149054819, 6127.816165515042, 6135.385782596045, 6155.169052177898, 6175.073738939195, 6206.131141640084, 6158.199434073542, 6158.507319414526, 6158.784442544489, 6173.259208894961, 6182.041783523561, 4513.819447222074, 6030.276366836008, 6092.847678808065, 6225.844353891189, 6254.186955695731, 5611.775528978795, 5677.063284558001, 5816.563757718103, 5894.351621321565, 5924.348321684697, 5059.241113832954, 6042.90935635957, 6056.152138700673, 6109.43503133753, 6119.015143218386, 5094.802721360551, 6043.909341359795, 6066.605670631395, 6085.300190478151, 6105.84735381619], "value": 5658.928036583571, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.6840685147801, 513.8278676643324, 520.9270702101707, 528.4134610582253, 545.8553483326916, 31702.0, 37867.81066094669, 39683.80158099213, 39705.4044189337, 41998.37002444966, 32055.35889282213, 33526.329473410515, 40682.38976415356, 40986.0, 41305.7934710326, 9217.723468295944, 9378.718638440823, 9419.058094190561, 9422.764430889214, 9447.905520944805, 9356.345055846094, 9442.10300021497, 9444.716658500256, 9474.573644185994, 9510.239180865548, 9407.20038796703, 9449.055094490564, 9483.19392851605, 9485.288603354742, 9534.237261019136, 9393.906060939371, 9451.574679139387, 9459.432434053944, 9474.431534107907, 9478.668246611336, 9275.165235128803, 9358.344915855892, 9375.203107735852, 9394.436333819993, 9395.906040939572, 38999.220015599814, 39104.804475977435, 39364.81905542839, 39372.0, 39503.012424689325, 3179.1257404213793, 3448.6551344865566, 3610.548681414823, 3643.981780091083, 3834.405667121601, 23467.0, 28921.1669770329, 29300.0, 29512.8524357379, 30222.395552089056, 3139.419207446607, 3186.2193762528327, 3195.744340452758, 3204.6154461464776, 3206.8396580170897, 497365.0, 501058.0, 501629.0, 504509.0, 504810.0, 56947.43052569421, 57057.714711426175, 57170.0, 57212.0, 57253.71373143191, 58533.70733146392, 59195.0, 59237.0, 59241.703791480766, 59340.10989835156, 15696.372145114296, 15940.601484962968, 15975.920120399323, 15981.520554383387, 15983.881128321138, 12350.876491235153, 12355.75288494225, 12425.005999520017, 12456.439460224312, 12471.937640311922, 12950.222986620833, 14581.0, 15259.542213733606, 15292.0, 15392.230388480755, 3228.8224147672104, 6582.506312026617, 6799.660016999131, 6831.863362732817, 6834.282400347991, 832.5004997001871, 994.751312171957, 1015.3603229965222, 1025.4821315235758, 1096.5997410944947, 29688.109356719335, 29741.702582974325, 29746.107616771533, 30041.849790750908, 30190.0, 1032.3444612671008, 1041.6406339812752, 1044.6448207609449, 1050.5010120193, 1081.9080378167787, 15107.622309442351, 15278.694426111415, 15294.388224471119, 15304.540863774104, 15412.691746165014, 682712.0, 958365.0, 972634.0, 976431.0, 978668.0, 56123.71938140283, 56391.0, 56516.717416412655, 56643.43356566464, 56737.71631141818, 1246.5823948977195, 1368.6988862450246, 1388.4723804954053, 1405.0235086614705, 1410.9929450352683, 25107.87446062831, 28884.855575721987, 29877.0, 30287.0, 30471.847640761654, 53245.733771332445, 53940.730296348265, 54131.0, 54181.0, 54351.184732228256, 631371.0, 634195.0, 642241.0, 642720.0, 642888.7855560692, 35782.0, 40825.0, 41758.37362439505, 41776.373354399104, 41992.79003605084, 37900.86297411082, 45223.7738811317, 45230.095398091195, 45231.54768452273, 45672.0, 33933.49099763555, 34658.78694245689, 37604.623953761205, 38021.04947376338, 39458.60541394549, 33422.32888355621, 37543.24913501769, 37876.62123378731, 38607.42088868725, 39008.21983560256, 29817.10548683543, 37031.62968370282, 37336.253274933806, 37620.62379376171, 37691.62308376991, 31932.680673192972, 32716.0, 33920.15199620029, 34087.4886876702, 34138.65861341355, 21762.0, 25444.872775636002, 25523.74476255288, 25694.743052569982, 26086.478270434105], "value": 19827.035433234356, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.9557555305587, 373.3037884345694, 386.4512392402789, 386.94582758413844, 403.9373897045959, 18216.271349146034, 19355.322563710273, 20171.697424538626, 20332.491687707778, 20339.694904576445, 18862.811371886277, 19319.710204346946, 19467.221311147572, 19899.60200795983, 20329.085191166396, 5799.391063938278, 5822.534197264209, 5857.502112320458, 6010.75956961721, 6025.608335458203, 5827.708614569276, 5910.822675319747, 5954.4938680212235, 5962.880742385171, 5968.4329988651225, 5784.959106323546, 5835.854103647401, 5878.294604647448, 5893.38119497453, 5900.645961242318, 5690.345610254812, 5881.970655135334, 5885.823425297248, 5890.734916928748, 5890.734916928748, 4973.0, 5132.255822905658, 5175.741212939338, 5184.611154163415, 5200.843974680765, 24083.277501674802, 24312.149074782297, 24494.755052449604, 24717.25848224556, 24789.752102478924, 2301.2981040782556, 2442.096424322994, 2456.520978409211, 2584.3022383956413, 3096.349766549027, 15063.623409414742, 15111.39554417822, 15196.0, 15333.775276754544, 15853.60365990848, 1833.6241070580631, 1850.5743678953781, 1861.3206179744432, 1885.8962757048357, 1923.6345094432013, 107753.0, 111106.0, 111563.44218278857, 111887.0, 112067.0, 8777.824443511094, 10900.291481053706, 11231.708353539447, 11380.487878045507, 11461.197716159788, 11479.426028698532, 13854.722905541832, 13868.653283667989, 13979.720405591832, 14008.08947418435, 8529.104444033288, 8561.229489345864, 8564.0, 8636.611352489153, 8666.263367613697, 7397.482176247611, 8333.916660833314, 8513.531755753493, 8551.187637174458, 8706.608202630898, 6576.408123268929, 6769.0, 6780.525363224527, 6786.287439818748, 6821.317868213238, 1869.4391682495198, 1897.2790339670837, 1920.9519762006062, 1938.1569017477443, 1943.9611207776047, 848.249299370062, 876.4697358098387, 891.1934699097327, 920.9355345125911, 1021.7649940513724, 20482.283120091022, 21590.568188636138, 21625.45936351572, 21934.780652193593, 21969.340919772112, 807.1610693890166, 944.3521055757609, 969.9272554558436, 971.3734641156556, 1001.6344034427382, 8948.8657670135, 9571.952140239255, 9751.829780426395, 11235.427040214376, 11511.942440287912, 526473.0, 568576.0, 577971.0, 599879.0006050029, 622139.0, 105955.47022264994, 109580.0, 124059.0, 125950.0, 127145.0, 426.3370458936322, 459.226203846513, 461.8614415675284, 463.7657982718638, 465.8160026789399, 10933.687957445158, 10950.452477376259, 11244.718882028015, 11555.537778488766, 11571.13216508782, 38993.0, 51936.0, 52235.694107647614, 52683.47316526939, 52889.735551321995, 75831.86252206111, 77582.0, 77991.0, 78122.0, 78438.82341764764, 8176.141505141874, 8198.631061602362, 8248.422610417214, 8505.106963768963, 8563.657453701782, 9265.305102117234, 12290.385480725748, 12491.500339986298, 12847.807282890577, 13090.14914030585, 11285.37930413835, 11308.886911131112, 11478.02436792882, 11661.591844285409, 11781.52873885035, 10536.262461627612, 11032.227744057838, 11262.887371126511, 11278.943605281922, 11435.428228588371, 11437.542498300307, 11494.94252528732, 11496.597619083292, 11517.366544839779, 11821.527138914691, 7937.920620793718, 8250.669973201246, 8436.704715334934, 8456.196661317164, 8540.871886921825, 8096.433249672466, 9436.669716559887, 11327.32036077838, 11343.092552595608, 11435.913588209107], "value": 9317.589327563299, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1471.1834931612955, 1514.765211392234, 1690.8900921440106, 1803.5400972751947, 1828.305244007277, 7613.923860761391, 7889.053313602376, 8144.348452123831, 8555.78610534737, 8576.97076350838, 7116.430685545157, 8030.718924837632, 8402.705905293316, 8448.577571121436, 8534.061253262153, 1792.9193186306647, 2001.269536619138, 2343.0979073056824, 2344.742078371378, 2351.75306592807, 2315.4674424882287, 2345.542619189259, 2372.620380739082, 2373.2405630198346, 2381.273711517986, 2318.675385446039, 2329.871857047862, 2345.308134100436, 2363.243761996162, 2365.6924599802, 2326.348622385735, 2332.195392589554, 2340.040583360831, 2343.168175297762, 2350.1304517328526, 2025.574629327843, 2341.285907798121, 2359.775821296991, 2363.1847012780563, 2372.9169479068323, 6535.346465353474, 7909.24862138096, 8514.148585141436, 8514.829703405898, 8541.743747687578, 4151.97924010378, 5158.40678321994, 5164.251183578398, 5164.715940623302, 5183.585313174937, 3330.417176994041, 3490.7905525668543, 3591.28174365128, 3625.166211771281, 3638.308721342963, 1054.9208809339254, 1069.0, 1089.6186334782842, 1106.1537923488538, 1114.587602587048, 9575.850897892227, 10987.670369888916, 10988.340699557972, 11152.052075573585, 11159.832602510887, 4542.841000564964, 4692.577668009895, 4724.787384567703, 4742.762861856962, 4787.234042553175, 9467.857982130274, 9511.57197926095, 9517.714468565953, 9531.38046027006, 9549.331546791796, 5215.895682086413, 5240.475952404805, 5249.107651699221, 5257.211418287211, 5266.210068489757, 4144.585541445831, 4769.57073863354, 4806.903861922812, 4813.831515896927, 4891.559759621651, 3206.583144191287, 3841.7118716096406, 3861.6717579005535, 3913.8630147944687, 3936.5472970608175, 1279.3795009420423, 1279.6672865054966, 1286.8584455709865, 1295.656650987489, 1298.5519995601503, 749.3443237167478, 849.3163003782015, 885.7652722028668, 904.5884122724144, 907.4691305586174, 5597.188407680864, 5691.857703557444, 5706.914396284059, 5738.0, 5937.317208520989, 890.7416849113685, 893.6461261189304, 895.2793001633747, 904.7604781449324, 926.7683079230193, 3930.4890364252465, 3992.7005474589523, 4025.4364388985564, 4029.4157347184496, 4040.8787736367367, 10042.598296068221, 10228.6419975302, 10244.436555989343, 10265.897341026643, 10301.484925753683, 10372.688819335432, 10377.481125943672, 10384.013518715861, 10394.48027598617, 10525.789484210272, 7471.215522370181, 7804.2585954334245, 7812.609369531614, 7906.52560846351, 8052.5571093590415, 5790.247267855152, 5862.794802181903, 5920.496757775637, 5972.731227094704, 5978.312494063064, 9258.0, 9374.906250937403, 9409.529523523659, 9535.38020028696, 9569.186619137174, 9472.431654100776, 9627.326087173831, 9640.035996400304, 9757.80484390294, 9801.362911410735, 7232.204457509775, 7275.345218930322, 7296.0, 7360.447966402434, 7393.778186654409, 7879.0, 7969.282764551218, 7975.641096150571, 7999.320057795153, 8000.999875015623, 7095.219525852049, 7187.6765545551625, 7261.455390845602, 7301.5984120871735, 7334.779956601311, 6962.268961758942, 7056.294370562902, 7075.893861592183, 7183.964080179566, 7193.100862392201, 5734.311882574118, 6114.419130182626, 6728.6972086255255, 6785.762498312535, 6801.489888258301, 4087.6116768906904, 4111.09555897702, 4416.4258646375765, 4876.878078048077, 4887.34020907173, 4854.660173787801, 4871.123197824284, 4909.828156014522, 4993.600511958962, 5018.42288136869], "value": 4443.758044852602, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.99702002979967, 303.55680706168994, 308.9830059346735, 312.838887972694, 319.2895806829804, 4549.840755573556, 4851.708897466155, 5338.06583847826, 5513.779448822047, 5604.663720176787, 4553.975355545001, 5244.973775131129, 5336.946630533703, 5393.896380411633, 5469.671819690821, 4136.979315103428, 4189.853355132571, 4194.916101677964, 4211.241976444238, 4216.2199993001395, 3867.671247943928, 3914.256291304657, 4218.0, 4220.894477638069, 4254.362520045363, 3588.946165807502, 3605.531280933475, 3770.944135642025, 4116.794160291988, 4138.33786594145, 3278.491833765758, 3674.669279764834, 4049.007993041694, 4095.8122144578037, 4142.917141657165, 3283.4582293921476, 3290.424175769255, 3530.5586801649793, 3558.857645694169, 3562.4834399011997, 5098.872528186788, 5182.922256166123, 5190.494756520605, 5323.467653234646, 5383.865403364908, 4106.301928672133, 4401.205234675972, 4620.561046700592, 4726.361941137937, 4727.45634252062, 4786.904261914777, 5760.2511673482595, 5787.363390027136, 5801.7389217485315, 5815.0, 3677.9080522986874, 3876.3604005339084, 3907.2576210520197, 3915.902102447433, 3923.8234279457206, 5098.974505127488, 5518.944810551923, 5795.855533452607, 6167.885217964944, 6168.444839964424, 3539.805310707936, 3982.944519702281, 3983.8008099594904, 3991.982044578614, 4003.6997225208224, 3392.4741665041956, 3918.4725851825415, 4030.0, 4035.9798201008807, 4040.1313717550865, 4697.365855609517, 4700.835470758507, 4703.048234982512, 4728.787204575802, 4749.216379297377, 5808.854778630568, 5837.912431313449, 5877.764889404461, 5894.934633990301, 5904.022152578934, 1060.9522571484301, 1193.4987305331608, 1239.7210627608715, 1241.2924632959307, 1255.0, 788.873780195166, 792.298815548244, 844.9197326253995, 859.9183077607618, 889.8042430665244, 5072.706459852788, 5086.796528138833, 5103.974480127576, 5106.412762532282, 5114.925865568198, 5062.443131255559, 5594.41258667834, 5616.522595579339, 5623.859403514863, 5646.774129034792, 4238.3430568261965, 4725.771299462096, 5080.796768129233, 5107.591392688576, 5135.948640513622, 2399.83201175916, 2928.5443177050856, 2931.6921723218757, 2945.262295245815, 2953.867075981586, 5670.603057785998, 5785.466851284413, 5787.102999035156, 5842.5618078644275, 5881.823545293648, 4828.927566086511, 4916.778744956486, 5215.009148261818, 5432.402435732066, 5450.836474905759, 4921.729304888194, 5158.63889527737, 5217.973910130425, 5437.048516509674, 5811.854703632443, 4560.771961401983, 4705.941163238244, 4849.0, 5284.339457567804, 5433.402325744086, 5070.467600901852, 5254.845513339513, 5530.751116199861, 5572.91640625399, 5578.852141925307, 4913.0, 5036.546710795899, 5488.0, 5571.777128914798, 5897.3807750185615, 3642.3990041642833, 3765.472833803215, 3780.773153610792, 3791.9052023698523, 3803.7337386382687, 4937.35814344133, 5083.898322033465, 5100.795968161232, 5123.436421993503, 5557.249771280825, 4379.3504929517185, 4727.787249573847, 5113.64204505681, 5434.3207099112615, 5670.971645141748, 5106.038497671268, 5263.499967503081, 5824.184614153937, 5829.417058294137, 5832.0, 4975.68144441716, 5162.793488260428, 5301.840944771663, 5597.916031259453, 5732.942670573241, 5309.831836995855, 5616.315105468261, 5752.741126649395, 5790.481140703807, 5804.796832110856, 4956.89332033877, 5189.455107213839, 5581.6930068846605, 5653.40639232891, 5711.857203569944], "value": 4045.9670985149355, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.1188235000374, 702.1539045450234, 743.8884167374894, 762.8131107878571, 794.4161041634397, 13988.09077409969, 14809.111453312782, 14816.481423150191, 14823.110613363206, 14850.108993460386, 13409.66475838102, 13431.59705208843, 13446.26045567496, 13459.730805383886, 13474.797878031814, 643.5655932245738, 670.3095811314354, 796.9481983671072, 822.5517093184229, 841.8232171244016, 829.9460535065201, 830.4933990265932, 830.8919840420767, 832.0930186097153, 833.2792134803382, 874.8425283448977, 877.2017464107663, 878.6221924572432, 882.2809410330592, 883.041899539001, 852.8848605438279, 868.4398562926876, 871.2072014466836, 875.6322344615277, 877.7147427086222, 554.2960440240912, 618.8143556932947, 642.7300533775825, 647.197475130839, 653.8005908197998, 11444.542218311257, 12539.808718171758, 12562.623121306375, 13767.655808604764, 13985.58043258704, 12779.936100319439, 13245.271510066943, 13330.066895317332, 13354.332283385791, 13356.998225133057, 9425.34022618417, 11021.0, 11052.723681907935, 11490.770184596346, 11775.857596136562, 3514.3147086318177, 3558.217192217708, 3646.6717995380545, 3659.451082337644, 3788.1666033472866, 84358.15641843564, 84998.57500712457, 86971.69542456868, 87969.0, 89307.0, 8773.956130219436, 9978.351407158512, 10153.0, 10365.792684146274, 10598.046175844056, 19904.90047549782, 19974.90012549957, 20100.0, 20179.697304540445, 20205.39383818488, 6895.34494223048, 7133.785986420416, 7587.886181707278, 7592.468527203153, 7618.4667073305445, 3562.305350456688, 4119.114390406017, 4271.658267338606, 4279.529251782302, 4295.548967358445, 3331.3170799985946, 3416.1118109291765, 3416.84624191907, 3423.760336776402, 3465.5321531593418, 643.1317721076509, 647.7506160128385, 649.0750680280581, 660.2242365220847, 662.2715013485127, 581.6190395291092, 589.8820235952741, 626.1859582542676, 647.6044124910825, 679.2392520377209, 8825.955870220609, 8994.86507702385, 9028.322875784343, 9133.68032118886, 9384.577694003787, 659.4262991197593, 662.4336192555392, 680.2245440198178, 680.5236334565725, 692.1486571517062, 4090.734102283342, 4212.7472351659, 4280.229558679405, 4597.816087356535, 4618.307253911873, 93851.53074234586, 102708.0, 105234.0, 106578.0, 110060.0, 67148.66425667937, 84911.5754421224, 93159.53420232855, 97816.51091744496, 98444.0, 84567.57716211502, 86203.0, 86368.0, 87465.25069498575, 88387.0, 8526.531040792815, 8824.647014119364, 9549.713508594754, 9630.0, 9796.265280103878, 40959.79520102381, 46695.533044669115, 47078.0, 48529.75735121302, 49319.0, 71764.28235717576, 73908.89136663062, 76101.09747256363, 76436.61781691056, 77160.61419692865, 12022.819657705317, 12197.939010304892, 12250.632481025226, 12360.320182390054, 12561.811572826233, 13610.863891361356, 13838.169709817443, 14221.64445888861, 14381.496647617283, 14602.977791554915, 12760.553380631633, 13560.11859229147, 13630.591082267549, 14048.719025619635, 14215.076020058663, 13451.0, 13584.592462226148, 13945.0, 14176.716465670423, 14187.929060354632, 13356.732865342834, 13384.799228011392, 13408.061435699407, 13599.184048956698, 13841.861581384057, 7891.881621775563, 8856.601452934763, 10272.743181420525, 11750.353730544903, 12067.069268916932, 11207.943960280147, 11491.367974761133, 11620.244684095846, 11628.825567616323, 11770.17608767378], "value": 7211.598866143608, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1527.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 119.0, 136.0, 177.0, 124.0, 118.0, 119.0, 117.0, 118.0], "value": 125.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 140.0, 143.0, 179.0, 112.0, 111.0, 111.0, 113.0, 140.0, 111.0], "value": 130.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 195.0, 197.0, 198.0, 193.0, 194.0, 196.0, 225.0, 272.0, 271.0], "value": 213.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 66.0, 106.0, 86.0, 86.0, 86.0, 61.0, 78.0, 55.0], "value": 76.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 88.0, 71.0, 79.0, 104.0, 91.0, 69.0, 72.0, 89.0], "value": 81.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 133.0, 89.0, 129.0, 91.0, 89.0, 89.0, 86.0, 89.0, 75.0], "value": 100.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 105.0, 96.0, 95.0, 96.0, 97.0, 95.0, 95.0, 94.0, 96.0], "value": 96.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 99.0, 87.0, 88.0, 92.0, 89.0, 112.0, 121.0, 89.0, 87.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 57.0, 57.0, 61.0, 60.0, 61.0, 60.0, 61.0, 60.0, 60.0], "value": 59.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 75.0, 75.0, 77.0, 76.0, 67.0, 72.0, 73.0, 71.0, 71.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 100.0, 85.0, 85.0, 86.0, 85.0, 83.0, 88.0, 85.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 157.0, 145.0, 140.0, 145.0, 144.0, 146.0, 153.0, 197.0, 142.0], "value": 151.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 164.0, 167.0, 184.0, 166.0, 169.0, 164.0, 167.0, 163.0, 167.0], "value": 168.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 69.0, 68.0, 68.0, 69.0, 71.0, 68.0, 68.0, 67.0], "value": 68.7, "unit": "score"}]}]} 09:09:55 INFO - Return code: 0 09:09:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:09:55 ERROR - # TBPL SUCCESS # 09:09:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:09:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:09:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:09:55 INFO - 2017-01-09 09:09:55.532 screenresolution[1665:11310] starting screenresolution argv=screenresolution get 09:09:55 INFO - 2017-01-09 09:09:55.536 screenresolution[1665:11310] Display 0: 1600x1200x32@60 09:09:55 INFO - 2017-01-09 09:09:55.542 screenresolution[1666:11313] starting screenresolution argv=screenresolution list 09:09:55 INFO - Available Modes on Display 0 09:09:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:09:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:09:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:09:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:09:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:09:55 INFO - Intel Iris: 09:09:55 INFO - Chipset Model: Intel Iris 09:09:55 INFO - Type: GPU 09:09:55 INFO - Bus: Built-In 09:09:55 INFO - VRAM (Dynamic, Max): 1536 MB 09:09:55 INFO - Vendor: Intel (0x8086) 09:09:55 INFO - Device ID: 0x0a2e 09:09:55 INFO - Revision ID: 0x0009 09:09:55 INFO - Displays: 09:09:55 INFO - Display: 09:09:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:09:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:09:55 INFO - Display Serial Number: 1600x1200 60 09:09:55 INFO - Main Display: Yes 09:09:55 INFO - Mirror: Off 09:09:55 INFO - Online: Yes 09:09:55 INFO - Rotation: Supported 09:09:55 INFO - Return code: 0 09:09:55 INFO - Running post-action listener: _resource_record_post_action 09:09:55 INFO - [mozharness: 2017-01-09 17:09:55.788198Z] Finished run-tests step (success) 09:09:55 INFO - Running post-run listener: _resource_record_post_run 09:09:55 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 34378240; Write bytes: 661579264; Read time: 315; Write time: 1913 09:09:55 INFO - TinderboxPrint: CPU usage
54.5% 09:09:55 INFO - TinderboxPrint: I/O read bytes / time
34,378,240 / 315 09:09:55 INFO - TinderboxPrint: I/O write bytes / time
661,579,264 / 1,913 09:09:55 INFO - TinderboxPrint: CPU idle
2,201.9 (45.5%) 09:09:55 INFO - TinderboxPrint: CPU system
981.7 (20.3%) 09:09:55 INFO - TinderboxPrint: CPU user
1,658.1 (34.2%) 09:09:55 INFO - TinderboxPrint: Swap in / out
379,854,848 / 0 09:09:55 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 346339840; Write bytes: 344963072; Read time: 26867; Write time: 620 09:09:55 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 32309760; Write bytes: 309460480; Read time: 293; Write time: 1264 09:09:56 INFO - Running post-run listener: _upload_blobber_files 09:09:56 INFO - Blob upload gear active. 09:09:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:09:56 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:09:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:09:56 INFO - (blobuploader) - INFO - Open directory for files ... 09:09:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:09:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:09:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:57 INFO - (blobuploader) - INFO - Done attempting. 09:09:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:09:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:09:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:58 INFO - (blobuploader) - INFO - Done attempting. 09:09:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:09:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:10:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:10:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:10:01 INFO - (blobuploader) - INFO - Done attempting. 09:10:01 INFO - (blobuploader) - INFO - Iteration through files over. 09:10:01 INFO - Return code: 0 09:10:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:10:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:10:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cb19709b48c406a6773583fe8f2bb0a133fbebd0910e16590d3f37b0b87fb95d33c9cee7e86af22296f79b08709995e17334dd0f621d76c6f7acd4bbb495afb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fd9d6eb0495f50eeb86fda2bd33ba0e221d496d707d0077767b989d78d6c7513ec5a19de3e82a5f7ee11c31fe0faa7096b94f85e4c8f5e0099f2c9ad3519dc7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/533fcec3ff0f1ad0531131126d6b9dcd6dfa32b689cf18914b8e2f5a19d11dae61022736246d9052fdff5acfd3def67e0532ddcc1897834c972b513c68d14e9c"} 09:10:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:10:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:10:01 INFO - Contents: 09:10:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cb19709b48c406a6773583fe8f2bb0a133fbebd0910e16590d3f37b0b87fb95d33c9cee7e86af22296f79b08709995e17334dd0f621d76c6f7acd4bbb495afb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fd9d6eb0495f50eeb86fda2bd33ba0e221d496d707d0077767b989d78d6c7513ec5a19de3e82a5f7ee11c31fe0faa7096b94f85e4c8f5e0099f2c9ad3519dc7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/533fcec3ff0f1ad0531131126d6b9dcd6dfa32b689cf18914b8e2f5a19d11dae61022736246d9052fdff5acfd3def67e0532ddcc1897834c972b513c68d14e9c"} 09:10:01 INFO - Running post-run listener: copy_logs_to_upload_dir 09:10:01 INFO - Copying logs to upload dir... 09:10:01 INFO - mkdir: /builds/slave/test/build/upload/logs 09:10:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.418323 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-09 09:10:01.101328) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 09:10:01.105434) ========= 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.fsgAFdmdYk/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.AKvXAJuBfc/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cb19709b48c406a6773583fe8f2bb0a133fbebd0910e16590d3f37b0b87fb95d33c9cee7e86af22296f79b08709995e17334dd0f621d76c6f7acd4bbb495afb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fd9d6eb0495f50eeb86fda2bd33ba0e221d496d707d0077767b989d78d6c7513ec5a19de3e82a5f7ee11c31fe0faa7096b94f85e4c8f5e0099f2c9ad3519dc7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/533fcec3ff0f1ad0531131126d6b9dcd6dfa32b689cf18914b8e2f5a19d11dae61022736246d9052fdff5acfd3def67e0532ddcc1897834c972b513c68d14e9c"} build_url:https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011184 build_url: 'https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cb19709b48c406a6773583fe8f2bb0a133fbebd0910e16590d3f37b0b87fb95d33c9cee7e86af22296f79b08709995e17334dd0f621d76c6f7acd4bbb495afb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fd9d6eb0495f50eeb86fda2bd33ba0e221d496d707d0077767b989d78d6c7513ec5a19de3e82a5f7ee11c31fe0faa7096b94f85e4c8f5e0099f2c9ad3519dc7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/533fcec3ff0f1ad0531131126d6b9dcd6dfa32b689cf18914b8e2f5a19d11dae61022736246d9052fdff5acfd3def67e0532ddcc1897834c972b513c68d14e9c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 09:10:01.143925) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 09:10:01.144242) ========= 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.fsgAFdmdYk/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.AKvXAJuBfc/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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.005130 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 09:10:01.192780) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 09:10:01.193092) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 09:10:01.193420) ========= ========= Total master_lag: 0.18 =========