builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0360 starttime: 1484244288.9 results: success (0) buildid: 20170112090117 builduid: 5be27114ca10479593e86562112f670d revision: 8a89dd47e7847c27ffe04b12832f0cdba7262b56 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 10:04:48.901511) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 10:04:48.902092) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 10:04:48.902393) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 10:04:48.930191) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 10:04:48.930655) ========= 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.86ZRiH4Pxj/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.f2smWyZ3qw/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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-12 10:04:48-- 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% 19.1M=0.001s 2017-01-12 10:04:49 (19.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.079896 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 10:04:49.141137) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 10:04:49.141604) ========= 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.86ZRiH4Pxj/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.f2smWyZ3qw/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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.026454 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 10:04:49.192864) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-01-12 10:04:49.193398) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8a89dd47e7847c27ffe04b12832f0cdba7262b56 --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 8a89dd47e7847c27ffe04b12832f0cdba7262b56 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.86ZRiH4Pxj/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.f2smWyZ3qw/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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-12 10:04:49,295 truncating revision to first 12 chars 2017-01-12 10:04:49,295 Setting DEBUG logging. 2017-01-12 10:04:49,295 attempt 1/10 2017-01-12 10:04:49,295 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8a89dd47e784?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-12 10:04:50,355 unpacking tar archive at: mozilla-aurora-8a89dd47e784/testing/mozharness/ program finished with exit code 0 elapsedTime=1.329953 ========= master_lag: 2.92 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-01-12 10:04:53.445404) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 10:04:53.445811) ========= script_repo_revision: 8a89dd47e7847c27ffe04b12832f0cdba7262b56 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 10:04:53.446281) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 10:04:53.446608) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 10:04:53.479681) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-01-12 10:04:53.480051) ========= /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.86ZRiH4Pxj/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.f2smWyZ3qw/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 10:04:53 INFO - MultiFileLogger online at 20170112 10:04:53 in /builds/slave/test 10:04:53 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 10:04:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:04:53 INFO - {'append_to_log': False, 10:04:53 INFO - 'base_work_dir': '/builds/slave/test', 10:04:53 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:04:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:04:53 INFO - 'branch': 'Mozilla-Aurora', 10:04:53 INFO - 'buildbot_json_path': 'buildprops.json', 10:04:53 INFO - 'config_files': ('talos/mac_config.py',), 10:04:53 INFO - 'default_actions': ('clobber', 10:04:53 INFO - 'read-buildbot-config', 10:04:53 INFO - 'download-and-extract', 10:04:53 INFO - 'populate-webroot', 10:04:53 INFO - 'create-virtualenv', 10:04:53 INFO - 'install', 10:04:53 INFO - 'run-tests'), 10:04:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:04:53 INFO - 'download_minidump_stackwalk': True, 10:04:53 INFO - 'download_symbols': 'ondemand', 10:04:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:04:53 INFO - 'tooltool.py': '/tools/tooltool.py', 10:04:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:04:53 INFO - '/tools/misc-python/virtualenv.py')}, 10:04:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:04:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:04:53 INFO - 'installer_path': 'installer.exe', 10:04:53 INFO - 'log_level': 'info', 10:04:53 INFO - 'log_name': 'talos', 10:04:53 INFO - 'log_to_console': True, 10:04:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:04:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:04:53 INFO - 'opt_config_files': (), 10:04:53 INFO - 'pip_index': False, 10:04:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:04:53 INFO - 'cmd': ('bash', 10:04:53 INFO - '-c', 10:04:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:04:53 INFO - 'enabled': True, 10:04:53 INFO - 'halt_on_failure': False, 10:04:53 INFO - 'name': 'check_screen_resolution'},), 10:04:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:04:53 INFO - 'cmd': ('bash', 10:04:53 INFO - '-c', 10:04:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:04:53 INFO - 'enabled': True, 10:04:53 INFO - 'halt_on_failure': False, 10:04:53 INFO - 'name': 'check_screen_resolution'},), 10:04:53 INFO - 'run_cmd_checks_enabled': True, 10:04:53 INFO - 'sps_profile': False, 10:04:53 INFO - 'sps_profile_interval': 0, 10:04:53 INFO - 'suite': 'dromaeojs-e10s', 10:04:53 INFO - 'system_bits': '32', 10:04:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:04:53 INFO - 'title': 't-yosemite-r7-0360', 10:04:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:04:53 INFO - 'use_talos_json': True, 10:04:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:04:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:04:53 INFO - 'work_dir': 'build'} 10:04:53 INFO - [mozharness: 2017-01-12 18:04:53.690355Z] Running clobber step. 10:04:53 INFO - Running pre-action listener: _resource_record_pre_action 10:04:53 INFO - Running main action method: clobber 10:04:53 INFO - rmtree: /builds/slave/test/build 10:04:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:04:56 INFO - Running post-action listener: _resource_record_post_action 10:04:56 INFO - [mozharness: 2017-01-12 18:04:56.016646Z] Finished clobber step (success) 10:04:56 INFO - [mozharness: 2017-01-12 18:04:56.016871Z] Running read-buildbot-config step. 10:04:56 INFO - Running pre-action listener: _resource_record_pre_action 10:04:56 INFO - Running main action method: read_buildbot_config 10:04:56 INFO - Using buildbot properties: 10:04:56 INFO - { 10:04:56 INFO - "project": "", 10:04:56 INFO - "product": "firefox", 10:04:56 INFO - "script_repo_revision": "production", 10:04:56 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 10:04:56 INFO - "repository": "", 10:04:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 10:04:56 INFO - "buildid": "20170112090117", 10:04:56 INFO - "pgo_build": "False", 10:04:56 INFO - "basedir": "/builds/slave/test", 10:04:56 INFO - "buildnumber": 7, 10:04:56 INFO - "slavename": "t-yosemite-r7-0360", 10:04:56 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:04:56 INFO - "platform": "yosemite_r7", 10:04:56 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:04:56 INFO - "branch": "mozilla-aurora", 10:04:56 INFO - "repo_path": "releases/mozilla-aurora", 10:04:56 INFO - "revision": "8a89dd47e7847c27ffe04b12832f0cdba7262b56", 10:04:56 INFO - "stage_platform": "macosx64", 10:04:56 INFO - "builduid": "5be27114ca10479593e86562112f670d", 10:04:56 INFO - "slavebuilddir": "test" 10:04:56 INFO - } 10:04:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 10:04:56 INFO - Running post-action listener: _resource_record_post_action 10:04:56 INFO - [mozharness: 2017-01-12 18:04:56.022723Z] Finished read-buildbot-config step (success) 10:04:56 INFO - [mozharness: 2017-01-12 18:04:56.022949Z] Running download-and-extract step. 10:04:56 INFO - Running pre-action listener: _resource_record_pre_action 10:04:56 INFO - Running main action method: download_and_extract 10:04:56 INFO - mkdir: /builds/slave/test/build/tests 10:04:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:56 INFO - trying https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 10:04:56 INFO - Downloading https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/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 10:04:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/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 10:04:57 INFO - Downloaded 1199 bytes. 10:04:57 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 10:04:57 INFO - Using the following test package requirements: 10:04:57 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:04:57 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:04:57 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 10:04:57 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:04:57 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 10:04:57 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:04:57 INFO - u'jsshell-mac.zip'], 10:04:57 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:04:57 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 10:04:57 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:04:57 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:04:57 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 10:04:57 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:04:57 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 10:04:57 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:04:57 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 10:04:57 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:04:57 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 10:04:57 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:04:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:04:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:04:57 INFO - Fetch https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:04:57 INFO - Http code: 200 10:04:57 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 10:04:57 INFO - x-amz-cf-id: 79tDGkJN-nrcGx_KZPFsxptYCjkk4Pk5nUhYLDC3n0l8to3bJp9VMA== 10:04:57 INFO - x-amz-version-id: CBoggmg94JT2rv.tPQOpU1cFVCSHXNOn 10:04:58 INFO - Expected file size: 16275546 10:04:58 INFO - Obtained file size: 16275546 10:05:04 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 10:05:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:05:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:05:04 INFO - Fetch https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:05:05 INFO - Http code: 200 10:05:05 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 10:05:05 INFO - x-amz-cf-id: WGy7qps0rlUe9DeeSd5bCMQMdCNqCxobCqYQ-5NvZ0FnnJoRTVC8WA== 10:05:05 INFO - x-amz-version-id: CBoggmg94JT2rv.tPQOpU1cFVCSHXNOn 10:05:05 INFO - Expected file size: 16275546 10:05:05 INFO - Obtained file size: 16275546 10:05:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 10:05:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:05:11 INFO - Fetch https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 10:05:12 INFO - Http code: 200 10:05:12 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 10:05:12 INFO - x-amz-cf-id: 3JSTfWV9UqdhCGRIZ95SM_007GBGdvBgspT0Q9ulFInz9VbWgktGMA== 10:05:12 INFO - x-amz-version-id: nJKrJj4i.Xe9MPXm_83UnI5afVbX2OxM 10:05:12 INFO - Expected file size: 14409038 10:05:12 INFO - Obtained file size: 14409038 10:05:13 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')]} 10:05:13 INFO - trying https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:05:13 INFO - Downloading https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 10:05:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/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 10:05:15 INFO - Downloaded 109963736 bytes. 10:05:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:05:15 INFO - mkdir: /builds/slave/test/properties 10:05:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:05:15 INFO - Writing to file /builds/slave/test/properties/build_url 10:05:15 INFO - Contents: 10:05:15 INFO - build_url:https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:05:16 INFO - Running post-action listener: _resource_record_post_action 10:05:16 INFO - Running post-action listener: set_extra_try_arguments 10:05:16 INFO - [mozharness: 2017-01-12 18:05:16.616372Z] Finished download-and-extract step (success) 10:05:16 INFO - [mozharness: 2017-01-12 18:05:16.616511Z] Running populate-webroot step. 10:05:16 INFO - Running pre-action listener: _resource_record_pre_action 10:05:16 INFO - Running main action method: populate_webroot 10:05:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:05:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:05:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:05:16 INFO - '--tppagecycles', 10:05:16 INFO - '7'], 10:05:16 INFO - 'tests': ['tsvgm']}}, 10:05:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:05:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:05:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:05:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:05:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:05:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:05:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:16 INFO - 'talos_options': ['--disable-e10s'], 10:05:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:05:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:05:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:16 INFO - 'talos_options': ['--disable-e10s'], 10:05:16 INFO - 'tests': ['damp', 'tps']}, 10:05:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:16 INFO - 'tests': ['damp', 'tps']}, 10:05:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:05:16 INFO - 'tests': ['dromaeo_dom']}, 10:05:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:05:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:05:16 INFO - 'tests': ['basic_compositor_video']}, 10:05:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:05:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:05:16 INFO - 'tests': ['a11yr', 10:05:16 INFO - 'ts_paint', 10:05:16 INFO - 'tpaint', 10:05:16 INFO - 'sessionrestore', 10:05:16 INFO - 'sessionrestore_no_auto_restore', 10:05:16 INFO - 'tabpaint']}, 10:05:16 INFO - 'other-e10s': {'tests': ['a11yr', 10:05:16 INFO - 'ts_paint', 10:05:16 INFO - 'tpaint', 10:05:16 INFO - 'sessionrestore', 10:05:16 INFO - 'sessionrestore_no_auto_restore', 10:05:16 INFO - 'tabpaint']}, 10:05:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:05:16 INFO - 'ts_paint', 10:05:16 INFO - 'tpaint', 10:05:16 INFO - 'sessionrestore', 10:05:16 INFO - 'sessionrestore_no_auto_restore', 10:05:16 INFO - 'tabpaint']}, 10:05:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:05:16 INFO - 'ts_paint', 10:05:16 INFO - 'tpaint', 10:05:16 INFO - 'sessionrestore', 10:05:16 INFO - 'sessionrestore_no_auto_restore', 10:05:16 INFO - 'tabpaint']}, 10:05:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:05:16 INFO - 'tests': ['a11yr', 10:05:16 INFO - 'ts_paint', 10:05:16 INFO - 'tpaint', 10:05:16 INFO - 'sessionrestore', 10:05:16 INFO - 'sessionrestore_no_auto_restore', 10:05:16 INFO - 'tabpaint']}, 10:05:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:05:16 INFO - 'tests': ['a11yr', 10:05:16 INFO - 'ts_paint', 10:05:16 INFO - 'tpaint', 10:05:16 INFO - 'sessionrestore', 10:05:16 INFO - 'sessionrestore_no_auto_restore', 10:05:16 INFO - 'tabpaint']}, 10:05:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:05:16 INFO - 'tests': ['tsvgx', 10:05:16 INFO - 'tsvgr_opacity', 10:05:16 INFO - 'tart', 10:05:16 INFO - 'tscrollx', 10:05:16 INFO - 'cart']}, 10:05:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:05:16 INFO - 'tsvgr_opacity', 10:05:16 INFO - 'tart', 10:05:16 INFO - 'tscrollx', 10:05:16 INFO - 'cart']}, 10:05:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:16 INFO - 'talos_options': ['--disable-e10s'], 10:05:16 INFO - 'tests': ['tp5o']}, 10:05:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:16 INFO - 'tests': ['tp5o']}, 10:05:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:16 INFO - 'talos_options': ['--disable-e10s', 10:05:16 INFO - '--xperf_path', 10:05:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:05:16 INFO - 'tests': ['tp5n']}, 10:05:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:16 INFO - 'talos_options': ['--xperf_path', 10:05:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:05:16 INFO - 'tests': ['tp5n']}}, 10:05:16 INFO - 'talos.zip': {'path': '', 10:05:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:05:16 INFO - Running post-action listener: _resource_record_post_action 10:05:16 INFO - [mozharness: 2017-01-12 18:05:16.635660Z] Finished populate-webroot step (success) 10:05:16 INFO - [mozharness: 2017-01-12 18:05:16.635794Z] Running create-virtualenv step. 10:05:16 INFO - Running pre-action listener: _resource_record_pre_action 10:05:16 INFO - Running main action method: create_virtualenv 10:05:16 INFO - Creating virtualenv /builds/slave/test/build/venv 10:05:16 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 10:05:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:05:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:05:16 INFO - Using real prefix '/tools/python27' 10:05:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:05:17 INFO - Installing distribute.............................................................................................................................................................................................done. 10:05:20 INFO - Installing pip.................done. 10:05:20 INFO - Return code: 0 10:05:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:05:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:05:20 INFO - Reading from file tmpfile_stdout 10:05:20 INFO - Output received: 10:05:20 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) 10:05:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:05:20 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')]} 10:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:20 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')]} 10:05:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:20 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 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd246eb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107076db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.86ZRiH4Pxj/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.f2smWyZ3qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 10:05:20 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 10:05:20 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 10:05:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86ZRiH4Pxj/Render', 10:05:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:20 INFO - 'HOME': '/Users/cltbld', 10:05:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:20 INFO - 'LOGNAME': 'cltbld', 10:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:20 INFO - 'MOZ_NO_REMOTE': '1', 10:05:20 INFO - 'NO_EM_RESTART': '1', 10:05:20 INFO - 'PAGER': '/bin/cat', 10:05:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:20 INFO - 'PWD': '/builds/slave/test', 10:05:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:20 INFO - 'SHELL': '/bin/bash', 10:05:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2smWyZ3qw/Listeners', 10:05:20 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 10:05:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:20 INFO - 'USER': 'cltbld', 10:05:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:20 INFO - 'XPC_FLAGS': '0x0', 10:05:20 INFO - 'XPC_SERVICE_NAME': '0', 10:05:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:20 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 10:05:20 INFO - Cleaning up... 10:05:20 INFO - Return code: 0 10:05:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:05:20 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')]} 10:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:20 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')]} 10:05:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:20 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 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd246eb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107076db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.86ZRiH4Pxj/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.f2smWyZ3qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 10:05:20 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 10:05:20 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 10:05:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86ZRiH4Pxj/Render', 10:05:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:20 INFO - 'HOME': '/Users/cltbld', 10:05:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:20 INFO - 'LOGNAME': 'cltbld', 10:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:20 INFO - 'MOZ_NO_REMOTE': '1', 10:05:20 INFO - 'NO_EM_RESTART': '1', 10:05:20 INFO - 'PAGER': '/bin/cat', 10:05:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:20 INFO - 'PWD': '/builds/slave/test', 10:05:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:20 INFO - 'SHELL': '/bin/bash', 10:05:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2smWyZ3qw/Listeners', 10:05:20 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 10:05:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:20 INFO - 'USER': 'cltbld', 10:05:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:20 INFO - 'XPC_FLAGS': '0x0', 10:05:20 INFO - 'XPC_SERVICE_NAME': '0', 10:05:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:21 INFO - Downloading/unpacking psutil>=3.1.1 10:05:21 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 10:05:21 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 10:05:21 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 10:05:21 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 10:05:21 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 10:05:21 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 10:05:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:05:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:05:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:05:24 INFO - Installing collected packages: psutil 10:05:24 INFO - Running setup.py install for psutil 10:05:24 INFO - building 'psutil._psutil_osx' extension 10:05:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:05:25 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 10:05:25 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 10:05:25 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 10:05:25 INFO - building 'psutil._psutil_posix' extension 10:05:25 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 10:05:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:05:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:05:25 INFO - ^ 10:05:25 INFO - 1 warning generated. 10:05:25 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 10:05:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:05:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:05:25 INFO - Successfully installed psutil 10:05:25 INFO - Cleaning up... 10:05:25 INFO - Return code: 0 10:05:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:05: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')]} 10:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05: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')]} 10:05:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd246eb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107076db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.86ZRiH4Pxj/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.f2smWyZ3qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 10:05: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:05: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 mozsystemmonitor==0.3 10:05:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86ZRiH4Pxj/Render', 10:05:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:25 INFO - 'HOME': '/Users/cltbld', 10:05:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:25 INFO - 'LOGNAME': 'cltbld', 10:05:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:25 INFO - 'MOZ_NO_REMOTE': '1', 10:05:25 INFO - 'NO_EM_RESTART': '1', 10:05:25 INFO - 'PAGER': '/bin/cat', 10:05:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:25 INFO - 'PWD': '/builds/slave/test', 10:05:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:25 INFO - 'SHELL': '/bin/bash', 10:05:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2smWyZ3qw/Listeners', 10:05:25 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 10:05:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:25 INFO - 'USER': 'cltbld', 10:05:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:25 INFO - 'XPC_FLAGS': '0x0', 10:05:25 INFO - 'XPC_SERVICE_NAME': '0', 10:05:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:05:26 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 10:05:26 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 10:05:26 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 10:05:26 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 10:05:26 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 10:05:26 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 10:05:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:05:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:05:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:05:28 INFO - Installing collected packages: mozsystemmonitor 10:05:28 INFO - Running setup.py install for mozsystemmonitor 10:05:28 INFO - Successfully installed mozsystemmonitor 10:05:28 INFO - Cleaning up... 10:05:29 INFO - Return code: 0 10:05:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:05: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')]} 10:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05: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')]} 10:05:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05: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', '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 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd246eb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107076db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.86ZRiH4Pxj/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.f2smWyZ3qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 10:05: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:05: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 jsonschema==2.5.1 10:05:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86ZRiH4Pxj/Render', 10:05:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:29 INFO - 'HOME': '/Users/cltbld', 10:05:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:29 INFO - 'LOGNAME': 'cltbld', 10:05:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:29 INFO - 'MOZ_NO_REMOTE': '1', 10:05:29 INFO - 'NO_EM_RESTART': '1', 10:05:29 INFO - 'PAGER': '/bin/cat', 10:05:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:29 INFO - 'PWD': '/builds/slave/test', 10:05:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:29 INFO - 'SHELL': '/bin/bash', 10:05:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2smWyZ3qw/Listeners', 10:05:29 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 10:05:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:29 INFO - 'USER': 'cltbld', 10:05:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:29 INFO - 'XPC_FLAGS': '0x0', 10:05:29 INFO - 'XPC_SERVICE_NAME': '0', 10:05:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:29 INFO - Downloading/unpacking jsonschema==2.5.1 10:05: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 10:05: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 10:05: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 10:05: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 10:05: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 10:05: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 10:05:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:05:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:05:31 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 10:05:31 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 10:05:31 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 10:05:31 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 10:05:31 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 10:05:31 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 10:05:32 INFO - Downloading functools32-3.2.3-2.tar.gz 10:05:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:05:32 INFO - warning: no files found matching '*.txt' 10:05:32 INFO - no previously-included directories found matching 'build' 10:05:32 INFO - no previously-included directories found matching 'dist' 10:05:32 INFO - no previously-included directories found matching '.git*' 10:05:32 INFO - Installing collected packages: jsonschema, functools32 10:05:32 INFO - Running setup.py install for functools32 10:05:32 INFO - warning: no files found matching '*.txt' 10:05:32 INFO - no previously-included directories found matching 'build' 10:05:32 INFO - no previously-included directories found matching 'dist' 10:05:32 INFO - no previously-included directories found matching '.git*' 10:05:32 INFO - Successfully installed jsonschema functools32 10:05:32 INFO - Cleaning up... 10:05:32 INFO - Return code: 0 10:05:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd246eb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107076db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.86ZRiH4Pxj/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.f2smWyZ3qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 10:05:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:05:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:05:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86ZRiH4Pxj/Render', 10:05:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:32 INFO - 'HOME': '/Users/cltbld', 10:05:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:32 INFO - 'LOGNAME': 'cltbld', 10:05:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:32 INFO - 'MOZ_NO_REMOTE': '1', 10:05:32 INFO - 'NO_EM_RESTART': '1', 10:05:32 INFO - 'PAGER': '/bin/cat', 10:05:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:32 INFO - 'PWD': '/builds/slave/test', 10:05:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:32 INFO - 'SHELL': '/bin/bash', 10:05:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2smWyZ3qw/Listeners', 10:05:32 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 10:05:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:32 INFO - 'USER': 'cltbld', 10:05:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:32 INFO - 'XPC_FLAGS': '0x0', 10:05:32 INFO - 'XPC_SERVICE_NAME': '0', 10:05:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:05:33 INFO - Cleaning up... 10:05:33 INFO - Return code: 0 10:05:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:05:33 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')]} 10:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:33 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')]} 10:05:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:33 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 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd246eb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107076db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.86ZRiH4Pxj/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.f2smWyZ3qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 10:05:33 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 10:05:33 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 10:05:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86ZRiH4Pxj/Render', 10:05:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:33 INFO - 'HOME': '/Users/cltbld', 10:05:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:33 INFO - 'LOGNAME': 'cltbld', 10:05:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:33 INFO - 'MOZ_NO_REMOTE': '1', 10:05:33 INFO - 'NO_EM_RESTART': '1', 10:05:33 INFO - 'PAGER': '/bin/cat', 10:05:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:33 INFO - 'PWD': '/builds/slave/test', 10:05:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:33 INFO - 'SHELL': '/bin/bash', 10:05:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2smWyZ3qw/Listeners', 10:05:33 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 10:05:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:33 INFO - 'USER': 'cltbld', 10:05:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:33 INFO - 'XPC_FLAGS': '0x0', 10:05:33 INFO - 'XPC_SERVICE_NAME': '0', 10:05:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:33 INFO - Downloading/unpacking blobuploader==1.2.4 10:05: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 10:05: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 10:05: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 10:05: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 10:05: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 10:05: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 10:05:35 INFO - Downloading blobuploader-1.2.4.tar.gz 10:05:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:05:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:05:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:05:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:05:36 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 10:05:36 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 10:05:36 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 10:05:36 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 10:05:36 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 10:05:36 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 10:05:37 INFO - Downloading docopt-0.6.1.tar.gz 10:05:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:05:37 INFO - Installing collected packages: blobuploader, requests, docopt 10:05:37 INFO - Running setup.py install for blobuploader 10:05:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:05:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:05:37 INFO - Running setup.py install for requests 10:05:37 INFO - Running setup.py install for docopt 10:05:38 INFO - Successfully installed blobuploader requests docopt 10:05:38 INFO - Cleaning up... 10:05:38 INFO - Return code: 0 10:05:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:05:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:38 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 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd246eb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107076db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.86ZRiH4Pxj/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.f2smWyZ3qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 10:05:38 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 10:05:38 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 10:05:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86ZRiH4Pxj/Render', 10:05:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:38 INFO - 'HOME': '/Users/cltbld', 10:05:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:38 INFO - 'LOGNAME': 'cltbld', 10:05:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:38 INFO - 'MOZ_NO_REMOTE': '1', 10:05:38 INFO - 'NO_EM_RESTART': '1', 10:05:38 INFO - 'PAGER': '/bin/cat', 10:05:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:38 INFO - 'PWD': '/builds/slave/test', 10:05:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:38 INFO - 'SHELL': '/bin/bash', 10:05:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2smWyZ3qw/Listeners', 10:05:38 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 10:05:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:38 INFO - 'USER': 'cltbld', 10:05:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:38 INFO - 'XPC_FLAGS': '0x0', 10:05:38 INFO - 'XPC_SERVICE_NAME': '0', 10:05:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:05:38 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-RYjOOZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:05:38 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-1PDAjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:05:38 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-3CRNO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:05:38 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-Bo2PnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:05:38 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-zGNsNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:05:38 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-uNtrIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:05:38 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-gCg39W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:05:39 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-L4dJUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:05:39 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-ptqfVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:05:39 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-BUsIUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:05:39 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-wNu0dQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:05:39 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-re16Py-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:05:39 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-7E52jg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:05:39 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-ZvmLGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:39 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-aFeaYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:05:39 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-hJ4rkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:05:40 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-Kp3nJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:05:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:05:40 INFO - Running setup.py install for manifestparser 10:05:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:05:40 INFO - Running setup.py install for mozcrash 10:05:40 INFO - Running setup.py install for mozdebug 10:05:40 INFO - Running setup.py install for mozdevice 10:05:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:05:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:05:40 INFO - Running setup.py install for mozfile 10:05:40 INFO - Running setup.py install for mozhttpd 10:05:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:05:40 INFO - Running setup.py install for mozinfo 10:05:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:05:41 INFO - Running setup.py install for mozInstall 10:05:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:05:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:05:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:05:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:05:41 INFO - Running setup.py install for mozleak 10:05:41 INFO - Running setup.py install for mozlog 10:05:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:05:41 INFO - Running setup.py install for moznetwork 10:05:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:05:41 INFO - Running setup.py install for mozprocess 10:05:41 INFO - Running setup.py install for mozprofile 10:05:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:05:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:05:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:05:42 INFO - Running setup.py install for mozrunner 10:05:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:05:42 INFO - Running setup.py install for mozscreenshot 10:05:42 INFO - Running setup.py install for moztest 10:05:42 INFO - Running setup.py install for mozversion 10:05:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:05:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:05:42 INFO - Cleaning up... 10:05:42 INFO - Return code: 0 10:05:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:05:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:43 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')]} 10:05:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:43 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 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd246eb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107076db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.86ZRiH4Pxj/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.f2smWyZ3qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 10:05:43 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 10:05:43 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 10:05:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86ZRiH4Pxj/Render', 10:05:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:43 INFO - 'HOME': '/Users/cltbld', 10:05:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:43 INFO - 'LOGNAME': 'cltbld', 10:05:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:43 INFO - 'MOZ_NO_REMOTE': '1', 10:05:43 INFO - 'NO_EM_RESTART': '1', 10:05:43 INFO - 'PAGER': '/bin/cat', 10:05:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:43 INFO - 'PWD': '/builds/slave/test', 10:05:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:43 INFO - 'SHELL': '/bin/bash', 10:05:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2smWyZ3qw/Listeners', 10:05:43 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 10:05:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:43 INFO - 'USER': 'cltbld', 10:05:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:43 INFO - 'XPC_FLAGS': '0x0', 10:05:43 INFO - 'XPC_SERVICE_NAME': '0', 10:05:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:05:43 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-V0WuOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:05:43 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)) 10:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:05:43 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-86LoIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:05:43 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)) 10:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:05:43 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-aU8QL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:05:44 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)) 10:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:05:44 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-aotOwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:05:44 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)) 10:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:05:44 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-26Prjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:05:44 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)) 10:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:05:44 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-g_OR_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:05:44 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)) 10:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:05:44 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-6iUEeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:05:44 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)) 10:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:05:44 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-TjuFzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:05:44 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)) 10:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:05:44 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-mw9LAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:05:44 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)) 10:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:05:44 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-qb4E7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:05:44 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)) 10:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:05:44 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-7O6EY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:05:44 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)) 10:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:05:44 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-tOSjbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:05:45 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)) 10:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:05:45 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-zcOOqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:05:45 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)) 10:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:05:45 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-M3FyaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:05:45 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)) 10:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:45 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-4fVPub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:45 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)) 10:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:05:45 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-4APGlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:05:45 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)) 10:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:05:45 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-FLSrbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:05:45 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)) 10:05:45 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)) 10:05:45 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)) 10:05:45 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)) 10:05:45 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)) 10:05:45 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)) 10:05:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:48 INFO - Downloading blessings-1.6.tar.gz 10:05:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:05:48 INFO - Installing collected packages: blessings 10:05:48 INFO - Running setup.py install for blessings 10:05:48 INFO - Successfully installed blessings 10:05:48 INFO - Cleaning up... 10:05:48 INFO - Return code: 0 10:05:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:05:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:05:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:05:49 INFO - Reading from file tmpfile_stdout 10:05:49 INFO - Current package versions: 10:05:49 INFO - blessings == 1.6 10:05:49 INFO - blobuploader == 1.2.4 10:05:49 INFO - docopt == 0.6.1 10:05:49 INFO - functools32 == 3.2.3-2 10:05:49 INFO - jsonschema == 2.5.1 10:05:49 INFO - manifestparser == 1.1 10:05:49 INFO - mozInstall == 1.12 10:05:49 INFO - mozcrash == 1.0 10:05:49 INFO - mozdebug == 0.1 10:05:49 INFO - mozdevice == 0.48 10:05:49 INFO - mozfile == 1.2 10:05:49 INFO - mozhttpd == 0.7 10:05:49 INFO - mozinfo == 0.9 10:05:49 INFO - mozleak == 0.1 10:05:49 INFO - mozlog == 3.4 10:05:49 INFO - moznetwork == 0.27 10:05:49 INFO - mozprocess == 0.23 10:05:49 INFO - mozprofile == 0.28 10:05:49 INFO - mozrunner == 6.13 10:05:49 INFO - mozscreenshot == 0.1 10:05:49 INFO - mozsystemmonitor == 0.3 10:05:49 INFO - moztest == 0.8 10:05:49 INFO - mozversion == 1.4 10:05:49 INFO - psutil == 3.1.1 10:05:49 INFO - requests == 1.2.3 10:05:49 INFO - wsgiref == 0.1.2 10:05:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:49 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 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd246eb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107076db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.86ZRiH4Pxj/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.f2smWyZ3qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 10:05:49 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 10:05:49 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 10:05:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86ZRiH4Pxj/Render', 10:05:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:49 INFO - 'HOME': '/Users/cltbld', 10:05:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:49 INFO - 'LOGNAME': 'cltbld', 10:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:49 INFO - 'MOZ_NO_REMOTE': '1', 10:05:49 INFO - 'NO_EM_RESTART': '1', 10:05:49 INFO - 'PAGER': '/bin/cat', 10:05:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:49 INFO - 'PWD': '/builds/slave/test', 10:05:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:49 INFO - 'SHELL': '/bin/bash', 10:05:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2smWyZ3qw/Listeners', 10:05:49 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 10:05:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:49 INFO - 'USER': 'cltbld', 10:05:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:49 INFO - 'XPC_FLAGS': '0x0', 10:05:49 INFO - 'XPC_SERVICE_NAME': '0', 10:05:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:49 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)) 10:05:49 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)) 10:05:49 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)) 10:05:49 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)) 10:05:49 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)) 10:05:49 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)) 10:05:49 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)) 10:05:49 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)) 10:05:49 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)) 10:05:49 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)) 10:05:49 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)) 10:05:49 INFO - Cleaning up... 10:05:49 INFO - Return code: 0 10:05:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:49 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 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd246eb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107076db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.86ZRiH4Pxj/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.f2smWyZ3qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 10:05:49 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 10:05:49 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 10:05:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86ZRiH4Pxj/Render', 10:05:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:49 INFO - 'HOME': '/Users/cltbld', 10:05:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:49 INFO - 'LOGNAME': 'cltbld', 10:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:49 INFO - 'MOZ_NO_REMOTE': '1', 10:05:49 INFO - 'NO_EM_RESTART': '1', 10:05:49 INFO - 'PAGER': '/bin/cat', 10:05:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:49 INFO - 'PWD': '/builds/slave/test', 10:05:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:49 INFO - 'SHELL': '/bin/bash', 10:05:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2smWyZ3qw/Listeners', 10:05:49 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 10:05:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:49 INFO - 'USER': 'cltbld', 10:05:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:49 INFO - 'XPC_FLAGS': '0x0', 10:05:49 INFO - 'XPC_SERVICE_NAME': '0', 10:05:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:05:49 INFO - Cleaning up... 10:05:49 INFO - Return code: 0 10:05:49 INFO - Running post-action listener: _resource_record_post_action 10:05:49 INFO - Running post-action listener: _start_resource_monitoring 10:05:49 INFO - Starting resource monitoring. 10:05:49 INFO - [mozharness: 2017-01-12 18:05:49.771788Z] Finished create-virtualenv step (success) 10:05:49 INFO - [mozharness: 2017-01-12 18:05:49.772764Z] Running install step. 10:05:49 INFO - Running pre-action listener: _resource_record_pre_action 10:05:49 INFO - Running main action method: install 10:05:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:05:50 INFO - Reading from file tmpfile_stdout 10:05:50 INFO - Detecting whether we're running mozinstall >=1.0... 10:05:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:05:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:05:50 INFO - Reading from file tmpfile_stdout 10:05:50 INFO - Output received: 10:05:50 INFO - Usage: mozinstall [options] installer 10:05:50 INFO - Options: 10:05:50 INFO - -h, --help show this help message and exit 10:05:50 INFO - -d DEST, --destination=DEST 10:05:50 INFO - Directory to install application into. [default: 10:05:50 INFO - "/builds/slave/test"] 10:05:50 INFO - --app=APP Application being installed. [default: firefox] 10:05:50 INFO - mkdir: /builds/slave/test/build/application 10:05:50 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'] 10:05:50 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 10:06:20 INFO - Reading from file tmpfile_stdout 10:06:20 INFO - Output received: 10:06:20 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:06:20 INFO - Running post-action listener: _resource_record_post_action 10:06:20 INFO - [mozharness: 2017-01-12 18:06:20.860558Z] Finished install step (success) 10:06:20 INFO - [mozharness: 2017-01-12 18:06:20.860680Z] Running run-tests step. 10:06:20 INFO - Running pre-action listener: _resource_record_pre_action 10:06:20 INFO - Running main action method: run_tests 10:06:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:06:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:06:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:06:20 INFO - 2017-01-12 10:06:20.873 screenresolution[1631:7392] starting screenresolution argv=screenresolution get 10:06:20 INFO - 2017-01-12 10:06:20.876 screenresolution[1631:7392] Display 0: 1600x1200x32@60 10:06:20 INFO - 2017-01-12 10:06:20.881 screenresolution[1632:7394] starting screenresolution argv=screenresolution list 10:06:20 INFO - Available Modes on Display 0 10:06:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:06:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:06:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:06:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:06:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:06:21 INFO - Intel Iris: 10:06:21 INFO - Chipset Model: Intel Iris 10:06:21 INFO - Type: GPU 10:06:21 INFO - Bus: Built-In 10:06:21 INFO - VRAM (Dynamic, Max): 1536 MB 10:06:21 INFO - Vendor: Intel (0x8086) 10:06:21 INFO - Device ID: 0x0a2e 10:06:21 INFO - Revision ID: 0x0009 10:06:21 INFO - Displays: 10:06:21 INFO - Display: 10:06:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:06:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:06:21 INFO - Display Serial Number: 1600x1200 60 10:06:21 INFO - Main Display: Yes 10:06:21 INFO - Mirror: Off 10:06:21 INFO - Online: Yes 10:06:21 INFO - Rotation: Supported 10:06:21 INFO - Return code: 0 10:06:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:06:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:06:21 INFO - Python 2.7.3 10:06:21 INFO - Return code: 0 10:06:21 INFO - grabbing minidump binary from tooltool 10:06:21 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')]} 10:06:21 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 0x107210d30>, '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 0x107076db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:06:21 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 10:06:21 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 10:06:21 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 10:06:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:06:21 INFO - Return code: 0 10:06:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:06:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:06:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:06:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:06:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:06:21 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/WanZ-aCzSWOyxsbHnl8iag/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0360', '--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 10:06:21 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/WanZ-aCzSWOyxsbHnl8iag/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0360 --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 10:06:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86ZRiH4Pxj/Render', 10:06:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:21 INFO - 'HOME': '/Users/cltbld', 10:06:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:21 INFO - 'LOGNAME': 'cltbld', 10:06:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:06:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:06:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:21 INFO - 'MOZ_NO_REMOTE': '1', 10:06:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:06:21 INFO - 'NO_EM_RESTART': '1', 10:06:21 INFO - 'PAGER': '/bin/cat', 10:06:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:21 INFO - 'PWD': '/builds/slave/test', 10:06:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:06:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:21 INFO - 'SHELL': '/bin/bash', 10:06:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2smWyZ3qw/Listeners', 10:06:21 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 10:06:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:21 INFO - 'USER': 'cltbld', 10:06:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:21 INFO - 'XPC_FLAGS': '0x0', 10:06:21 INFO - 'XPC_SERVICE_NAME': '0', 10:06:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:21 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/WanZ-aCzSWOyxsbHnl8iag/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0360', '--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 10:06:21 INFO - mozversion application_buildid: 20170112090117 10:06:21 INFO - mozversion application_changeset: 8a89dd47e7847c27ffe04b12832f0cdba7262b56 10:06:21 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:06:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:06:21 INFO - mozversion application_name: Firefox 10:06:21 INFO - mozversion application_remotingname: firefox-dev 10:06:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:06:21 INFO - mozversion application_vendor: Mozilla 10:06:21 INFO - mozversion application_version: 52.0a2 10:06:21 INFO - mozversion platform_buildid: 20170112090117 10:06:21 INFO - mozversion platform_changeset: 8a89dd47e7847c27ffe04b12832f0cdba7262b56 10:06:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:06:21 INFO - mozversion platform_version: 52.0a2 10:06:21 INFO - using testdate: 1484244381 10:06:21 INFO - actual date: 1484244381 10:06:21 INFO - starting webserver on 'localhost:49235' 10:06:21 INFO - SUITE-START | Running 2 tests 10:06:21 INFO - TEST-START | dromaeo_css 10:06:21 INFO - operating with platform_type : mac_ 10:06:21 INFO - Initialising browser for dromaeo_css test... 10:06:21 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmpvaxHan/profile http://localhost:49235/getInfo.html) 10:06:24 INFO - PROCESS | 1642 | 2017-01-12 10:06:24.745 plugin-container[1644:7579] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b43, name = 'com.apple.tsm.portname' 10:06:24 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:24 INFO - PROCESS | 1642 | 2017-01-12 10:06:24.748 plugin-container[1644:7579] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e1b, name = 'com.apple.CFPasteboardClient' 10:06:24 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:24 INFO - PROCESS | 1642 | 2017-01-12 10:06:24.748 plugin-container[1644:7579] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:06:24 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 10:06:24 INFO - PROCESS | 1642 | colorDepth:24 10:06:24 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 10:06:24 INFO - PROCESS | 1642 | __metrics 10:06:24 ERROR - PROCESS | 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 10:06:25 INFO - TEST-INFO | 1642: exit 0 10:06:25 INFO - Browser initialized. 10:06:25 INFO - Running cycle 1/1 for dromaeo_css test... 10:06:25 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmpvaxHan/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:06:28 INFO - PROCESS | 1645 | 2017-01-12 10:06:28.709 plugin-container[1646:7702] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 10:06:28 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:28 INFO - PROCESS | 1645 | 2017-01-12 10:06:28.710 plugin-container[1646:7702] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 10:06:28 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:28 INFO - PROCESS | 1645 | 2017-01-12 10:06:28.710 plugin-container[1646:7702] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:06:38 INFO - PROCESS | 1645 | RSS: Main: 229163008 10:06:38 INFO - PROCESS | 1645 | 10:09:40 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 10:09:42 INFO - PROCESS | 1645 | RSS: Main: 235380736 10:09:42 INFO - PROCESS | 1645 | 10:12:44 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 10:12:45 INFO - PROCESS | 1645 | RSS: Main: 243355648 10:12:45 INFO - PROCESS | 1645 | 10:15:47 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 10:15:48 INFO - PROCESS | 1645 | RSS: Main: 246431744 10:15:48 INFO - PROCESS | 1645 | 10:18:49 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 10:18:50 INFO - PROCESS | 1645 | RSS: Main: 245600256 10:18:50 INFO - PROCESS | 1645 | 10:21:53 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 10:21:54 INFO - PROCESS | 1645 | RSS: Main: 249077760 10:21:54 INFO - PROCESS | 1645 | 10:24:56 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 10:24:57 INFO - PROCESS | 1645 | RSS: Main: 250593280 10:24:57 INFO - PROCESS | 1645 | 10:24:57 INFO - PROCESS | 1645 | __start_tp_report 10:24:57 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 10:24:57 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 10:24:57 INFO - PROCESS | 1645 | |i|pagename|runs| 10:24:57 INFO - PROCESS | 1645 | |0;dojo.html;3304.041827869918;3326.933461330773;3347.2301370684727;3352.296017836254;3364.1925937774936;4809.591184749301;5304.708241046741;5477.78088876445;5479.671219726814;5620.072688006474;5514.393416724157;5567.164925261213;5585.944140558593;5591.217229587851;5613.943860561393;5051.419086805009;5062.746862656871;5145.761593706749;5170.198619214009;5221.634485586011;5116.258142569325;5119.385673719159;5144.408393034792;5165.225216217578;5184.040952423814;4987.202047672391;5036.445990940993;5086.6693664911845;5119.2321151827155;5145.536901678829;4977.278294647292;4994.200927851526;5007.699538027694;5050.7222102784335;5087.79648814047;4716.363290955712;4898.265260210961;4938.604911607063;5058.190689489701;5073.112205364084;4896.143174944371;5064.772085256172;5509.944900550983;5529.585281103893;5548.805791797308;6067.484263837579;6112.594670127729;6116.914073580098;6122.093225952662;6125.937554508974;6140.263168419819;6182.598131121463;6188.350223226586;6208.068789681539;6229.3459186785185;5520.089185284463;5576.968260871752;5618.353889302783;5696.661284598157;5700.942990570083;6193.225846769154;6238.500919926395;6245.843853903598;6252.6873656317;6310.337414571496;5984.670843103584;6004.219451471281;6006.219191505076;6019.367966363557;6071.301800293023;5944.851378715567;5988.820335389945;6121.755129794848;6160.568760186744;6169.259688837278;5877.206577112121;5911.852203694942;5969.98510253258;6052.061930400795;6058.727357268934;6066.787662431793;6068.059450785136;6081.331053584102;6087.54343424245;6096.085587161872;4824.662273640812;4825.420949486014;4828.61371090312;4835.032993401333;4876.122297986326;3637.181634132299;4283.2932566126865;4286.764227967429;4303.311470164758;4322.610965013164;5453.291072160594;5615.438456154352;5649.576281778883;5669.092945128759;5699.544036477072;4962.5533701967;5873;6004.309504406961;6160.414760597736;6180.721867515973;4733.431988161443;5626.099824028136;5630.324361076615;5645.858853528694;5685.886282274414;5272.261883336336;5296.973515132399;5300.973495132577;5304.38999515053;5305.628605997543;5146.588272938231;6183.598066125687;6210.937890621126;6268.905966410599;6316.431521163117;4983.953369792384;5865.090910908816;6082.391760823883;6139.293981192041;6140.478059364826;6246.656433896179;6251.249850018027;6254.030625253093;6275.843103922439;6310;5276.498732620395;5546.389897111395;5933.881322373615;6001.609895356788;6030.065339872327;5076.238564215398;5615.298087739033;5944.1975333331175;6129.570930034854;6169.043798211284;5802.796902108406;5827.24245848037;5848.181254624441;5866.853328666817;5869.589128760946;5809.50619197373;5828.154917536848;5884.705764711833;5894.233749612523;5909.852253693692;4788.353572267699;5668.234788303526;5723.828285151452;5744.138379243147;5744.741486633028;5719.8570035749435;5865.4134586541;5872.530197584098;5880.764769409176;5891.351951285269;4720.409948756405;5639.2668953035845;5728.140778883201;5796.971015144897;5872.207252020922 10:24:57 INFO - PROCESS | 1645 | |1;ext.html;493.62731137990824;494.8914431673052;525.8956191996805;534.1800336483496;536.857732700834;38610.22779544411;40262.798686006565;40453.59546404535;40496.59503404965;40523.78428647138;41541.37687934683;42085.158296834044;42130.36804447936;42196.36705449421;42350.57649423505;9375.484348360855;9389.62441502339;9516.80966380672;9530.18993385563;9552.665656702016;9414.246860251198;9494.382865113745;9527.761805954837;9568.952155239214;9588.520573971273;9531.237500999938;9565.66520171794;9590.472524011177;9599.760005999837;9601.47191904445;9511.904880951206;9559.904400956006;9569.712908612753;9582.712518624452;9597.904020959772;9413.57638906251;9456.101670341306;9498.525073746285;9502.47736374499;9506.334556581041;34864.651353486646;37470.25059498795;39698;39994;40093.79953100245;3110.6422761382278;3271.4111459937203;3274.9017529473917;3568.4647302904514;3813.8855834325013;28389.716102838913;30303.696963030307;30947.381052379053;31143.844280778678;31470.37059258825;2838.957415638767;3113.4395808754416;3138.921526961821;3179.60254968129;3197.216681912946;455659;460148;461673;463588.3641163579;464533;50481.99036019259;60236.69881650651;60538.69730651318;60839;61432.15703528901;47358.76320618375;57829.710851445474;58256.41743582595;63103.36896630975;64005.35994640087;16003.519894403187;16149.59626009359;16322.75515867263;16330.510084697478;16375.344986200656;12298.877011229952;12528.874711252953;12616.621501354974;12673.366331683379;12702.30137342455;14832.629184270263;18062.819371806378;18115.54711132232;18207.907525548508;18208.27021432962;6104.084387341845;6134.38656134383;7085.751998680124;7102.874735116636;7165.426765858823;823.5717426937958;846.8814365988767;958.736347504428;995.9502024898726;1042.1298215989582;29375.55936660952;29651.703482965324;30009.69990300113;30089;30487.847560762053;1028.3058935218698;1049.9055085042382;1069.2835800014009;1073.5078241244676;1112.9610463633735;15644.530664080095;15702.136382499073;15703.528894133195;15728.213589320489;15806.972546784422;677026;680028;686841;687390;688278;41914;51120.74439627778;51669.48330516647;55800.72099639476;56276.1861906903;1335.2322414611442;1342.4227582139579;1357.1992524410646;1358.5584684977423;1363.0731102850157;24011.399715007265;24662.63006054875;28928.855355723088;29516.262093447836;30065.248368790955;50572.747136264086;51104;51375;51422;51596.22605660993;623310;623678;624157;624353;625786;26595.601065984414;27060.1881943542;27392;27471.587926181524;27476;45185.548144519445;46145;46258.537414625425;46615.766921165174;46824.53175468338;38134.809325953196;38588.22823543457;38804.41793373158;39781.60218397895;40465.59534404736;37976.62023379731;38133.427998579486;38307.808460957516;39326.60673393229;39525.81422557328;37417.625823741415;37914.8104259477;38169.42745858759;38180.427293590066;38201.85394438171;30856;34120.976370708915;34555;34661.30677386488;35202;25424.364390890376;25458.490830183662;25488.872555637103;25696.87151564305;25911.740882590933 10:24:57 INFO - PROCESS | 1645 | |2;jquery.html;366.99266014679705;367.78668372344043;377.3773274097739;378.78030742169545;396.1482811954298;19006.95461749603;19296.421107366783;19767.70348444771;20002.89998550007;20286.695699564483;11352;11422.257553259053;11717.648470545875;11725.941370293158;11751.001164900994;5127.769250383723;5395.568354531628;5458.153986132156;5513.61404701671;5551.583631227674;3977.3636218205093;4170.416141740159;4879.438864530571;5456.290682211307;5494.423085575997;4460.308652158904;6766.932330676679;6777.2545020047755;6845.315468453165;6855.657217139148;6106.389361063858;6150.8769824603705;6194.721237544299;6770.289119642441;6807.081044059063;5961.672108034057;5962.880742385171;5971.850703732398;5973.223480947492;5987.072003839412;12755.489780408772;12764.61706148817;12992.610221693365;13080.672983175496;13119.86880131196;2135.47680818199;2152.0638522242875;2219.766924472923;2343.027643527934;2880.3663194097476;9391;9913.40519568821;10155.441450720207;10350.964903509665;10441.373517588969;1825.8040983156054;1827.5065732252217;1844.1088212682507;1848.7042073268346;1887.858410619209;90288;121217;122204.38897805543;125625;125767.74232257652;9786.727725395653;10981.505832237568;11755.588554400554;11779.587714429954;12009.819852702216;10410.115140213014;14388.352524136439;14629.268536573129;14638.341274642667;14648.633784155481;7045.68294426752;7255.45584081196;8064.516129032277;8233.629486673113;8466;7031.191412987572;7701.922980770232;8311.916880831235;8320.459170153921;8413.621387037598;3770.472133901256;3852.8826640274565;4539.546045395434;4578.839740609063;4584.862454126315;1885.33070759881;1958.7943265957153;1959.9612205530807;1969.212315073982;1995.890226037582;706.336044118527;806.6531391501711;830.5126147189206;925.9582620322174;949.5537097564134;16153.757693634605;16870.493885183463;20611.4847128823;20848.687269690967;21063.473413164487;879.7228872905015;893.5784025291533;897.0536084430988;950;1003.9096481316717;10654.520546575422;11053.502592383362;11140.721481963015;11298.60454884075;11355.54577816878;483959.1604083984;516152;575193;592069;593194;120430;124475;124858.37570812088;125113.37443312725;126972.36513817371;860.9096044915319;939.0937745076035;995.3281534964016;995.4773743784571;995.6913356859396;14007.929960350133;18488.16803243884;19638.60722785565;19750.60498790045;19906.5023374411;39107.80446097751;40255;47872;49879.501204987486;51995;64603;69790.65104674647;70456.64771676109;71354;72216.91674624779;6563.901541476786;7875.606219688878;7876.960615197117;8225.383096267684;8252.422330437052;10140.391576505433;12303.630891073282;12308.199967002474;12444.875551244733;12740.936295318465;10831.350118992885;10831.945840270748;10842.620508282173;10997.285176463503;11127.55489780432;8215.84978103078;9115.951665558545;10698.41158736277;10775.299605525615;10809.45952702346;10751.270947090981;11147.88852111501;11176.497057632589;11272.436378180893;11633.302001879914;8140.348772098337;8175.79560510992;8245.175482451707;8254.215849494289;8274.503529788231;10531.842022369518;10965.95823396776;10991;11199.66401007971;11313.943430283125 10:24:57 INFO - PROCESS | 1645 | |3;mootools.html;1439.352291468839;1475.3803402570923;1619.3036994092542;1702.9489115326544;1758.2703178181055;7493.1008279006455;7774.494657847236;8053.637586308616;8554.40119191656;8557.572121393929;7978.521288722681;8273.83452330953;8283.46157499762;8363.330933525318;8411.285040771541;2303.493231489074;2337.637666161739;2337.9766202338014;2339.9298021059312;2345.976540234593;2317.8609283443047;2357.0571771291447;2359.292212336301;2360.527894421114;2378.512404956988;2088.1021160900805;2198.2635817001324;2302.1482051640915;2314.930552083432;2337.6610391493223;2223.67756675282;2229.2532001779423;2249.4488850231796;2251.797338239558;2269.7843704848096;1943.4558323669253;2309.6488554195817;2334.8248881333798;2335.019291897407;2352.8117750579913;8335.54154521501;8380.078191398941;8421.989361276625;8423.073461919184;8494.74515764528;4697.577218050392;4946.208606622923;5029.170186919154;5195.948040519622;5220.138677118271;3218.4045951499047;3307.520409540627;3513.5959364672876;3513.6310687377713;3530.735194860396;1033.6950599573145;1040.1522758951505;1067.7223921780394;1078.8273876179774;1082.7726177502655;10973.670789876316;10986;11043.83434248487;11125.109991200765;11150.107991360672;4481.820727170877;4564.269716845289;4646.233371493733;4673.509281525391;4706.835260765857;9457.574409151604;9514.238860891111;9514.762130946643;9522.666706665372;9551.952240238894;5204.089284375219;5208.218767184952;5210.166373380241;5214.348206474191;5243.318368612056;4215.578442155821;4251.936220956688;4722.95277047232;4726.574608285202;4766.475687674353;3792.488014118114;3853.0560012797046;3909.4135879618284;3922.7057970651745;3941.349677303213;1272.4083301264957;1287;1290.348374071088;1296.7017585955284;1297.5069473599972;795.9482633628795;864.1401805203863;894.1058941058941;895.583553647557;913.8674892140735;5785.537157027427;5799.739011744482;5806.4193580641595;5828.242328497268;5836.358000619928;849.9277561407226;882.9867551986725;888.493558671564;900.7612982559627;948.5636607160783;3643.653852883972;3697.7966211858607;3702.9814850925573;3737.289914916157;3825.6748176404762;7448.776536703907;9999.20006399501;10105.242106842017;10117.494125293706;10118.241131915136;9814.214862810957;9894.30739848218;10023.649172278936;10089.344192627455;10168.33905796121;7769.922300777033;8026.799330016796;8029.116797152308;8033.0360356757565;8044.477108988014;5428.864278392914;5468.070428027166;5488.423715509974;5610.102383618601;5621.915671264853;7584.241575842592;9042.095790421168;9341.065893410605;9477.336586438883;9477.573509191965;9399.624015039322;9487.952560237154;9491.288153388661;9636.614535418785;9664.613415463302;6647.867042659216;6836.555623884432;7022.40309573692;7125.5368401053765;7264.309890560388;7048.625518023936;7300.415966722756;7884.684612615431;7888.368930485432;7901.881471777813;7037.612931288623;7120.928790712027;7187.065681461377;7192.100987376578;7284.089488813898;6886.345797149263;7006.9649651751415;7028.929710702828;7050.929490705233;7125.643717814192;6556.885329493902;6604.801855944329;6628.53600247978;6677.599344039373;6682.966585167043;3940.6059394060367;4771.5228477152;4799.448063472606;4829.078407563374;4918.754062296943;4840;4850.6119510438375;4864.027194561031;4874.8537543873745;4913.582345500672 10:24:57 INFO - PROCESS | 1645 | |4;prototype.html;302.5265459555795;307.7245864950869;310.78711082908205;316.75926296015024;318.285449166621;4509.346144809005;4556.316552517124;4686.578207961283;4799.800049987499;5336.9733151334185;4934.950650493494;5051.605103559898;5201.401838788541;5287.41838397776;5297.311349524566;3182.22035601278;3660.3162545229093;3783.2244389900075;3984.9838291235837;4081.4081958116053;4032.697547683935;4041.5554289028332;4067.532233793122;4071.5724848890886;4076.4700588923547;3528.1708798432346;3922.0977825754485;4031.5766844481345;4047.7368971016936;4063.79681015948;3904.511936007999;4012.879613611596;4044.0294329361036;4096.590340965894;4098.979505102485;3236.9838150809333;3372.291818718072;3533.717302615785;3551.6803487686234;3565.8217089145437;4458;4668.902807840189;4782.819425082316;5000.1249781288125;5156.613254005964;4445.110528287748;4456.77716114193;4632.7220366777765;4672.953270467286;4685.7657117144345;4653.58065789934;5315.415304316522;5606.551475881961;5657.7270114223975;5703.372629010805;3444.9655503444897;3919;3929.5481019682816;3933.8426462941447;3941.9211615767813;5302.663317335747;5455.972720136414;5607.243022192033;5907.527397808165;5939.584229103964;3514;3937.3897045957924;3961.3859851723028;3991.5808840071372;3993.281209382339;4039.848643136692;4048.6963477739287;4053.91892162161;4061.614146656063;4068.898277543026;3913.6477717005605;4661.86014419568;4685.461171965268;4687.882802929886;4751.075814295216;5344.625876188629;5667.943320566824;5668.433156684381;5717.342505611825;5718.513926316226;1170.2568868768394;1170.2978213072088;1261.2999785119164;1314.7962065879804;1328.435414948643;772.3087836386403;782.6830133795822;795.327947884037;823.8137082601036;892.8348505200646;5082.872928176826;5187.870303242374;5214.061468935628;5217.373915130209;5384.488473595081;5251.711155886386;5847.38602446737;5865.97345464542;5871.236739223874;5877.353491115974;5041.873953151201;5300.091966891916;5345.572354211654;5347.786088556492;5355.303810504687;2523.2682522068762;2611.2688447234805;2855.5716642503376;2893;2931.7508011819236;4399.294433923178;5394.004218439202;5571.437138916144;5685.545156387479;5724.341700704473;4967.633900677342;5433.619646624698;5634.859128521738;5683.4884860361935;5723.771049157987;4964.751762411865;5336.91994620081;5452.04589196889;5726.713664316768;5812.534997200299;5283.286756288006;5422.674639521641;5482.533984611353;5553.72231388437;5565.276514053147;4790.065937142223;4884.62018211301;4886.804527818989;5054.064997975386;5536.944630553643;4501.527339629291;4981.37732783402;5551.8896220756815;5686.630369026;5808.186853840551;3131.765117616142;3313.7680362375368;3747.781970859427;3752.3620984432164;3771.0006848185803;4835.228521753038;4874.0495603357;4907.141250281258;4960.379952505937;5504.669719816824;4583.610393116623;4791.6406269529225;4854.2233242681;5113.974430127825;5403.729813509387;5074.720890351066;5164.32863727713;5205;5325.387580428301;5370.275012873369;4702.647301452474;4904.5340692634145;5202.982270283151;5243.449437808975;5288.338957630296;4520.389747384061;4651.883702907455;5376.7580458878665;5520.420355862577;5524.580335731319;5106.514881086291;5272.813616936159;5526.198701188386;5534.723263836872;5591.664500130005 10:24:57 INFO - PROCESS | 1645 | |5;yui.html;663.1048085085139;672.7914346552568;732.930371614697;754.6264599023484;772.4592785050463;14116.858831411682;14871.405143794262;14956.476523321686;14962.625934351647;14972.251387430626;13409;13430.597082087528;13442.798358024638;13473.595792126227;13561.796573051412;637.3244360977367;662.3211208511266;778.2800909159033;796.4026979765176;813.1624426840334;812.6911773526052;812.9268365847073;813.5891374855719;814.8859159717615;823.3783493462428;860.5267103093305;860.6686425726109;862.4394143806513;863.1153068105211;864.6325311742515;850.5194565070777;851.433796525313;852.1478521478522;856.1866227084295;857.1514200941054;627.7394881124328;633.3286716080969;633.7179954920063;635.4947816485904;636.2015670333701;13513.932430337883;13931.303434828218;13935.163890166623;13944.651383715387;14354.49759258421;13552.254625995567;13737.175769453865;13737.519186828513;13856;14047.508337208252;11049.558017679283;11109.005117564315;11220.214584979054;11336.773264534662;11354.545818167264;3636.4363523653874;3642.817859107034;3690.5017822593873;3712.2575484903127;3806.1626442182674;91209;93833;93897.53051234769;95681.5215923923;96751.51624241903;9571.994940531284;10396.22028347877;10448.738781530523;10601;10687.30532515384;17256.482305530855;17863.196156173002;20769.27307544229;21152.259670911444;21187.894060529907;7652.923470765221;7662.693492260247;7672.65473053714;7736.92263077362;7771.222877712184;3372.8650853966055;3820.6818647566547;4184.911922900068;4230.915381692411;4291.270484017725;3130.1705048162257;3220.2271454850666;3241.546183534307;3243.724283435887;3251.821149836782;572.5276646766351;608.9451949324493;620.9441150296495;638.6902352359102;644.2011905237472;568.4542838874727;593.1547544748715;627.9246490421178;635.7393468677913;636.6530241018563;7787.3770098392;9011.909880901238;9101.863472047924;9132.954335228414;9343.90656093444;510.3340141115879;583.743153012673;583.9678817664984;587.6386022596073;597.5518361229078;4090.795460226977;4457.064016556495;4485.775711214426;4519.457665080211;4525.343825145335;79944.60166195023;94479;95641;96718.51640741751;115568;66758.33241667521;76867.61566192245;92745;98375;99247.99999999856;78533;84479.57760211283;86635.26729465375;87872.12127878639;88798.33604991731;7715.382769378324;9175.77060573491;9606.27952903549;9613.759656008655;9637.18083962871;51847;52448.47551524436;52746.47253527416;53180;54132.72933635306;71704.64147679228;74907;75324.62337688496;76163;77277;9768.804623907625;10175.847362289423;12027.819582706441;12236.938815305866;12301.077419193418;13026.934865325613;13557.864421356055;13653.112547684368;13842;14574;10733.785324293314;12988;13005.479780808662;13219.933900330438;13442.731145376842;12816.935915320364;13294.401751920994;13374.465021399035;13421.463141474233;13444.126131801402;12252;12603.621891343275;12722.554710585086;12800.935995319964;13131.34343282813;10577;10627.840582391116;11174.832377514507;11268.605598804003;11295.983361497505;10234.283600148216;11040.723981900517;11042.834357484804;11304.660860174208;11354.772904541698 10:24:57 INFO - PROCESS | 1645 | __end_tp_report 10:24:57 INFO - PROCESS | 1645 | __start_cc_report 10:24:57 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,364 10:24:57 INFO - PROCESS | 1645 | __end_cc_report 10:24:57 INFO - PROCESS | 1645 | __startTimestamp1484245497161__endTimestamp 10:24:57 INFO - PROCESS | 1645 | 10:24:57 INFO - PROCESS | 1645 | ------- Summary: start ------- 10:24:57 INFO - PROCESS | 1645 | Number of tests: 6 10:24:57 INFO - PROCESS | 1645 | 10:24:57 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5537.23 Median:5685.89 stddev:647.27 (11.4%) stddev-sans-first:625.09 10:24:57 INFO - PROCESS | 1645 | Values: 3304.0 3326.9 3347.2 3352.3 3364.2 4809.6 5304.7 5477.8 5479.7 5620.1 5514.4 5567.2 5585.9 5591.2 5613.9 5051.4 5062.7 5145.8 5170.2 5221.6 5116.3 5119.4 5144.4 5165.2 5184.0 4987.2 5036.4 5086.7 5119.2 5145.5 4977.3 4994.2 5007.7 5050.7 5087.8 4716.4 4898.3 4938.6 5058.2 5073.1 4896.1 5064.8 5509.9 5529.6 5548.8 6067.5 6112.6 6116.9 6122.1 6125.9 6140.3 6182.6 6188.4 6208.1 6229.3 5520.1 5577.0 5618.4 5696.7 5700.9 6193.2 6238.5 6245.8 6252.7 6310.3 5984.7 6004.2 6006.2 6019.4 6071.3 5944.9 5988.8 6121.8 6160.6 6169.3 5877.2 5911.9 5970.0 6052.1 6058.7 6066.8 6068.1 6081.3 6087.5 6096.1 4824.7 4825.4 4828.6 4835.0 4876.1 3637.2 4283.3 4286.8 4303.3 4322.6 5453.3 5615.4 5649.6 5669.1 5699.5 4962.6 5873.0 6004.3 6160.4 6180.7 4733.4 5626.1 5630.3 5645.9 5685.9 5272.3 5297.0 5301.0 5304.4 5305.6 5146.6 6183.6 6210.9 6268.9 6316.4 4984.0 5865.1 6082.4 6139.3 6140.5 6246.7 6251.2 6254.0 6275.8 6310.0 5276.5 5546.4 5933.9 6001.6 6030.1 5076.2 5615.3 5944.2 6129.6 6169.0 5802.8 5827.2 5848.2 5866.9 5869.6 5809.5 5828.2 5884.7 5894.2 5909.9 4788.4 5668.2 5723.8 5744.1 5744.7 5719.9 5865.4 5872.5 5880.8 5891.4 4720.4 5639.3 5728.1 5797.0 5872.2 10:24:57 INFO - PROCESS | 1645 | 10:24:57 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:71455.78 Median:40178.30 stddev:160004.84 (398.2%) stddev-sans-first:160364.99 10:24:57 INFO - PROCESS | 1645 | Values: 493.6 494.9 525.9 534.2 536.9 38610.2 40262.8 40453.6 40496.6 40523.8 41541.4 42085.2 42130.4 42196.4 42350.6 9375.5 9389.6 9516.8 9530.2 9552.7 9414.2 9494.4 9527.8 9569.0 9588.5 9531.2 9565.7 9590.5 9599.8 9601.5 9511.9 9559.9 9569.7 9582.7 9597.9 9413.6 9456.1 9498.5 9502.5 9506.3 34864.7 37470.3 39698.0 39994.0 40093.8 3110.6 3271.4 3274.9 3568.5 3813.9 28389.7 30303.7 30947.4 31143.8 31470.4 2839.0 3113.4 3138.9 3179.6 3197.2 455659.0 460148.0 461673.0 463588.4 464533.0 50482.0 60236.7 60538.7 60839.0 61432.2 47358.8 57829.7 58256.4 63103.4 64005.4 16003.5 16149.6 16322.8 16330.5 16375.3 12298.9 12528.9 12616.6 12673.4 12702.3 14832.6 18062.8 18115.5 18207.9 18208.3 6104.1 6134.4 7085.8 7102.9 7165.4 823.6 846.9 958.7 996.0 1042.1 29375.6 29651.7 30009.7 30089.0 30487.8 1028.3 1049.9 1069.3 1073.5 1113.0 15644.5 15702.1 15703.5 15728.2 15807.0 677026.0 680028.0 686841.0 687390.0 688278.0 41914.0 51120.7 51669.5 55800.7 56276.2 1335.2 1342.4 1357.2 1358.6 1363.1 24011.4 24662.6 28928.9 29516.3 30065.2 50572.7 51104.0 51375.0 51422.0 51596.2 623310.0 623678.0 624157.0 624353.0 625786.0 26595.6 27060.2 27392.0 27471.6 27476.0 45185.5 46145.0 46258.5 46615.8 46824.5 38134.8 38588.2 38804.4 39781.6 40465.6 37976.6 38133.4 38307.8 39326.6 39525.8 37417.6 37914.8 38169.4 38180.4 38201.9 30856.0 34121.0 34555.0 34661.3 35202.0 25424.4 25458.5 25488.9 25696.9 25911.7 10:24:57 INFO - PROCESS | 1645 | 10:24:57 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:32390.58 Median:367.39 stddev:92808.55 (25261.6%) stddev-sans-first:93037.76 10:24:57 INFO - PROCESS | 1645 | Values: 367.0 367.8 377.4 378.8 396.1 19007.0 19296.4 19767.7 20002.9 20286.7 11352.0 11422.3 11717.6 11725.9 11751.0 5127.8 5395.6 5458.2 5513.6 5551.6 3977.4 4170.4 4879.4 5456.3 5494.4 4460.3 6766.9 6777.3 6845.3 6855.7 6106.4 6150.9 6194.7 6770.3 6807.1 5961.7 5962.9 5971.9 5973.2 5987.1 12755.5 12764.6 12992.6 13080.7 13119.9 2135.5 2152.1 2219.8 2343.0 2880.4 9391.0 9913.4 10155.4 10351.0 10441.4 1825.8 1827.5 1844.1 1848.7 1887.9 90288.0 121217.0 122204.4 125625.0 125767.7 9786.7 10981.5 11755.6 11779.6 12009.8 10410.1 14388.4 14629.3 14638.3 14648.6 7045.7 7255.5 8064.5 8233.6 8466.0 7031.2 7701.9 8311.9 8320.5 8413.6 3770.5 3852.9 4539.5 4578.8 4584.9 1885.3 1958.8 1960.0 1969.2 1995.9 706.3 806.7 830.5 926.0 949.6 16153.8 16870.5 20611.5 20848.7 21063.5 879.7 893.6 897.1 950.0 1003.9 10654.5 11053.5 11140.7 11298.6 11355.5 483959.2 516152.0 575193.0 592069.0 593194.0 120430.0 124475.0 124858.4 125113.4 126972.4 860.9 939.1 995.3 995.5 995.7 14007.9 18488.2 19638.6 19750.6 19906.5 39107.8 40255.0 47872.0 49879.5 51995.0 64603.0 69790.7 70456.6 71354.0 72216.9 6563.9 7875.6 7877.0 8225.4 8252.4 10140.4 12303.6 12308.2 12444.9 12740.9 10831.4 10831.9 10842.6 10997.3 11127.6 8215.8 9116.0 10698.4 10775.3 10809.5 10751.3 11147.9 11176.5 11272.4 11633.3 8140.3 8175.8 8245.2 8254.2 8274.5 10531.8 10966.0 10991.0 11199.7 11313.9 10:24:57 INFO - PROCESS | 1645 | 10:24:57 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5422.13 Median:5206.15 stddev:2973.09 (57.1%) stddev-sans-first:2966.37 10:24:57 INFO - PROCESS | 1645 | Values: 1439.4 1475.4 1619.3 1702.9 1758.3 7493.1 7774.5 8053.6 8554.4 8557.6 7978.5 8273.8 8283.5 8363.3 8411.3 2303.5 2337.6 2338.0 2339.9 2346.0 2317.9 2357.1 2359.3 2360.5 2378.5 2088.1 2198.3 2302.1 2314.9 2337.7 2223.7 2229.3 2249.4 2251.8 2269.8 1943.5 2309.6 2334.8 2335.0 2352.8 8335.5 8380.1 8422.0 8423.1 8494.7 4697.6 4946.2 5029.2 5195.9 5220.1 3218.4 3307.5 3513.6 3513.6 3530.7 1033.7 1040.2 1067.7 1078.8 1082.8 10973.7 10986.0 11043.8 11125.1 11150.1 4481.8 4564.3 4646.2 4673.5 4706.8 9457.6 9514.2 9514.8 9522.7 9552.0 5204.1 5208.2 5210.2 5214.3 5243.3 4215.6 4251.9 4723.0 4726.6 4766.5 3792.5 3853.1 3909.4 3922.7 3941.3 1272.4 1287.0 1290.3 1296.7 1297.5 795.9 864.1 894.1 895.6 913.9 5785.5 5799.7 5806.4 5828.2 5836.4 849.9 883.0 888.5 900.8 948.6 3643.7 3697.8 3703.0 3737.3 3825.7 7448.8 9999.2 10105.2 10117.5 10118.2 9814.2 9894.3 10023.6 10089.3 10168.3 7769.9 8026.8 8029.1 8033.0 8044.5 5428.9 5468.1 5488.4 5610.1 5621.9 7584.2 9042.1 9341.1 9477.3 9477.6 9399.6 9488.0 9491.3 9636.6 9664.6 6647.9 6836.6 7022.4 7125.5 7264.3 7048.6 7300.4 7884.7 7888.4 7901.9 7037.6 7120.9 7187.1 7192.1 7284.1 6886.3 7007.0 7028.9 7050.9 7125.6 6556.9 6604.8 6628.5 6677.6 6683.0 3940.6 4771.5 4799.4 4829.1 4918.8 4840.0 4850.6 4864.0 4874.9 4913.6 10:24:57 INFO - PROCESS | 1645 | 10:24:57 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4424.71 Median:4895.67 stddev:1343.68 (27.4%) stddev-sans-first:1311.34 10:24:57 INFO - PROCESS | 1645 | Values: 302.5 307.7 310.8 316.8 318.3 4509.3 4556.3 4686.6 4799.8 5337.0 4935.0 5051.6 5201.4 5287.4 5297.3 3182.2 3660.3 3783.2 3985.0 4081.4 4032.7 4041.6 4067.5 4071.6 4076.5 3528.2 3922.1 4031.6 4047.7 4063.8 3904.5 4012.9 4044.0 4096.6 4099.0 3237.0 3372.3 3533.7 3551.7 3565.8 4458.0 4668.9 4782.8 5000.1 5156.6 4445.1 4456.8 4632.7 4673.0 4685.8 4653.6 5315.4 5606.6 5657.7 5703.4 3445.0 3919.0 3929.5 3933.8 3941.9 5302.7 5456.0 5607.2 5907.5 5939.6 3514.0 3937.4 3961.4 3991.6 3993.3 4039.8 4048.7 4053.9 4061.6 4068.9 3913.6 4661.9 4685.5 4687.9 4751.1 5344.6 5667.9 5668.4 5717.3 5718.5 1170.3 1170.3 1261.3 1314.8 1328.4 772.3 782.7 795.3 823.8 892.8 5082.9 5187.9 5214.1 5217.4 5384.5 5251.7 5847.4 5866.0 5871.2 5877.4 5041.9 5300.1 5345.6 5347.8 5355.3 2523.3 2611.3 2855.6 2893.0 2931.8 4399.3 5394.0 5571.4 5685.5 5724.3 4967.6 5433.6 5634.9 5683.5 5723.8 4964.8 5336.9 5452.0 5726.7 5812.5 5283.3 5422.7 5482.5 5553.7 5565.3 4790.1 4884.6 4886.8 5054.1 5536.9 4501.5 4981.4 5551.9 5686.6 5808.2 3131.8 3313.8 3747.8 3752.4 3771.0 4835.2 4874.0 4907.1 4960.4 5504.7 4583.6 4791.6 4854.2 5114.0 5403.7 5074.7 5164.3 5205.0 5325.4 5370.3 4702.6 4904.5 5203.0 5243.4 5288.3 4520.4 4651.9 5376.8 5520.4 5524.6 5106.5 5272.8 5526.2 5534.7 5591.7 10:24:57 INFO - PROCESS | 1645 | 10:24:57 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:20028.95 Median:52147.74 stddev:28974.14 (55.6%) stddev-sans-first:29018.94 10:24:57 INFO - PROCESS | 1645 | Values: 663.1 672.8 732.9 754.6 772.5 14116.9 14871.4 14956.5 14962.6 14972.3 13409.0 13430.6 13442.8 13473.6 13561.8 637.3 662.3 778.3 796.4 813.2 812.7 812.9 813.6 814.9 823.4 860.5 860.7 862.4 863.1 864.6 850.5 851.4 852.1 856.2 857.2 627.7 633.3 633.7 635.5 636.2 13513.9 13931.3 13935.2 13944.7 14354.5 13552.3 13737.2 13737.5 13856.0 14047.5 11049.6 11109.0 11220.2 11336.8 11354.5 3636.4 3642.8 3690.5 3712.3 3806.2 91209.0 93833.0 93897.5 95681.5 96751.5 9572.0 10396.2 10448.7 10601.0 10687.3 17256.5 17863.2 20769.3 21152.3 21187.9 7652.9 7662.7 7672.7 7736.9 7771.2 3372.9 3820.7 4184.9 4230.9 4291.3 3130.2 3220.2 3241.5 3243.7 3251.8 572.5 608.9 620.9 638.7 644.2 568.5 593.2 627.9 635.7 636.7 7787.4 9011.9 9101.9 9133.0 9343.9 510.3 583.7 584.0 587.6 597.6 4090.8 4457.1 4485.8 4519.5 4525.3 79944.6 94479.0 95641.0 96718.5 115568.0 66758.3 76867.6 92745.0 98375.0 99248.0 78533.0 84479.6 86635.3 87872.1 88798.3 7715.4 9175.8 9606.3 9613.8 9637.2 51847.0 52448.5 52746.5 53180.0 54132.7 71704.6 74907.0 75324.6 76163.0 77277.0 9768.8 10175.8 12027.8 12236.9 12301.1 13026.9 13557.9 13653.1 13842.0 14574.0 10733.8 12988.0 13005.5 13219.9 13442.7 12816.9 13294.4 13374.5 13421.5 13444.1 12252.0 12603.6 12722.6 12800.9 13131.3 10577.0 10627.8 11174.8 11268.6 11296.0 10234.3 11040.7 11042.8 11304.7 11354.8 10:24:57 INFO - PROCESS | 1645 | -------- Summary: end -------- 10:24:57 INFO - PROCESS | 1645 | 10:24:57 INFO - TEST-INFO | 1645: exit 0 10:24:57 INFO - TEST-OK | dromaeo_css | took 1116332ms 10:24:57 INFO - TEST-START | kraken 10:24:57 INFO - operating with platform_type : mac_ 10:24:57 INFO - Initialising browser for kraken test... 10:24:57 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmpyZR2AE/profile http://localhost:49235/getInfo.html) 10:25:00 INFO - PROCESS | 1657 | 2017-01-12 10:25:00.803 plugin-container[1658:10060] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 10:25:00 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:25:00 INFO - PROCESS | 1657 | 2017-01-12 10:25:00.809 plugin-container[1658:10060] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4223, name = 'com.apple.CFPasteboardClient' 10:25:00 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:25:00 INFO - PROCESS | 1657 | 2017-01-12 10:25:00.809 plugin-container[1658:10060] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:25:00 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 10:25:00 INFO - PROCESS | 1657 | colorDepth:24 10:25:00 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 10:25:00 INFO - PROCESS | 1657 | __metrics 10:25:00 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 10:25:01 INFO - TEST-INFO | 1657: exit 0 10:25:01 INFO - Browser initialized. 10:25:01 INFO - Running cycle 1/1 for kraken test... 10:25:01 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmpyZR2AE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:25:04 INFO - PROCESS | 1659 | 2017-01-12 10:25:04.374 plugin-container[1660:10178] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 10:25:04 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:25:04 INFO - PROCESS | 1659 | 2017-01-12 10:25:04.375 plugin-container[1660:10178] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c13, name = 'com.apple.CFPasteboardClient' 10:25:04 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:25:04 INFO - PROCESS | 1659 | 2017-01-12 10:25:04.375 plugin-container[1660:10178] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:25:14 INFO - PROCESS | 1659 | RSS: Main: 228958208 10:25:14 INFO - PROCESS | 1659 | 10:25:17 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 10:25:17 INFO - PROCESS | 1659 | RSS: Main: 241385472 10:25:17 INFO - PROCESS | 1659 | 10:25:20 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 10:25:21 INFO - PROCESS | 1659 | RSS: Main: 249475072 10:25:21 INFO - PROCESS | 1659 | 10:25:24 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 10:25:25 INFO - PROCESS | 1659 | RSS: Main: 253255680 10:25:25 INFO - PROCESS | 1659 | 10:25:27 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 10:25:27 INFO - PROCESS | 1659 | RSS: Main: 253587456 10:25:27 INFO - PROCESS | 1659 | 10:25:29 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:25:30 INFO - PROCESS | 1659 | RSS: Main: 253464576 10:25:30 INFO - PROCESS | 1659 | 10:25:32 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 10:25:33 INFO - PROCESS | 1659 | RSS: Main: 257544192 10:25:33 INFO - PROCESS | 1659 | 10:25:36 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 10:25:37 INFO - PROCESS | 1659 | RSS: Main: 258260992 10:25:37 INFO - PROCESS | 1659 | 10:25:39 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 10:25:40 INFO - PROCESS | 1659 | RSS: Main: 257859584 10:25:40 INFO - PROCESS | 1659 | 10:25:42 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:25:42 INFO - PROCESS | 1659 | RSS: Main: 257908736 10:25:42 INFO - PROCESS | 1659 | 10:25:44 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:25:45 INFO - PROCESS | 1659 | RSS: Main: 257912832 10:25:45 INFO - PROCESS | 1659 | 10:25:47 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:25:48 INFO - PROCESS | 1659 | RSS: Main: 257781760 10:25:48 INFO - PROCESS | 1659 | 10:25:51 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:25:52 INFO - PROCESS | 1659 | RSS: Main: 257679360 10:25:52 INFO - PROCESS | 1659 | 10:25:55 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:25:55 INFO - PROCESS | 1659 | RSS: Main: 258015232 10:25:55 INFO - PROCESS | 1659 | 10:25:57 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 10:25:57 INFO - PROCESS | 1659 | RSS: Main: 259727360 10:25:57 INFO - PROCESS | 1659 | 10:25:57 INFO - PROCESS | 1659 | __start_tp_report 10:25:57 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 10:25:57 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 10:25:57 INFO - PROCESS | 1659 | |i|pagename|runs| 10:25:57 INFO - PROCESS | 1659 | |0;ai-astar;104;116;116;117;117;116;121;118;117;121 10:25:57 INFO - PROCESS | 1659 | |1;audio-beat-detection;141;113;113;146;126;124;118;114;146;115 10:25:57 INFO - PROCESS | 1659 | |2;audio-dft;194;200;201;199;198;193;196;198;227;261 10:25:57 INFO - PROCESS | 1659 | |3;audio-fft;70;59;61;84;60;61;62;61;96;85 10:25:57 INFO - PROCESS | 1659 | |4;audio-oscillator;83;71;90;74;70;71;87;68;72;89 10:25:57 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;90;87;89;87;88;87;89;88;89;85 10:25:57 INFO - PROCESS | 1659 | |6;imaging-darkroom;95;95;95;96;95;95;97;98;105;119 10:25:57 INFO - PROCESS | 1659 | |7;imaging-desaturate;82;88;86;87;88;106;143;82;135;142 10:25:57 INFO - PROCESS | 1659 | |8;json-parse-financial;60;57;57;59;61;57;56;61;60;60 10:25:57 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;59;94;77;107;119;64;83;110;105;96 10:25:57 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;84;81;86;87;84;86;85;86;85;86 10:25:57 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;158;160;151;156;159;145;165;173;217;165 10:25:57 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;167;169;167;169;172;166;171;172;171;169 10:25:57 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;73;66;84;97;91;88;92;83;70;70 10:25:57 INFO - PROCESS | 1659 | __end_tp_report 10:25:57 INFO - PROCESS | 1659 | __start_cc_report 10:25:57 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,948 10:25:57 INFO - PROCESS | 1659 | __end_cc_report 10:25:57 INFO - PROCESS | 1659 | __startTimestamp1484245557870__endTimestamp 10:25:57 INFO - PROCESS | 1659 | 10:25:57 INFO - PROCESS | 1659 | ------- Summary: start ------- 10:25:57 INFO - PROCESS | 1659 | Number of tests: 14 10:25:57 INFO - PROCESS | 1659 | 10:25:57 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:116.30 Median:117.00 stddev:4.72 (4.0%) stddev-sans-first:2.00 10:25:57 INFO - PROCESS | 1659 | Values: 104.0 116.0 116.0 117.0 117.0 116.0 121.0 118.0 117.0 121.0 10:25:57 INFO - PROCESS | 1659 | 10:25:57 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:125.60 Median:125.00 stddev:13.72 (11.0%) stddev-sans-first:13.37 10:25:57 INFO - PROCESS | 1659 | Values: 141.0 113.0 113.0 146.0 126.0 124.0 118.0 114.0 146.0 115.0 10:25:57 INFO - PROCESS | 1659 | 10:25:57 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:206.70 Median:199.50 stddev:21.38 (10.7%) stddev-sans-first:22.17 10:25:57 INFO - PROCESS | 1659 | Values: 194.0 200.0 201.0 199.0 198.0 193.0 196.0 198.0 227.0 261.0 10:25:57 INFO - PROCESS | 1659 | 10:25:57 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:69.90 Median:66.00 stddev:13.44 (20.4%) stddev-sans-first:14.25 10:25:57 INFO - PROCESS | 1659 | Values: 70.0 59.0 61.0 84.0 60.0 61.0 62.0 61.0 96.0 85.0 10:25:57 INFO - PROCESS | 1659 | 10:25:57 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:78.50 stddev:8.71 (11.1%) stddev-sans-first:9.01 10:25:57 INFO - PROCESS | 1659 | Values: 83.0 71.0 90.0 74.0 70.0 71.0 87.0 68.0 72.0 89.0 10:25:57 INFO - PROCESS | 1659 | 10:25:57 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:88.50 stddev:1.45 (1.6%) stddev-sans-first:1.32 10:25:57 INFO - PROCESS | 1659 | Values: 90.0 87.0 89.0 87.0 88.0 87.0 89.0 88.0 89.0 85.0 10:25:57 INFO - PROCESS | 1659 | 10:25:57 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:99.00 Median:95.00 stddev:7.67 (8.1%) stddev-sans-first:8.00 10:25:57 INFO - PROCESS | 1659 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 97.0 98.0 105.0 119.0 10:25:57 INFO - PROCESS | 1659 | 10:25:57 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:103.90 Median:84.00 stddev:25.87 (30.8%) stddev-sans-first:26.20 10:25:57 INFO - PROCESS | 1659 | Values: 82.0 88.0 86.0 87.0 88.0 106.0 143.0 82.0 135.0 142.0 10:25:57 INFO - PROCESS | 1659 | 10:25:57 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:60.00 stddev:1.87 (3.1%) stddev-sans-first:1.94 10:25:57 INFO - PROCESS | 1659 | Values: 60.0 57.0 57.0 59.0 61.0 57.0 56.0 61.0 60.0 60.0 10:25:57 INFO - PROCESS | 1659 | 10:25:57 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:91.40 Median:70.50 stddev:20.12 (28.5%) stddev-sans-first:17.59 10:25:57 INFO - PROCESS | 1659 | Values: 59.0 94.0 77.0 107.0 119.0 64.0 83.0 110.0 105.0 96.0 10:25:57 INFO - PROCESS | 1659 | 10:25:57 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:86.00 stddev:1.70 (2.0%) stddev-sans-first:1.76 10:25:57 INFO - PROCESS | 1659 | Values: 84.0 81.0 86.0 87.0 84.0 86.0 85.0 86.0 85.0 86.0 10:25:57 INFO - PROCESS | 1659 | 10:25:57 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:164.90 Median:162.50 stddev:19.87 (12.2%) stddev-sans-first:20.92 10:25:57 INFO - PROCESS | 1659 | Values: 158.0 160.0 151.0 156.0 159.0 145.0 165.0 173.0 217.0 165.0 10:25:57 INFO - PROCESS | 1659 | 10:25:57 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.30 Median:170.00 stddev:2.16 (1.3%) stddev-sans-first:2.13 10:25:57 INFO - PROCESS | 1659 | Values: 167.0 169.0 167.0 169.0 172.0 166.0 171.0 172.0 171.0 169.0 10:25:57 INFO - PROCESS | 1659 | 10:25:57 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.40 Median:86.00 stddev:10.90 (12.7%) stddev-sans-first:11.12 10:25:57 INFO - PROCESS | 1659 | Values: 73.0 66.0 84.0 97.0 91.0 88.0 92.0 83.0 70.0 70.0 10:25:57 INFO - PROCESS | 1659 | -------- Summary: end -------- 10:25:57 INFO - PROCESS | 1659 | 10:25:58 INFO - TEST-INFO | 1659: exit 0 10:25:58 INFO - TEST-OK | kraken | took 61102ms 10:25:58 INFO - SUITE-END | took 1177s 10:25:59 INFO - Completed test suite (00:19:37) 10:25:59 INFO - javascript benchmark 10:25:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7037.647023503682, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3304.041827869918, 3326.933461330773, 3347.2301370684727, 3352.296017836254, 3364.1925937774936, 4809.591184749301, 5304.708241046741, 5477.78088876445, 5479.671219726814, 5620.072688006474, 5514.393416724157, 5567.164925261213, 5585.944140558593, 5591.217229587851, 5613.943860561393, 5051.419086805009, 5062.746862656871, 5145.761593706749, 5170.198619214009, 5221.634485586011, 5116.258142569325, 5119.385673719159, 5144.408393034792, 5165.225216217578, 5184.040952423814, 4987.202047672391, 5036.445990940993, 5086.6693664911845, 5119.2321151827155, 5145.536901678829, 4977.278294647292, 4994.200927851526, 5007.699538027694, 5050.7222102784335, 5087.79648814047, 4716.363290955712, 4898.265260210961, 4938.604911607063, 5058.190689489701, 5073.112205364084, 4896.143174944371, 5064.772085256172, 5509.944900550983, 5529.585281103893, 5548.805791797308, 6067.484263837579, 6112.594670127729, 6116.914073580098, 6122.093225952662, 6125.937554508974, 6140.263168419819, 6182.598131121463, 6188.350223226586, 6208.068789681539, 6229.3459186785185, 5520.089185284463, 5576.968260871752, 5618.353889302783, 5696.661284598157, 5700.942990570083, 6193.225846769154, 6238.500919926395, 6245.843853903598, 6252.6873656317, 6310.337414571496, 5984.670843103584, 6004.219451471281, 6006.219191505076, 6019.367966363557, 6071.301800293023, 5944.851378715567, 5988.820335389945, 6121.755129794848, 6160.568760186744, 6169.259688837278, 5877.206577112121, 5911.852203694942, 5969.98510253258, 6052.061930400795, 6058.727357268934, 6066.787662431793, 6068.059450785136, 6081.331053584102, 6087.54343424245, 6096.085587161872, 4824.662273640812, 4825.420949486014, 4828.61371090312, 4835.032993401333, 4876.122297986326, 3637.181634132299, 4283.2932566126865, 4286.764227967429, 4303.311470164758, 4322.610965013164, 5453.291072160594, 5615.438456154352, 5649.576281778883, 5669.092945128759, 5699.544036477072, 4962.5533701967, 5873.0, 6004.309504406961, 6160.414760597736, 6180.721867515973, 4733.431988161443, 5626.099824028136, 5630.324361076615, 5645.858853528694, 5685.886282274414, 5272.261883336336, 5296.973515132399, 5300.973495132577, 5304.38999515053, 5305.628605997543, 5146.588272938231, 6183.598066125687, 6210.937890621126, 6268.905966410599, 6316.431521163117, 4983.953369792384, 5865.090910908816, 6082.391760823883, 6139.293981192041, 6140.478059364826, 6246.656433896179, 6251.249850018027, 6254.030625253093, 6275.843103922439, 6310.0, 5276.498732620395, 5546.389897111395, 5933.881322373615, 6001.609895356788, 6030.065339872327, 5076.238564215398, 5615.298087739033, 5944.1975333331175, 6129.570930034854, 6169.043798211284, 5802.796902108406, 5827.24245848037, 5848.181254624441, 5866.853328666817, 5869.589128760946, 5809.50619197373, 5828.154917536848, 5884.705764711833, 5894.233749612523, 5909.852253693692, 4788.353572267699, 5668.234788303526, 5723.828285151452, 5744.138379243147, 5744.741486633028, 5719.8570035749435, 5865.4134586541, 5872.530197584098, 5880.764769409176, 5891.351951285269, 4720.409948756405, 5639.2668953035845, 5728.140778883201, 5796.971015144897, 5872.207252020922], "value": 5498.312512346025, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.62731137990824, 494.8914431673052, 525.8956191996805, 534.1800336483496, 536.857732700834, 38610.22779544411, 40262.798686006565, 40453.59546404535, 40496.59503404965, 40523.78428647138, 41541.37687934683, 42085.158296834044, 42130.36804447936, 42196.36705449421, 42350.57649423505, 9375.484348360855, 9389.62441502339, 9516.80966380672, 9530.18993385563, 9552.665656702016, 9414.246860251198, 9494.382865113745, 9527.761805954837, 9568.952155239214, 9588.520573971273, 9531.237500999938, 9565.66520171794, 9590.472524011177, 9599.760005999837, 9601.47191904445, 9511.904880951206, 9559.904400956006, 9569.712908612753, 9582.712518624452, 9597.904020959772, 9413.57638906251, 9456.101670341306, 9498.525073746285, 9502.47736374499, 9506.334556581041, 34864.651353486646, 37470.25059498795, 39698.0, 39994.0, 40093.79953100245, 3110.6422761382278, 3271.4111459937203, 3274.9017529473917, 3568.4647302904514, 3813.8855834325013, 28389.716102838913, 30303.696963030307, 30947.381052379053, 31143.844280778678, 31470.37059258825, 2838.957415638767, 3113.4395808754416, 3138.921526961821, 3179.60254968129, 3197.216681912946, 455659.0, 460148.0, 461673.0, 463588.3641163579, 464533.0, 50481.99036019259, 60236.69881650651, 60538.69730651318, 60839.0, 61432.15703528901, 47358.76320618375, 57829.710851445474, 58256.41743582595, 63103.36896630975, 64005.35994640087, 16003.519894403187, 16149.59626009359, 16322.75515867263, 16330.510084697478, 16375.344986200656, 12298.877011229952, 12528.874711252953, 12616.621501354974, 12673.366331683379, 12702.30137342455, 14832.629184270263, 18062.819371806378, 18115.54711132232, 18207.907525548508, 18208.27021432962, 6104.084387341845, 6134.38656134383, 7085.751998680124, 7102.874735116636, 7165.426765858823, 823.5717426937958, 846.8814365988767, 958.736347504428, 995.9502024898726, 1042.1298215989582, 29375.55936660952, 29651.703482965324, 30009.69990300113, 30089.0, 30487.847560762053, 1028.3058935218698, 1049.9055085042382, 1069.2835800014009, 1073.5078241244676, 1112.9610463633735, 15644.530664080095, 15702.136382499073, 15703.528894133195, 15728.213589320489, 15806.972546784422, 677026.0, 680028.0, 686841.0, 687390.0, 688278.0, 41914.0, 51120.74439627778, 51669.48330516647, 55800.72099639476, 56276.1861906903, 1335.2322414611442, 1342.4227582139579, 1357.1992524410646, 1358.5584684977423, 1363.0731102850157, 24011.399715007265, 24662.63006054875, 28928.855355723088, 29516.262093447836, 30065.248368790955, 50572.747136264086, 51104.0, 51375.0, 51422.0, 51596.22605660993, 623310.0, 623678.0, 624157.0, 624353.0, 625786.0, 26595.601065984414, 27060.1881943542, 27392.0, 27471.587926181524, 27476.0, 45185.548144519445, 46145.0, 46258.537414625425, 46615.766921165174, 46824.53175468338, 38134.809325953196, 38588.22823543457, 38804.41793373158, 39781.60218397895, 40465.59534404736, 37976.62023379731, 38133.427998579486, 38307.808460957516, 39326.60673393229, 39525.81422557328, 37417.625823741415, 37914.8104259477, 38169.42745858759, 38180.427293590066, 38201.85394438171, 30856.0, 34120.976370708915, 34555.0, 34661.30677386488, 35202.0, 25424.364390890376, 25458.490830183662, 25488.872555637103, 25696.87151564305, 25911.740882590933], "value": 19686.743891300583, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.99266014679705, 367.78668372344043, 377.3773274097739, 378.78030742169545, 396.1482811954298, 19006.95461749603, 19296.421107366783, 19767.70348444771, 20002.89998550007, 20286.695699564483, 11352.0, 11422.257553259053, 11717.648470545875, 11725.941370293158, 11751.001164900994, 5127.769250383723, 5395.568354531628, 5458.153986132156, 5513.61404701671, 5551.583631227674, 3977.3636218205093, 4170.416141740159, 4879.438864530571, 5456.290682211307, 5494.423085575997, 4460.308652158904, 6766.932330676679, 6777.2545020047755, 6845.315468453165, 6855.657217139148, 6106.389361063858, 6150.8769824603705, 6194.721237544299, 6770.289119642441, 6807.081044059063, 5961.672108034057, 5962.880742385171, 5971.850703732398, 5973.223480947492, 5987.072003839412, 12755.489780408772, 12764.61706148817, 12992.610221693365, 13080.672983175496, 13119.86880131196, 2135.47680818199, 2152.0638522242875, 2219.766924472923, 2343.027643527934, 2880.3663194097476, 9391.0, 9913.40519568821, 10155.441450720207, 10350.964903509665, 10441.373517588969, 1825.8040983156054, 1827.5065732252217, 1844.1088212682507, 1848.7042073268346, 1887.858410619209, 90288.0, 121217.0, 122204.38897805543, 125625.0, 125767.74232257652, 9786.727725395653, 10981.505832237568, 11755.588554400554, 11779.587714429954, 12009.819852702216, 10410.115140213014, 14388.352524136439, 14629.268536573129, 14638.341274642667, 14648.633784155481, 7045.68294426752, 7255.45584081196, 8064.516129032277, 8233.629486673113, 8466.0, 7031.191412987572, 7701.922980770232, 8311.916880831235, 8320.459170153921, 8413.621387037598, 3770.472133901256, 3852.8826640274565, 4539.546045395434, 4578.839740609063, 4584.862454126315, 1885.33070759881, 1958.7943265957153, 1959.9612205530807, 1969.212315073982, 1995.890226037582, 706.336044118527, 806.6531391501711, 830.5126147189206, 925.9582620322174, 949.5537097564134, 16153.757693634605, 16870.493885183463, 20611.4847128823, 20848.687269690967, 21063.473413164487, 879.7228872905015, 893.5784025291533, 897.0536084430988, 950.0, 1003.9096481316717, 10654.520546575422, 11053.502592383362, 11140.721481963015, 11298.60454884075, 11355.54577816878, 483959.1604083984, 516152.0, 575193.0, 592069.0, 593194.0, 120430.0, 124475.0, 124858.37570812088, 125113.37443312725, 126972.36513817371, 860.9096044915319, 939.0937745076035, 995.3281534964016, 995.4773743784571, 995.6913356859396, 14007.929960350133, 18488.16803243884, 19638.60722785565, 19750.60498790045, 19906.5023374411, 39107.80446097751, 40255.0, 47872.0, 49879.501204987486, 51995.0, 64603.0, 69790.65104674647, 70456.64771676109, 71354.0, 72216.91674624779, 6563.901541476786, 7875.606219688878, 7876.960615197117, 8225.383096267684, 8252.422330437052, 10140.391576505433, 12303.630891073282, 12308.199967002474, 12444.875551244733, 12740.936295318465, 10831.350118992885, 10831.945840270748, 10842.620508282173, 10997.285176463503, 11127.55489780432, 8215.84978103078, 9115.951665558545, 10698.41158736277, 10775.299605525615, 10809.45952702346, 10751.270947090981, 11147.88852111501, 11176.497057632589, 11272.436378180893, 11633.302001879914, 8140.348772098337, 8175.79560510992, 8245.175482451707, 8254.215849494289, 8274.503529788231, 10531.842022369518, 10965.95823396776, 10991.0, 11199.66401007971, 11313.943430283125], "value": 8954.414572900941, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1439.352291468839, 1475.3803402570923, 1619.3036994092542, 1702.9489115326544, 1758.2703178181055, 7493.1008279006455, 7774.494657847236, 8053.637586308616, 8554.40119191656, 8557.572121393929, 7978.521288722681, 8273.83452330953, 8283.46157499762, 8363.330933525318, 8411.285040771541, 2303.493231489074, 2337.637666161739, 2337.9766202338014, 2339.9298021059312, 2345.976540234593, 2317.8609283443047, 2357.0571771291447, 2359.292212336301, 2360.527894421114, 2378.512404956988, 2088.1021160900805, 2198.2635817001324, 2302.1482051640915, 2314.930552083432, 2337.6610391493223, 2223.67756675282, 2229.2532001779423, 2249.4488850231796, 2251.797338239558, 2269.7843704848096, 1943.4558323669253, 2309.6488554195817, 2334.8248881333798, 2335.019291897407, 2352.8117750579913, 8335.54154521501, 8380.078191398941, 8421.989361276625, 8423.073461919184, 8494.74515764528, 4697.577218050392, 4946.208606622923, 5029.170186919154, 5195.948040519622, 5220.138677118271, 3218.4045951499047, 3307.520409540627, 3513.5959364672876, 3513.6310687377713, 3530.735194860396, 1033.6950599573145, 1040.1522758951505, 1067.7223921780394, 1078.8273876179774, 1082.7726177502655, 10973.670789876316, 10986.0, 11043.83434248487, 11125.109991200765, 11150.107991360672, 4481.820727170877, 4564.269716845289, 4646.233371493733, 4673.509281525391, 4706.835260765857, 9457.574409151604, 9514.238860891111, 9514.762130946643, 9522.666706665372, 9551.952240238894, 5204.089284375219, 5208.218767184952, 5210.166373380241, 5214.348206474191, 5243.318368612056, 4215.578442155821, 4251.936220956688, 4722.95277047232, 4726.574608285202, 4766.475687674353, 3792.488014118114, 3853.0560012797046, 3909.4135879618284, 3922.7057970651745, 3941.349677303213, 1272.4083301264957, 1287.0, 1290.348374071088, 1296.7017585955284, 1297.5069473599972, 795.9482633628795, 864.1401805203863, 894.1058941058941, 895.583553647557, 913.8674892140735, 5785.537157027427, 5799.739011744482, 5806.4193580641595, 5828.242328497268, 5836.358000619928, 849.9277561407226, 882.9867551986725, 888.493558671564, 900.7612982559627, 948.5636607160783, 3643.653852883972, 3697.7966211858607, 3702.9814850925573, 3737.289914916157, 3825.6748176404762, 7448.776536703907, 9999.20006399501, 10105.242106842017, 10117.494125293706, 10118.241131915136, 9814.214862810957, 9894.30739848218, 10023.649172278936, 10089.344192627455, 10168.33905796121, 7769.922300777033, 8026.799330016796, 8029.116797152308, 8033.0360356757565, 8044.477108988014, 5428.864278392914, 5468.070428027166, 5488.423715509974, 5610.102383618601, 5621.915671264853, 7584.241575842592, 9042.095790421168, 9341.065893410605, 9477.336586438883, 9477.573509191965, 9399.624015039322, 9487.952560237154, 9491.288153388661, 9636.614535418785, 9664.613415463302, 6647.867042659216, 6836.555623884432, 7022.40309573692, 7125.5368401053765, 7264.309890560388, 7048.625518023936, 7300.415966722756, 7884.684612615431, 7888.368930485432, 7901.881471777813, 7037.612931288623, 7120.928790712027, 7187.065681461377, 7192.100987376578, 7284.089488813898, 6886.345797149263, 7006.9649651751415, 7028.929710702828, 7050.929490705233, 7125.643717814192, 6556.885329493902, 6604.801855944329, 6628.53600247978, 6677.599344039373, 6682.966585167043, 3940.6059394060367, 4771.5228477152, 4799.448063472606, 4829.078407563374, 4918.754062296943, 4840.0, 4850.6119510438375, 4864.027194561031, 4874.8537543873745, 4913.582345500672], "value": 4398.135063919136, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.5265459555795, 307.7245864950869, 310.78711082908205, 316.75926296015024, 318.285449166621, 4509.346144809005, 4556.316552517124, 4686.578207961283, 4799.800049987499, 5336.9733151334185, 4934.950650493494, 5051.605103559898, 5201.401838788541, 5287.41838397776, 5297.311349524566, 3182.22035601278, 3660.3162545229093, 3783.2244389900075, 3984.9838291235837, 4081.4081958116053, 4032.697547683935, 4041.5554289028332, 4067.532233793122, 4071.5724848890886, 4076.4700588923547, 3528.1708798432346, 3922.0977825754485, 4031.5766844481345, 4047.7368971016936, 4063.79681015948, 3904.511936007999, 4012.879613611596, 4044.0294329361036, 4096.590340965894, 4098.979505102485, 3236.9838150809333, 3372.291818718072, 3533.717302615785, 3551.6803487686234, 3565.8217089145437, 4458.0, 4668.902807840189, 4782.819425082316, 5000.1249781288125, 5156.613254005964, 4445.110528287748, 4456.77716114193, 4632.7220366777765, 4672.953270467286, 4685.7657117144345, 4653.58065789934, 5315.415304316522, 5606.551475881961, 5657.7270114223975, 5703.372629010805, 3444.9655503444897, 3919.0, 3929.5481019682816, 3933.8426462941447, 3941.9211615767813, 5302.663317335747, 5455.972720136414, 5607.243022192033, 5907.527397808165, 5939.584229103964, 3514.0, 3937.3897045957924, 3961.3859851723028, 3991.5808840071372, 3993.281209382339, 4039.848643136692, 4048.6963477739287, 4053.91892162161, 4061.614146656063, 4068.898277543026, 3913.6477717005605, 4661.86014419568, 4685.461171965268, 4687.882802929886, 4751.075814295216, 5344.625876188629, 5667.943320566824, 5668.433156684381, 5717.342505611825, 5718.513926316226, 1170.2568868768394, 1170.2978213072088, 1261.2999785119164, 1314.7962065879804, 1328.435414948643, 772.3087836386403, 782.6830133795822, 795.327947884037, 823.8137082601036, 892.8348505200646, 5082.872928176826, 5187.870303242374, 5214.061468935628, 5217.373915130209, 5384.488473595081, 5251.711155886386, 5847.38602446737, 5865.97345464542, 5871.236739223874, 5877.353491115974, 5041.873953151201, 5300.091966891916, 5345.572354211654, 5347.786088556492, 5355.303810504687, 2523.2682522068762, 2611.2688447234805, 2855.5716642503376, 2893.0, 2931.7508011819236, 4399.294433923178, 5394.004218439202, 5571.437138916144, 5685.545156387479, 5724.341700704473, 4967.633900677342, 5433.619646624698, 5634.859128521738, 5683.4884860361935, 5723.771049157987, 4964.751762411865, 5336.91994620081, 5452.04589196889, 5726.713664316768, 5812.534997200299, 5283.286756288006, 5422.674639521641, 5482.533984611353, 5553.72231388437, 5565.276514053147, 4790.065937142223, 4884.62018211301, 4886.804527818989, 5054.064997975386, 5536.944630553643, 4501.527339629291, 4981.37732783402, 5551.8896220756815, 5686.630369026, 5808.186853840551, 3131.765117616142, 3313.7680362375368, 3747.781970859427, 3752.3620984432164, 3771.0006848185803, 4835.228521753038, 4874.0495603357, 4907.141250281258, 4960.379952505937, 5504.669719816824, 4583.610393116623, 4791.6406269529225, 4854.2233242681, 5113.974430127825, 5403.729813509387, 5074.720890351066, 5164.32863727713, 5205.0, 5325.387580428301, 5370.275012873369, 4702.647301452474, 4904.5340692634145, 5202.982270283151, 5243.449437808975, 5288.338957630296, 4520.389747384061, 4651.883702907455, 5376.7580458878665, 5520.420355862577, 5524.580335731319, 5106.514881086291, 5272.813616936159, 5526.198701188386, 5534.723263836872, 5591.664500130005], "value": 3992.0294330757906, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [663.1048085085139, 672.7914346552568, 732.930371614697, 754.6264599023484, 772.4592785050463, 14116.858831411682, 14871.405143794262, 14956.476523321686, 14962.625934351647, 14972.251387430626, 13409.0, 13430.597082087528, 13442.798358024638, 13473.595792126227, 13561.796573051412, 637.3244360977367, 662.3211208511266, 778.2800909159033, 796.4026979765176, 813.1624426840334, 812.6911773526052, 812.9268365847073, 813.5891374855719, 814.8859159717615, 823.3783493462428, 860.5267103093305, 860.6686425726109, 862.4394143806513, 863.1153068105211, 864.6325311742515, 850.5194565070777, 851.433796525313, 852.1478521478522, 856.1866227084295, 857.1514200941054, 627.7394881124328, 633.3286716080969, 633.7179954920063, 635.4947816485904, 636.2015670333701, 13513.932430337883, 13931.303434828218, 13935.163890166623, 13944.651383715387, 14354.49759258421, 13552.254625995567, 13737.175769453865, 13737.519186828513, 13856.0, 14047.508337208252, 11049.558017679283, 11109.005117564315, 11220.214584979054, 11336.773264534662, 11354.545818167264, 3636.4363523653874, 3642.817859107034, 3690.5017822593873, 3712.2575484903127, 3806.1626442182674, 91209.0, 93833.0, 93897.53051234769, 95681.5215923923, 96751.51624241903, 9571.994940531284, 10396.22028347877, 10448.738781530523, 10601.0, 10687.30532515384, 17256.482305530855, 17863.196156173002, 20769.27307544229, 21152.259670911444, 21187.894060529907, 7652.923470765221, 7662.693492260247, 7672.65473053714, 7736.92263077362, 7771.222877712184, 3372.8650853966055, 3820.6818647566547, 4184.911922900068, 4230.915381692411, 4291.270484017725, 3130.1705048162257, 3220.2271454850666, 3241.546183534307, 3243.724283435887, 3251.821149836782, 572.5276646766351, 608.9451949324493, 620.9441150296495, 638.6902352359102, 644.2011905237472, 568.4542838874727, 593.1547544748715, 627.9246490421178, 635.7393468677913, 636.6530241018563, 7787.3770098392, 9011.909880901238, 9101.863472047924, 9132.954335228414, 9343.90656093444, 510.3340141115879, 583.743153012673, 583.9678817664984, 587.6386022596073, 597.5518361229078, 4090.795460226977, 4457.064016556495, 4485.775711214426, 4519.457665080211, 4525.343825145335, 79944.60166195023, 94479.0, 95641.0, 96718.51640741751, 115568.0, 66758.33241667521, 76867.61566192245, 92745.0, 98375.0, 99247.99999999856, 78533.0, 84479.57760211283, 86635.26729465375, 87872.12127878639, 88798.33604991731, 7715.382769378324, 9175.77060573491, 9606.27952903549, 9613.759656008655, 9637.18083962871, 51847.0, 52448.47551524436, 52746.47253527416, 53180.0, 54132.72933635306, 71704.64147679228, 74907.0, 75324.62337688496, 76163.0, 77277.0, 9768.804623907625, 10175.847362289423, 12027.819582706441, 12236.938815305866, 12301.077419193418, 13026.934865325613, 13557.864421356055, 13653.112547684368, 13842.0, 14574.0, 10733.785324293314, 12988.0, 13005.479780808662, 13219.933900330438, 13442.731145376842, 12816.935915320364, 13294.401751920994, 13374.465021399035, 13421.463141474233, 13444.126131801402, 12252.0, 12603.621891343275, 12722.554710585086, 12800.935995319964, 13131.34343282813, 10577.0, 10627.840582391116, 11174.832377514507, 11268.605598804003, 11295.983361497505, 10234.283600148216, 11040.723981900517, 11042.834357484804, 11304.660860174208, 11354.772904541698], "value": 7138.622757777351, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1537.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 116.0, 117.0, 117.0, 116.0, 121.0, 118.0, 117.0, 121.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 113.0, 113.0, 146.0, 126.0, 124.0, 118.0, 114.0, 146.0, 115.0], "value": 125.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 200.0, 201.0, 199.0, 198.0, 193.0, 196.0, 198.0, 227.0, 261.0], "value": 206.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 59.0, 61.0, 84.0, 60.0, 61.0, 62.0, 61.0, 96.0, 85.0], "value": 69.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 90.0, 74.0, 70.0, 71.0, 87.0, 68.0, 72.0, 89.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 87.0, 88.0, 87.0, 89.0, 88.0, 89.0, 85.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 97.0, 98.0, 105.0, 119.0], "value": 99.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 88.0, 86.0, 87.0, 88.0, 106.0, 143.0, 82.0, 135.0, 142.0], "value": 103.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 57.0, 57.0, 59.0, 61.0, 57.0, 56.0, 61.0, 60.0, 60.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 94.0, 77.0, 107.0, 119.0, 64.0, 83.0, 110.0, 105.0, 96.0], "value": 91.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 86.0, 87.0, 84.0, 86.0, 85.0, 86.0, 85.0, 86.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 160.0, 151.0, 156.0, 159.0, 145.0, 165.0, 173.0, 217.0, 165.0], "value": 164.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 169.0, 167.0, 169.0, 172.0, 166.0, 171.0, 172.0, 171.0, 169.0], "value": 169.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 66.0, 84.0, 97.0, 91.0, 88.0, 92.0, 83.0, 70.0, 70.0], "value": 81.4, "unit": "score"}]}]} 10:25:59 INFO - Return code: 0 10:25:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:25:59 ERROR - # TBPL SUCCESS # 10:25:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:25:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:25:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:25:59 INFO - 2017-01-12 10:25:59.203 screenresolution[1664:10560] starting screenresolution argv=screenresolution get 10:25:59 INFO - 2017-01-12 10:25:59.205 screenresolution[1664:10560] Display 0: 1600x1200x32@60 10:25:59 INFO - 2017-01-12 10:25:59.211 screenresolution[1665:10563] starting screenresolution argv=screenresolution list 10:25:59 INFO - Available Modes on Display 0 10:25:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:25:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:25:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:25:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:25:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:25:59 INFO - Intel Iris: 10:25:59 INFO - Chipset Model: Intel Iris 10:25:59 INFO - Type: GPU 10:25:59 INFO - Bus: Built-In 10:25:59 INFO - VRAM (Dynamic, Max): 1536 MB 10:25:59 INFO - Vendor: Intel (0x8086) 10:25:59 INFO - Device ID: 0x0a2e 10:25:59 INFO - Revision ID: 0x0009 10:25:59 INFO - Displays: 10:25:59 INFO - Display: 10:25:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:25:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:25:59 INFO - Display Serial Number: 1600x1200 60 10:25:59 INFO - Main Display: Yes 10:25:59 INFO - Mirror: Off 10:25:59 INFO - Online: Yes 10:25:59 INFO - Rotation: Supported 10:25:59 INFO - Return code: 0 10:25:59 INFO - Running post-action listener: _resource_record_post_action 10:25:59 INFO - [mozharness: 2017-01-12 18:25:59.454961Z] Finished run-tests step (success) 10:25:59 INFO - Running post-run listener: _resource_record_post_run 10:25:59 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 37534208; Write bytes: 659945472; Read time: 243; Write time: 1916 10:25:59 INFO - TinderboxPrint: CPU usage
54.2% 10:25:59 INFO - TinderboxPrint: I/O read bytes / time
37,534,208 / 243 10:25:59 INFO - TinderboxPrint: I/O write bytes / time
659,945,472 / 1,916 10:25:59 INFO - TinderboxPrint: CPU idle
2,217.0 (45.8%) 10:25:59 INFO - TinderboxPrint: CPU system
976.2 (20.2%) 10:25:59 INFO - TinderboxPrint: CPU user
1,644.6 (34.0%) 10:25:59 INFO - TinderboxPrint: Swap in / out
387,198,976 / 0 10:25:59 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 350257664; Write bytes: 351074304; Read time: 26977; Write time: 751 10:25:59 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35002880; Write bytes: 307531776; Read time: 221; Write time: 1159 10:25:59 INFO - Running post-run listener: _upload_blobber_files 10:25:59 INFO - Blob upload gear active. 10:25:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:25:59 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 10:25:59 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'] 10:25:59 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 10:26:00 INFO - (blobuploader) - INFO - Open directory for files ... 10:26:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:26:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:26:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:26:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:26:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:26:01 INFO - (blobuploader) - INFO - Done attempting. 10:26:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:26:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:26:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:26:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:26:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:26:02 INFO - (blobuploader) - INFO - Done attempting. 10:26:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:26:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:26:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:26:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:26:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:26:04 INFO - (blobuploader) - INFO - Done attempting. 10:26:04 INFO - (blobuploader) - INFO - Iteration through files over. 10:26:04 INFO - Return code: 0 10:26:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:26:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:26:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38887fdf6aa7f07ce08f1c5336b4d10eb44847262ddc86a8a8be67a164cf8766d806182ddd73aa770866beba9844cd64989c487d7308b026743b97a8ec21f918", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3aa76c1667baee2216ea9a4f984ed5f20b851e6a97b86c63a0944a595a53bd8507ace1582e7f6d16791a17846b4eecc2791fb9b07e2786832a53923c21ae9eb2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ca1dff6a69a77422d602810725d9d95d6a8d328a497bf7b187d3827f7c39bd1ac925c2d72cc67b9c1d8a0cc790bc6d767b5101955fa300eb5040ee480b0d4f70"} 10:26:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:26:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:26:04 INFO - Contents: 10:26:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38887fdf6aa7f07ce08f1c5336b4d10eb44847262ddc86a8a8be67a164cf8766d806182ddd73aa770866beba9844cd64989c487d7308b026743b97a8ec21f918", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3aa76c1667baee2216ea9a4f984ed5f20b851e6a97b86c63a0944a595a53bd8507ace1582e7f6d16791a17846b4eecc2791fb9b07e2786832a53923c21ae9eb2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ca1dff6a69a77422d602810725d9d95d6a8d328a497bf7b187d3827f7c39bd1ac925c2d72cc67b9c1d8a0cc790bc6d767b5101955fa300eb5040ee480b0d4f70"} 10:26:04 INFO - Running post-run listener: copy_logs_to_upload_dir 10:26:04 INFO - Copying logs to upload dir... 10:26:04 INFO - mkdir: /builds/slave/test/build/upload/logs 10:26:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.340932 ========= master_lag: 0.37 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-01-12 10:26:05.188628) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 10:26:05.192501) ========= 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.86ZRiH4Pxj/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.f2smWyZ3qw/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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/38887fdf6aa7f07ce08f1c5336b4d10eb44847262ddc86a8a8be67a164cf8766d806182ddd73aa770866beba9844cd64989c487d7308b026743b97a8ec21f918", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3aa76c1667baee2216ea9a4f984ed5f20b851e6a97b86c63a0944a595a53bd8507ace1582e7f6d16791a17846b4eecc2791fb9b07e2786832a53923c21ae9eb2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ca1dff6a69a77422d602810725d9d95d6a8d328a497bf7b187d3827f7c39bd1ac925c2d72cc67b9c1d8a0cc790bc6d767b5101955fa300eb5040ee480b0d4f70"} build_url:https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011249 build_url: 'https://queue.taskcluster.net/v1/task/WanZ-aCzSWOyxsbHnl8iag/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/38887fdf6aa7f07ce08f1c5336b4d10eb44847262ddc86a8a8be67a164cf8766d806182ddd73aa770866beba9844cd64989c487d7308b026743b97a8ec21f918", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3aa76c1667baee2216ea9a4f984ed5f20b851e6a97b86c63a0944a595a53bd8507ace1582e7f6d16791a17846b4eecc2791fb9b07e2786832a53923c21ae9eb2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ca1dff6a69a77422d602810725d9d95d6a8d328a497bf7b187d3827f7c39bd1ac925c2d72cc67b9c1d8a0cc790bc6d767b5101955fa300eb5040ee480b0d4f70"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 10:26:05.228913) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 10:26:05.229577) ========= 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.86ZRiH4Pxj/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.f2smWyZ3qw/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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.005196 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 10:26:05.279106) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 10:26:05.279462) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 10:26:05.279837) ========= ========= Total master_lag: 3.51 =========