builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0183 starttime: 1487375048.41 results: success (0) buildid: 20170217134420 builduid: a9848e0d7eb743bf9ec03ca5dd2447b7 revision: 42db8a0b3673b959560b0edc2ac89a44576bddd1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 15:44:08.414105) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 15:44:08.414549) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 15:44:08.414828) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 15:44:08.444401) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 15:44:08.444770) ========= 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.2EXI1MWJv8/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.SFTNkXK7d3/Listeners TMPDIR=/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/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-02-17 15:44:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 27.8M=0s 2017-02-17 15:44:08 (27.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104019 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 15:44:08.576470) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 15:44:08.576788) ========= 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.2EXI1MWJv8/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.SFTNkXK7d3/Listeners TMPDIR=/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/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.026950 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 15:44:08.626081) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-17 15:44:08.629460) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 42db8a0b3673b959560b0edc2ac89a44576bddd1 --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-beta --rev 42db8a0b3673b959560b0edc2ac89a44576bddd1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2EXI1MWJv8/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.SFTNkXK7d3/Listeners TMPDIR=/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/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-02-17 15:44:08,745 truncating revision to first 12 chars 2017-02-17 15:44:08,745 Setting DEBUG logging. 2017-02-17 15:44:08,745 attempt 1/10 2017-02-17 15:44:08,745 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/42db8a0b3673?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-17 15:44:09,774 unpacking tar archive at: mozilla-beta-42db8a0b3673/testing/mozharness/ program finished with exit code 0 elapsedTime=1.301545 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-17 15:44:09.954742) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 15:44:09.955063) ========= script_repo_revision: 42db8a0b3673b959560b0edc2ac89a44576bddd1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 15:44:09.959521) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 15:44:09.959823) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 15:44:09.980181) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-02-17 15:44:09.980575) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2EXI1MWJv8/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.SFTNkXK7d3/Listeners TMPDIR=/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/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 15:44:10 INFO - MultiFileLogger online at 20170217 15:44:10 in /builds/slave/test 15:44:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:44:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:44:10 INFO - {'append_to_log': False, 15:44:10 INFO - 'base_work_dir': '/builds/slave/test', 15:44:10 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:44:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:44:10 INFO - 'branch': 'Mozilla-Beta', 15:44:10 INFO - 'buildbot_json_path': 'buildprops.json', 15:44:10 INFO - 'config_files': ('talos/mac_config.py',), 15:44:10 INFO - 'default_actions': ('clobber', 15:44:10 INFO - 'read-buildbot-config', 15:44:10 INFO - 'download-and-extract', 15:44:10 INFO - 'populate-webroot', 15:44:10 INFO - 'create-virtualenv', 15:44:10 INFO - 'install', 15:44:10 INFO - 'run-tests'), 15:44:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:44:10 INFO - 'download_minidump_stackwalk': True, 15:44:10 INFO - 'download_symbols': 'ondemand', 15:44:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:44:10 INFO - 'tooltool.py': '/tools/tooltool.py', 15:44:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:44:10 INFO - '/tools/misc-python/virtualenv.py')}, 15:44:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:44:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:44:10 INFO - 'installer_path': 'installer.exe', 15:44:10 INFO - 'log_level': 'info', 15:44:10 INFO - 'log_name': 'talos', 15:44:10 INFO - 'log_to_console': True, 15:44:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:44:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:44:10 INFO - 'opt_config_files': (), 15:44:10 INFO - 'pip_index': False, 15:44:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:44:10 INFO - 'cmd': ('bash', 15:44:10 INFO - '-c', 15:44:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:44:10 INFO - 'enabled': True, 15:44:10 INFO - 'halt_on_failure': False, 15:44:10 INFO - 'name': 'check_screen_resolution'},), 15:44:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:44:10 INFO - 'cmd': ('bash', 15:44:10 INFO - '-c', 15:44:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:44:10 INFO - 'enabled': True, 15:44:10 INFO - 'halt_on_failure': False, 15:44:10 INFO - 'name': 'check_screen_resolution'},), 15:44:10 INFO - 'run_cmd_checks_enabled': True, 15:44:10 INFO - 'sps_profile': False, 15:44:10 INFO - 'sps_profile_interval': 0, 15:44:10 INFO - 'suite': 'dromaeojs-e10s', 15:44:10 INFO - 'system_bits': '32', 15:44:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:44:10 INFO - 'title': 't-yosemite-r7-0183', 15:44:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:44:10 INFO - 'use_talos_json': True, 15:44:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:44:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:44:10 INFO - 'work_dir': 'build'} 15:44:10 INFO - [mozharness: 2017-02-17 23:44:10.201738Z] Running clobber step. 15:44:10 INFO - Running pre-action listener: _resource_record_pre_action 15:44:10 INFO - Running main action method: clobber 15:44:10 INFO - rmtree: /builds/slave/test/build 15:44:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:44:12 INFO - Running post-action listener: _resource_record_post_action 15:44:12 INFO - [mozharness: 2017-02-17 23:44:12.709427Z] Finished clobber step (success) 15:44:12 INFO - [mozharness: 2017-02-17 23:44:12.709636Z] Running read-buildbot-config step. 15:44:12 INFO - Running pre-action listener: _resource_record_pre_action 15:44:12 INFO - Running main action method: read_buildbot_config 15:44:12 INFO - Using buildbot properties: 15:44:12 INFO - { 15:44:12 INFO - "project": "", 15:44:12 INFO - "product": "firefox", 15:44:12 INFO - "script_repo_revision": "production", 15:44:12 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 15:44:12 INFO - "repository": "", 15:44:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 15:44:12 INFO - "buildid": "20170217134420", 15:44:12 INFO - "pgo_build": "False", 15:44:12 INFO - "basedir": "/builds/slave/test", 15:44:12 INFO - "buildnumber": 15, 15:44:12 INFO - "slavename": "t-yosemite-r7-0183", 15:44:12 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 15:44:12 INFO - "platform": "yosemite_r7", 15:44:12 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 15:44:12 INFO - "branch": "mozilla-beta", 15:44:12 INFO - "repo_path": "releases/mozilla-beta", 15:44:12 INFO - "revision": "42db8a0b3673b959560b0edc2ac89a44576bddd1", 15:44:12 INFO - "stage_platform": "macosx64", 15:44:12 INFO - "builduid": "a9848e0d7eb743bf9ec03ca5dd2447b7", 15:44:12 INFO - "slavebuilddir": "test" 15:44:12 INFO - } 15:44:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 15:44:12 INFO - Running post-action listener: _resource_record_post_action 15:44:12 INFO - [mozharness: 2017-02-17 23:44:12.715168Z] Finished read-buildbot-config step (success) 15:44:12 INFO - [mozharness: 2017-02-17 23:44:12.715401Z] Running download-and-extract step. 15:44:12 INFO - Running pre-action listener: _resource_record_pre_action 15:44:12 INFO - Running main action method: download_and_extract 15:44:12 INFO - mkdir: /builds/slave/test/build/tests 15:44:12 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')]} 15:44:12 INFO - trying https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 15:44:12 INFO - Downloading https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 15:44:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 15:44:14 INFO - Downloaded 1165 bytes. 15:44:14 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 15:44:14 INFO - Using the following test package requirements: 15:44:14 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 15:44:14 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:44:14 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 15:44:14 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:44:14 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 15:44:14 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:44:14 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:44:14 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 15:44:14 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 15:44:14 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:44:14 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 15:44:14 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:44:14 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 15:44:14 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:44:14 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 15:44:14 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:44:14 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 15:44:14 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 15:44:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 15:44:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 15:44:14 INFO - Fetch https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 15:44:14 INFO - Http code: 200 15:44:14 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 15:44:14 INFO - x-amz-cf-id: gs6KYIws-Tp3FRp8qlHbtETvG4uZ6MkMxfIrcyy8CvM7WdwXPr1H1A== 15:44:14 INFO - x-amz-version-id: RLSkFym.oSf4ZaBFN0y9lxeUnYFLXms4 15:44:14 INFO - Expected file size: 16092968 15:44:14 INFO - Obtained file size: 16092968 15:44:21 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 15:44:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 15:44:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 15:44:21 INFO - Fetch https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 15:44:22 INFO - Http code: 200 15:44:22 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 15:44:22 INFO - x-amz-cf-id: tPdF0uothPSSEBLonoFlsd6YoWn6j5Dm3ud-tnbT4vOGBwHwG5-WDQ== 15:44:22 INFO - x-amz-version-id: RLSkFym.oSf4ZaBFN0y9lxeUnYFLXms4 15:44:22 INFO - Expected file size: 16092968 15:44:22 INFO - Obtained file size: 16092968 15:44:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 15:44:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 15:44:28 INFO - Fetch https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 15:44:30 INFO - Http code: 200 15:44:30 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 15:44:30 INFO - x-amz-cf-id: gvi0FLPKTOKBCOXnb_FeisoF9_Ktl8UHAml2F6evWkiO_vfnLQi1-A== 15:44:30 INFO - x-amz-version-id: 8dd4w6GhfywNMwx8s.oAKa68hWvsGV96 15:44:30 INFO - Expected file size: 14403005 15:44:30 INFO - Obtained file size: 14403005 15:44:30 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')]} 15:44:30 INFO - trying https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.dmg 15:44:30 INFO - Downloading https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 15:44:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 15:44:32 INFO - Downloaded 82136880 bytes. 15:44:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.dmg 15:44:32 INFO - mkdir: /builds/slave/test/properties 15:44:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:44:32 INFO - Writing to file /builds/slave/test/properties/build_url 15:44:32 INFO - Contents: 15:44:32 INFO - build_url:https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.dmg 15:44:33 INFO - Running post-action listener: _resource_record_post_action 15:44:33 INFO - Running post-action listener: set_extra_try_arguments 15:44:33 INFO - [mozharness: 2017-02-17 23:44:33.740606Z] Finished download-and-extract step (success) 15:44:33 INFO - [mozharness: 2017-02-17 23:44:33.740751Z] Running populate-webroot step. 15:44:33 INFO - Running pre-action listener: _resource_record_pre_action 15:44:33 INFO - Running main action method: populate_webroot 15:44:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:44:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:44:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:44:33 INFO - '--tppagecycles', 15:44:33 INFO - '7'], 15:44:33 INFO - 'tests': ['tsvgm']}}, 15:44:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:44:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:44:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:44:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:44:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:44:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:44:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:33 INFO - 'talos_options': ['--disable-e10s'], 15:44:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:44:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:44:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:33 INFO - 'talos_options': ['--disable-e10s'], 15:44:33 INFO - 'tests': ['damp', 'tps']}, 15:44:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:33 INFO - 'tests': ['damp', 'tps']}, 15:44:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:44:33 INFO - 'tests': ['dromaeo_dom']}, 15:44:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:44:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:44:33 INFO - 'tests': ['basic_compositor_video']}, 15:44:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:44:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:44:33 INFO - 'tests': ['a11yr', 15:44:33 INFO - 'ts_paint', 15:44:33 INFO - 'tpaint', 15:44:33 INFO - 'sessionrestore', 15:44:33 INFO - 'sessionrestore_no_auto_restore', 15:44:33 INFO - 'tabpaint']}, 15:44:33 INFO - 'other-e10s': {'tests': ['a11yr', 15:44:33 INFO - 'ts_paint', 15:44:33 INFO - 'tpaint', 15:44:33 INFO - 'sessionrestore', 15:44:33 INFO - 'sessionrestore_no_auto_restore', 15:44:33 INFO - 'tabpaint']}, 15:44:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:44:33 INFO - 'ts_paint', 15:44:33 INFO - 'tpaint', 15:44:33 INFO - 'sessionrestore', 15:44:33 INFO - 'sessionrestore_no_auto_restore', 15:44:33 INFO - 'tabpaint']}, 15:44:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:44:33 INFO - 'ts_paint', 15:44:33 INFO - 'tpaint', 15:44:33 INFO - 'sessionrestore', 15:44:33 INFO - 'sessionrestore_no_auto_restore', 15:44:33 INFO - 'tabpaint']}, 15:44:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:44:33 INFO - 'tests': ['a11yr', 15:44:33 INFO - 'ts_paint', 15:44:33 INFO - 'tpaint', 15:44:33 INFO - 'sessionrestore', 15:44:33 INFO - 'sessionrestore_no_auto_restore', 15:44:33 INFO - 'tabpaint']}, 15:44:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:44:33 INFO - 'tests': ['a11yr', 15:44:33 INFO - 'ts_paint', 15:44:33 INFO - 'tpaint', 15:44:33 INFO - 'sessionrestore', 15:44:33 INFO - 'sessionrestore_no_auto_restore', 15:44:33 INFO - 'tabpaint']}, 15:44:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:44:33 INFO - 'tests': ['tsvgx', 15:44:33 INFO - 'tsvgr_opacity', 15:44:33 INFO - 'tart', 15:44:33 INFO - 'tscrollx', 15:44:33 INFO - 'cart']}, 15:44:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:44:33 INFO - 'tsvgr_opacity', 15:44:33 INFO - 'tart', 15:44:33 INFO - 'tscrollx', 15:44:33 INFO - 'cart']}, 15:44:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:33 INFO - 'talos_options': ['--disable-e10s'], 15:44:33 INFO - 'tests': ['tp5o']}, 15:44:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:33 INFO - 'tests': ['tp5o']}, 15:44:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:33 INFO - 'talos_options': ['--disable-e10s', 15:44:33 INFO - '--xperf_path', 15:44:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:44:33 INFO - 'tests': ['tp5n']}, 15:44:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:33 INFO - 'talos_options': ['--xperf_path', 15:44:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:44:33 INFO - 'tests': ['tp5n']}}, 15:44:33 INFO - 'talos.zip': {'path': '', 15:44:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:44:33 INFO - Running post-action listener: _resource_record_post_action 15:44:33 INFO - [mozharness: 2017-02-17 23:44:33.759472Z] Finished populate-webroot step (success) 15:44:33 INFO - [mozharness: 2017-02-17 23:44:33.759604Z] Running create-virtualenv step. 15:44:33 INFO - Running pre-action listener: _resource_record_pre_action 15:44:33 INFO - Running main action method: create_virtualenv 15:44:33 INFO - Creating virtualenv /builds/slave/test/build/venv 15:44:33 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 15:44:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:44:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:44:33 INFO - Using real prefix '/tools/python27' 15:44:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:44:34 INFO - Installing distribute.............................................................................................................................................................................................done. 15:44:37 INFO - Installing pip.................done. 15:44:37 INFO - Return code: 0 15:44:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:44:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:44:37 INFO - Reading from file tmpfile_stdout 15:44:37 INFO - Output received: 15:44:37 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) 15:44:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:37 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 0x10f036490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9be2c7f640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4ad30>, '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 0x10ecb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee42b20>, '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.2EXI1MWJv8/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.SFTNkXK7d3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/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 15:44:37 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 15:44:37 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 15:44:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EXI1MWJv8/Render', 15:44:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:37 INFO - 'HOME': '/Users/cltbld', 15:44:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:37 INFO - 'LOGNAME': 'cltbld', 15:44:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:37 INFO - 'MOZ_NO_REMOTE': '1', 15:44:37 INFO - 'NO_EM_RESTART': '1', 15:44:37 INFO - 'PAGER': '/bin/cat', 15:44:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:37 INFO - 'PWD': '/builds/slave/test', 15:44:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:37 INFO - 'SHELL': '/bin/bash', 15:44:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFTNkXK7d3/Listeners', 15:44:37 INFO - 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/', 15:44:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:37 INFO - 'USER': 'cltbld', 15:44:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:37 INFO - 'XPC_FLAGS': '0x0', 15:44:37 INFO - 'XPC_SERVICE_NAME': '0', 15:44:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:37 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 15:44:37 INFO - Cleaning up... 15:44:37 INFO - Return code: 0 15:44:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:37 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 0x10f036490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9be2c7f640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4ad30>, '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 0x10ecb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee42b20>, '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.2EXI1MWJv8/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.SFTNkXK7d3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/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 15:44:37 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 15:44:37 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 15:44:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EXI1MWJv8/Render', 15:44:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:37 INFO - 'HOME': '/Users/cltbld', 15:44:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:37 INFO - 'LOGNAME': 'cltbld', 15:44:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:37 INFO - 'MOZ_NO_REMOTE': '1', 15:44:37 INFO - 'NO_EM_RESTART': '1', 15:44:37 INFO - 'PAGER': '/bin/cat', 15:44:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:37 INFO - 'PWD': '/builds/slave/test', 15:44:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:37 INFO - 'SHELL': '/bin/bash', 15:44:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFTNkXK7d3/Listeners', 15:44:37 INFO - 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/', 15:44:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:37 INFO - 'USER': 'cltbld', 15:44:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:37 INFO - 'XPC_FLAGS': '0x0', 15:44:37 INFO - 'XPC_SERVICE_NAME': '0', 15:44:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:38 INFO - Downloading/unpacking psutil>=3.1.1 15:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:44:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:44:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:44:41 INFO - Installing collected packages: psutil 15:44:41 INFO - Running setup.py install for psutil 15:44:41 INFO - building 'psutil._psutil_osx' extension 15:44:41 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 15:44:42 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 15:44:42 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 15:44:42 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 15:44:42 INFO - building 'psutil._psutil_posix' extension 15:44:42 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 15:44:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:44:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:44:42 INFO - ^ 15:44:42 INFO - 1 warning generated. 15:44:42 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 15:44:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:44:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:44:42 INFO - Successfully installed psutil 15:44:42 INFO - Cleaning up... 15:44:42 INFO - Return code: 0 15:44:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:44: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')]} 15:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44: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')]} 15:44:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:42 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 0x10f036490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9be2c7f640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4ad30>, '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 0x10ecb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee42b20>, '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.2EXI1MWJv8/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.SFTNkXK7d3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/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 15:44:42 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 15:44:42 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 15:44:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EXI1MWJv8/Render', 15:44:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:42 INFO - 'HOME': '/Users/cltbld', 15:44:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:42 INFO - 'LOGNAME': 'cltbld', 15:44:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:42 INFO - 'MOZ_NO_REMOTE': '1', 15:44:42 INFO - 'NO_EM_RESTART': '1', 15:44:42 INFO - 'PAGER': '/bin/cat', 15:44:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:42 INFO - 'PWD': '/builds/slave/test', 15:44:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:42 INFO - 'SHELL': '/bin/bash', 15:44:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFTNkXK7d3/Listeners', 15:44:42 INFO - 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/', 15:44:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:42 INFO - 'USER': 'cltbld', 15:44:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:42 INFO - 'XPC_FLAGS': '0x0', 15:44:42 INFO - 'XPC_SERVICE_NAME': '0', 15:44:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:44:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:44:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:44:45 INFO - Installing collected packages: mozsystemmonitor 15:44:45 INFO - Running setup.py install for mozsystemmonitor 15:44:45 INFO - Successfully installed mozsystemmonitor 15:44:45 INFO - Cleaning up... 15:44:46 INFO - Return code: 0 15:44:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:44:46 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')]} 15:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:46 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')]} 15:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:46 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 0x10f036490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9be2c7f640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4ad30>, '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 0x10ecb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee42b20>, '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.2EXI1MWJv8/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.SFTNkXK7d3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/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 15:44:46 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 15:44:46 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 15:44:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EXI1MWJv8/Render', 15:44:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:46 INFO - 'HOME': '/Users/cltbld', 15:44:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:46 INFO - 'LOGNAME': 'cltbld', 15:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:46 INFO - 'MOZ_NO_REMOTE': '1', 15:44:46 INFO - 'NO_EM_RESTART': '1', 15:44:46 INFO - 'PAGER': '/bin/cat', 15:44:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:46 INFO - 'PWD': '/builds/slave/test', 15:44:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:46 INFO - 'SHELL': '/bin/bash', 15:44:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFTNkXK7d3/Listeners', 15:44:46 INFO - 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/', 15:44:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:46 INFO - 'USER': 'cltbld', 15:44:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:46 INFO - 'XPC_FLAGS': '0x0', 15:44:46 INFO - 'XPC_SERVICE_NAME': '0', 15:44:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:46 INFO - Downloading/unpacking jsonschema==2.5.1 15:44:46 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 15:44:46 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 15:44:46 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 15:44:46 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 15:44:46 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 15:44:46 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 15:44:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:44:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:49 INFO - Downloading functools32-3.2.3-2.tar.gz 15:44:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:44:49 INFO - warning: no files found matching '*.txt' 15:44:49 INFO - no previously-included directories found matching 'build' 15:44:49 INFO - no previously-included directories found matching 'dist' 15:44:49 INFO - no previously-included directories found matching '.git*' 15:44:49 INFO - Installing collected packages: jsonschema, functools32 15:44:49 INFO - Running setup.py install for functools32 15:44:49 INFO - warning: no files found matching '*.txt' 15:44:49 INFO - no previously-included directories found matching 'build' 15:44:49 INFO - no previously-included directories found matching 'dist' 15:44:49 INFO - no previously-included directories found matching '.git*' 15:44:49 INFO - Successfully installed jsonschema functools32 15:44:49 INFO - Cleaning up... 15:44:49 INFO - Return code: 0 15:44:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:44: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')]} 15:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44: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')]} 15:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44: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', '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 0x10f036490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9be2c7f640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4ad30>, '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 0x10ecb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee42b20>, '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.2EXI1MWJv8/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.SFTNkXK7d3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/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 15:44: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:44: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 functools32==3.2.3-2 15:44:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EXI1MWJv8/Render', 15:44:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:49 INFO - 'HOME': '/Users/cltbld', 15:44:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:49 INFO - 'LOGNAME': 'cltbld', 15:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:49 INFO - 'MOZ_NO_REMOTE': '1', 15:44:49 INFO - 'NO_EM_RESTART': '1', 15:44:49 INFO - 'PAGER': '/bin/cat', 15:44:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:49 INFO - 'PWD': '/builds/slave/test', 15:44:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:49 INFO - 'SHELL': '/bin/bash', 15:44:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFTNkXK7d3/Listeners', 15:44:49 INFO - 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/', 15:44:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:49 INFO - 'USER': 'cltbld', 15:44:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:49 INFO - 'XPC_FLAGS': '0x0', 15:44:49 INFO - 'XPC_SERVICE_NAME': '0', 15:44:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:44:49 INFO - Cleaning up... 15:44:49 INFO - Return code: 0 15:44:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:44: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')]} 15:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44: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')]} 15:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44: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', '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 0x10f036490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9be2c7f640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4ad30>, '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 0x10ecb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee42b20>, '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.2EXI1MWJv8/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.SFTNkXK7d3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/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 15:44: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:44: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 blobuploader==1.2.4 15:44:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EXI1MWJv8/Render', 15:44:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:49 INFO - 'HOME': '/Users/cltbld', 15:44:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:49 INFO - 'LOGNAME': 'cltbld', 15:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:49 INFO - 'MOZ_NO_REMOTE': '1', 15:44:49 INFO - 'NO_EM_RESTART': '1', 15:44:49 INFO - 'PAGER': '/bin/cat', 15:44:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:49 INFO - 'PWD': '/builds/slave/test', 15:44:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:49 INFO - 'SHELL': '/bin/bash', 15:44:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFTNkXK7d3/Listeners', 15:44:49 INFO - 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/', 15:44:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:49 INFO - 'USER': 'cltbld', 15:44:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:49 INFO - 'XPC_FLAGS': '0x0', 15:44:49 INFO - 'XPC_SERVICE_NAME': '0', 15:44:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:50 INFO - Downloading/unpacking blobuploader==1.2.4 15:44:50 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 15:44:50 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 15:44:50 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 15:44:50 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 15:44:50 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 15:44:50 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 15:44:52 INFO - Downloading blobuploader-1.2.4.tar.gz 15:44:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:44:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:44:52 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 15:44:52 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 15:44:52 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 15:44:52 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 15:44:52 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 15:44:52 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 15:44:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:44:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:53 INFO - Downloading docopt-0.6.1.tar.gz 15:44:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:44:54 INFO - Installing collected packages: blobuploader, requests, docopt 15:44:54 INFO - Running setup.py install for blobuploader 15:44:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:44:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:44:54 INFO - Running setup.py install for requests 15:44:54 INFO - Running setup.py install for docopt 15:44:54 INFO - Successfully installed blobuploader requests docopt 15:44:54 INFO - Cleaning up... 15:44:54 INFO - Return code: 0 15:44:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:54 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 0x10f036490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9be2c7f640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4ad30>, '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 0x10ecb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee42b20>, '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.2EXI1MWJv8/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.SFTNkXK7d3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/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 15:44:54 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 15:44:54 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 15:44:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EXI1MWJv8/Render', 15:44:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:54 INFO - 'HOME': '/Users/cltbld', 15:44:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:54 INFO - 'LOGNAME': 'cltbld', 15:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:54 INFO - 'MOZ_NO_REMOTE': '1', 15:44:54 INFO - 'NO_EM_RESTART': '1', 15:44:54 INFO - 'PAGER': '/bin/cat', 15:44:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:54 INFO - 'PWD': '/builds/slave/test', 15:44:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:54 INFO - 'SHELL': '/bin/bash', 15:44:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFTNkXK7d3/Listeners', 15:44:54 INFO - 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/', 15:44:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:54 INFO - 'USER': 'cltbld', 15:44:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:54 INFO - 'XPC_FLAGS': '0x0', 15:44:54 INFO - 'XPC_SERVICE_NAME': '0', 15:44:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:44:55 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-cW8jS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:44:55 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-6skwXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:44:55 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-QOqrQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:44:55 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-cu49N4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:44:55 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-WjCbnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:44:55 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-LEWfy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:44:55 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-YunjTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:44:55 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-1SHBWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:44:56 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-uuaWlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:44:56 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-bfkI7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:44:56 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-iR21tD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:44:56 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-fxr4G9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:44:56 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-6NudBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:44:56 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-gTU2oj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:44:56 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-d3_ybv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:44:56 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-udbR_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:44:56 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-zc4Wil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:44:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:44:56 INFO - Running setup.py install for manifestparser 15:44:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:44:57 INFO - Running setup.py install for mozcrash 15:44:57 INFO - Running setup.py install for mozdebug 15:44:57 INFO - Running setup.py install for mozdevice 15:44:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:44:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:44:57 INFO - Running setup.py install for mozfile 15:44:57 INFO - Running setup.py install for mozhttpd 15:44:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:44:57 INFO - Running setup.py install for mozinfo 15:44:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:44:57 INFO - Running setup.py install for mozInstall 15:44:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:44:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:44:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:44:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:44:58 INFO - Running setup.py install for mozleak 15:44:58 INFO - Running setup.py install for mozlog 15:44:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:44:58 INFO - Running setup.py install for moznetwork 15:44:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:44:58 INFO - Running setup.py install for mozprocess 15:44:58 INFO - Running setup.py install for mozprofile 15:44:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:44:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:44:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:44:58 INFO - Running setup.py install for mozrunner 15:44:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:44:59 INFO - Running setup.py install for mozscreenshot 15:44:59 INFO - Running setup.py install for moztest 15:44:59 INFO - Running setup.py install for mozversion 15:44:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:44:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:44:59 INFO - Cleaning up... 15:44:59 INFO - Return code: 0 15:44:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:59 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 0x10f036490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9be2c7f640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4ad30>, '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 0x10ecb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee42b20>, '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.2EXI1MWJv8/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.SFTNkXK7d3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/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 15:44:59 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 15:44:59 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 15:44:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EXI1MWJv8/Render', 15:44:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:59 INFO - 'HOME': '/Users/cltbld', 15:44:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:59 INFO - 'LOGNAME': 'cltbld', 15:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:59 INFO - 'MOZ_NO_REMOTE': '1', 15:44:59 INFO - 'NO_EM_RESTART': '1', 15:44:59 INFO - 'PAGER': '/bin/cat', 15:44:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:59 INFO - 'PWD': '/builds/slave/test', 15:44:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:59 INFO - 'SHELL': '/bin/bash', 15:44:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFTNkXK7d3/Listeners', 15:44:59 INFO - 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/', 15:44:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:59 INFO - 'USER': 'cltbld', 15:44:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:59 INFO - 'XPC_FLAGS': '0x0', 15:44:59 INFO - 'XPC_SERVICE_NAME': '0', 15:44:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:44:59 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-UFWagy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:44:59 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)) 15:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:44:59 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-tb7IsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:45:00 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)) 15:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:45:00 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-s0Ghwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:45:00 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)) 15:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:45:00 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-LS2gwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:45:00 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)) 15:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:45:00 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-XYbtrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:45:00 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)) 15:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:45:00 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-N_84MY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:45:00 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)) 15:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:45:00 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-wlif7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:45:00 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)) 15:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:45:00 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-0Sx54j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:45:00 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)) 15:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:45:00 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-RJHnEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:45:00 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)) 15:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:45:00 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-xSO8Zj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:45:00 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)) 15:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:45:00 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-ihCty6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:45:00 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)) 15:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:45:00 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-1gyUJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:45:01 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)) 15:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:45:01 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-jJRDbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:45:01 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)) 15:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:45:01 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-2jmsT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:45:01 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)) 15:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:01 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-sUQUP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:01 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)) 15:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:45:01 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-2oVL_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:45:01 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)) 15:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:45:01 INFO - Running setup.py (path:/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/pip-qkVKhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:45:01 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)) 15:45:01 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)) 15:45:01 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)) 15:45:01 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)) 15:45:01 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)) 15:45:01 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)) 15:45:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:45:01 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 15:45:01 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 15:45:01 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 15:45:01 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 15:45:01 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 15:45:01 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 15:45:04 INFO - Downloading blessings-1.6.tar.gz 15:45:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:45:04 INFO - Installing collected packages: blessings 15:45:04 INFO - Running setup.py install for blessings 15:45:04 INFO - Successfully installed blessings 15:45:04 INFO - Cleaning up... 15:45:04 INFO - Return code: 0 15:45:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:45:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:45:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:45:04 INFO - Reading from file tmpfile_stdout 15:45:04 INFO - Current package versions: 15:45:04 INFO - blessings == 1.6 15:45:04 INFO - blobuploader == 1.2.4 15:45:04 INFO - docopt == 0.6.1 15:45:04 INFO - functools32 == 3.2.3-2 15:45:04 INFO - jsonschema == 2.5.1 15:45:04 INFO - manifestparser == 1.1 15:45:04 INFO - mozInstall == 1.12 15:45:04 INFO - mozcrash == 1.0 15:45:04 INFO - mozdebug == 0.1 15:45:04 INFO - mozdevice == 0.48 15:45:04 INFO - mozfile == 1.2 15:45:04 INFO - mozhttpd == 0.7 15:45:04 INFO - mozinfo == 0.9 15:45:04 INFO - mozleak == 0.1 15:45:04 INFO - mozlog == 3.4 15:45:04 INFO - moznetwork == 0.27 15:45:04 INFO - mozprocess == 0.23 15:45:04 INFO - mozprofile == 0.28 15:45:04 INFO - mozrunner == 6.13 15:45:04 INFO - mozscreenshot == 0.1 15:45:04 INFO - mozsystemmonitor == 0.3 15:45:04 INFO - moztest == 0.8 15:45:04 INFO - mozversion == 1.4 15:45:04 INFO - psutil == 3.1.1 15:45:04 INFO - requests == 1.2.3 15:45:04 INFO - wsgiref == 0.1.2 15:45:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:45:04 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')]} 15:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:05 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 0x10f036490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9be2c7f640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4ad30>, '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 0x10ecb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee42b20>, '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.2EXI1MWJv8/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.SFTNkXK7d3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/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 15:45:05 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 15:45:05 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 15:45:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EXI1MWJv8/Render', 15:45:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:05 INFO - 'HOME': '/Users/cltbld', 15:45:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:05 INFO - 'LOGNAME': 'cltbld', 15:45:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:05 INFO - 'MOZ_NO_REMOTE': '1', 15:45:05 INFO - 'NO_EM_RESTART': '1', 15:45:05 INFO - 'PAGER': '/bin/cat', 15:45:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:05 INFO - 'PWD': '/builds/slave/test', 15:45:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:05 INFO - 'SHELL': '/bin/bash', 15:45:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFTNkXK7d3/Listeners', 15:45:05 INFO - 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/', 15:45:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:05 INFO - 'USER': 'cltbld', 15:45:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:05 INFO - 'XPC_FLAGS': '0x0', 15:45:05 INFO - 'XPC_SERVICE_NAME': '0', 15:45:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:05 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)) 15:45:05 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)) 15:45:05 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)) 15:45:05 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)) 15:45:05 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)) 15:45:05 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)) 15:45:05 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)) 15:45:05 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)) 15:45:05 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)) 15:45:05 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)) 15:45:05 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)) 15:45:05 INFO - Cleaning up... 15:45:05 INFO - Return code: 0 15:45:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:06 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 0x10f036490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9be2c7f640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4ad30>, '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 0x10ecb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee42b20>, '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.2EXI1MWJv8/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.SFTNkXK7d3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/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 15:45:06 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 15:45:06 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 15:45:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EXI1MWJv8/Render', 15:45:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:06 INFO - 'HOME': '/Users/cltbld', 15:45:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:06 INFO - 'LOGNAME': 'cltbld', 15:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:06 INFO - 'MOZ_NO_REMOTE': '1', 15:45:06 INFO - 'NO_EM_RESTART': '1', 15:45:06 INFO - 'PAGER': '/bin/cat', 15:45:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:06 INFO - 'PWD': '/builds/slave/test', 15:45:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:06 INFO - 'SHELL': '/bin/bash', 15:45:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFTNkXK7d3/Listeners', 15:45:06 INFO - 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/', 15:45:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:06 INFO - 'USER': 'cltbld', 15:45:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:06 INFO - 'XPC_FLAGS': '0x0', 15:45:06 INFO - 'XPC_SERVICE_NAME': '0', 15:45:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:45:06 INFO - Cleaning up... 15:45:06 INFO - Return code: 0 15:45:06 INFO - Running post-action listener: _resource_record_post_action 15:45:06 INFO - Running post-action listener: _start_resource_monitoring 15:45:06 INFO - Starting resource monitoring. 15:45:06 INFO - [mozharness: 2017-02-17 23:45:06.611431Z] Finished create-virtualenv step (success) 15:45:06 INFO - [mozharness: 2017-02-17 23:45:06.612390Z] Running install step. 15:45:06 INFO - Running pre-action listener: _resource_record_pre_action 15:45:06 INFO - Running main action method: install 15:45:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:45:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:45:06 INFO - Reading from file tmpfile_stdout 15:45:06 INFO - Detecting whether we're running mozinstall >=1.0... 15:45:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:45:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:45:06 INFO - Reading from file tmpfile_stdout 15:45:06 INFO - Output received: 15:45:06 INFO - Usage: mozinstall [options] installer 15:45:06 INFO - Options: 15:45:06 INFO - -h, --help show this help message and exit 15:45:06 INFO - -d DEST, --destination=DEST 15:45:06 INFO - Directory to install application into. [default: 15:45:06 INFO - "/builds/slave/test"] 15:45:06 INFO - --app=APP Application being installed. [default: firefox] 15:45:06 INFO - mkdir: /builds/slave/test/build/application 15:45:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:45:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 15:45:28 INFO - Reading from file tmpfile_stdout 15:45:28 INFO - Output received: 15:45:28 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:45:28 INFO - Running post-action listener: _resource_record_post_action 15:45:28 INFO - [mozharness: 2017-02-17 23:45:28.278288Z] Finished install step (success) 15:45:28 INFO - [mozharness: 2017-02-17 23:45:28.278414Z] Running run-tests step. 15:45:28 INFO - Running pre-action listener: _resource_record_pre_action 15:45:28 INFO - Running main action method: run_tests 15:45:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:45:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:45:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:45:28 INFO - 2017-02-17 15:45:28.291 screenresolution[1626:7244] starting screenresolution argv=screenresolution get 15:45:28 INFO - 2017-02-17 15:45:28.293 screenresolution[1626:7244] Display 0: 1600x1200x32@60 15:45:28 INFO - 2017-02-17 15:45:28.298 screenresolution[1627:7247] starting screenresolution argv=screenresolution list 15:45:28 INFO - Available Modes on Display 0 15:45:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:45:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:45:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:45:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:45:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:45:28 INFO - Intel Iris: 15:45:28 INFO - Chipset Model: Intel Iris 15:45:28 INFO - Type: GPU 15:45:28 INFO - Bus: Built-In 15:45:28 INFO - VRAM (Dynamic, Max): 1536 MB 15:45:28 INFO - Vendor: Intel (0x8086) 15:45:28 INFO - Device ID: 0x0a2e 15:45:28 INFO - Revision ID: 0x0009 15:45:28 INFO - Displays: 15:45:28 INFO - Display: 15:45:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:45:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:45:28 INFO - Display Serial Number: 1600x1200 60 15:45:28 INFO - Main Display: Yes 15:45:28 INFO - Mirror: Off 15:45:28 INFO - Online: Yes 15:45:28 INFO - Rotation: Supported 15:45:28 INFO - Return code: 0 15:45:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:45:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:45:28 INFO - Python 2.7.3 15:45:28 INFO - Return code: 0 15:45:28 INFO - grabbing minidump binary from tooltool 15:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:28 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 0x10ee4ad30>, '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 0x10ecb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee42b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:45:28 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 15:45:28 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 15:45:28 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 15:45:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:45:28 INFO - Return code: 0 15:45:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:45:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:45:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:45:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:45:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:45:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0183', '--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 15:45:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0183 --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 15:45:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EXI1MWJv8/Render', 15:45:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:28 INFO - 'HOME': '/Users/cltbld', 15:45:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:28 INFO - 'LOGNAME': 'cltbld', 15:45:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:45:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:45:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:28 INFO - 'MOZ_NO_REMOTE': '1', 15:45:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:45:28 INFO - 'NO_EM_RESTART': '1', 15:45:28 INFO - 'PAGER': '/bin/cat', 15:45:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:28 INFO - 'PWD': '/builds/slave/test', 15:45:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:45:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:28 INFO - 'SHELL': '/bin/bash', 15:45:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFTNkXK7d3/Listeners', 15:45:28 INFO - 'TMPDIR': '/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/', 15:45:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:28 INFO - 'USER': 'cltbld', 15:45:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:28 INFO - 'XPC_FLAGS': '0x0', 15:45:28 INFO - 'XPC_SERVICE_NAME': '0', 15:45:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0183', '--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 15:45:28 INFO - mozversion application_buildid: 20170217134420 15:45:28 INFO - mozversion application_changeset: 42db8a0b3673b959560b0edc2ac89a44576bddd1 15:45:28 INFO - mozversion application_display_name: Firefox 15:45:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:45:28 INFO - mozversion application_name: Firefox 15:45:28 INFO - mozversion application_remotingname: firefox 15:45:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:45:28 INFO - mozversion application_vendor: Mozilla 15:45:28 INFO - mozversion application_version: 52.0 15:45:28 INFO - mozversion platform_buildid: 20170217134420 15:45:28 INFO - mozversion platform_changeset: 42db8a0b3673b959560b0edc2ac89a44576bddd1 15:45:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:45:28 INFO - mozversion platform_version: 52.0 15:45:28 INFO - using testdate: 1487375128 15:45:28 INFO - actual date: 1487375128 15:45:28 INFO - starting webserver on 'localhost:49239' 15:45:28 INFO - SUITE-START | Running 2 tests 15:45:28 INFO - TEST-START | dromaeo_css 15:45:28 INFO - operating with platform_type : mac_ 15:45:28 INFO - Initialising browser for dromaeo_css test... 15:45:28 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/tmpHSkcdi/profile http://localhost:49239/getInfo.html) 15:45:31 INFO - PROCESS | 1637 | 2017-02-17 15:45:31.866 plugin-container[1640:7422] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 15:45:31 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:31 INFO - PROCESS | 1637 | 2017-02-17 15:45:31.867 plugin-container[1640:7422] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 15:45:31 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:31 INFO - PROCESS | 1637 | 2017-02-17 15:45:31.867 plugin-container[1640:7422] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:45:31 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 15:45:31 INFO - PROCESS | 1637 | colorDepth:24 15:45:31 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 15:45:31 INFO - PROCESS | 1637 | __metrics 15:45:32 ERROR - PROCESS | 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 15:45:32 INFO - TEST-INFO | 1637: exit 0 15:45:32 INFO - Browser initialized. 15:45:32 INFO - Running cycle 1/1 for dromaeo_css test... 15:45:32 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/tmpHSkcdi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:45:35 INFO - PROCESS | 1641 | 2017-02-17 15:45:35.469 plugin-container[1642:7557] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833b, name = 'com.apple.tsm.portname' 15:45:35 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:35 INFO - PROCESS | 1641 | 2017-02-17 15:45:35.469 plugin-container[1642:7557] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 15:45:35 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:35 INFO - PROCESS | 1641 | 2017-02-17 15:45:35.470 plugin-container[1642:7557] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:45:45 INFO - PROCESS | 1641 | RSS: Main: 177844224 15:45:45 INFO - PROCESS | 1641 | 15:48:47 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49239/tests/dromaeo/cssquery-ext.html) 15:48:48 INFO - PROCESS | 1641 | RSS: Main: 183189504 15:48:48 INFO - PROCESS | 1641 | 15:51:50 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-ext.html (next: http://localhost:49239/tests/dromaeo/cssquery-jquery.html) 15:51:51 INFO - PROCESS | 1641 | RSS: Main: 193880064 15:51:51 INFO - PROCESS | 1641 | 15:54:53 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49239/tests/dromaeo/cssquery-mootools.html) 15:54:53 INFO - PROCESS | 1641 | RSS: Main: 195686400 15:54:53 INFO - PROCESS | 1641 | 15:57:55 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49239/tests/dromaeo/cssquery-prototype.html) 15:57:56 INFO - PROCESS | 1641 | RSS: Main: 198791168 15:57:56 INFO - PROCESS | 1641 | 16:00:59 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49239/tests/dromaeo/cssquery-yui.html) 16:00:59 INFO - PROCESS | 1641 | RSS: Main: 200224768 16:00:59 INFO - PROCESS | 1641 | 16:04:02 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-yui.html (next: http://localhost:49239/tests/dromaeo/cssquery-dojo.html) 16:04:02 INFO - PROCESS | 1641 | RSS: Main: 200810496 16:04:02 INFO - PROCESS | 1641 | 16:04:02 INFO - PROCESS | 1641 | __start_tp_report 16:04:02 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 16:04:02 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 16:04:02 INFO - PROCESS | 1641 | |i|pagename|runs| 16:04:02 INFO - PROCESS | 1641 | |0;dojo.html;3514.420120680088;3548.2016546277073;3568.80371579563;3583.6774690277884;3591.407417776068;5100.642954993152;5365.436629153941;5819.9127013094785;5922.555808314378;5988.7604495820115;4832.540908613676;5860.267466566679;5932.11018347247;5960.284765828107;5967.641941483504;5102.591792656597;5251.763670634821;5290.867728306804;5294.073537131006;5353.705546194958;5331.6534425262425;5355.6786592804365;5365.973170134144;5391.056565101111;5391.379991301012;5189.662671926331;5210.6613070150315;5338.599605029638;5340.279062326595;5363.436839131893;3866.420036994459;5283.8150664726745;5294.232336311214;5296.655717378397;5326.014687282865;5186.611004174702;5224.712640804754;5238.607104467142;5290.55030322423;5312.568371223418;5717.485426311652;5896.911546326808;5933.8813223735715;5960.642361458326;6047.969760151215;6222.097795819581;6230.750769969196;6262.968685156545;6266.185395898549;6286.182796236506;6289.87420251597;6319.083732858756;6323.620582765049;6328.050792381134;6341.936580634227;5860.941390586125;5923.763049478016;5954.7915822946425;5970.462658360769;5983.252093488314;6297.4017468340435;6348.396902294275;6357.427831495188;6375.968120159462;6422.550421470452;6281.811545653638;6296.65368404733;6307.463865571478;6335.936640633627;6344.460720838688;6181.690915454209;6232.376762323822;6278.843028924222;6308.053791931247;6339.904901426482;5209.583233341399;6323.9051414228825;6333.524985626004;6340.936590634127;6346.01636746305;6280.591761535486;6285.214348206474;6294.402031806971;6303;6326.936730632727;4928.359313289249;4992.525710057539;5008.549230569266;5072.365954255718;5114.999999999925;4471.619912307425;4478;4478.910421791546;4516.1419330327135;4546.840860569864;5838.241028666331;5971.820845374646;6040.939590604125;6061.818145455643;6072;5863.736131874076;6233.906491402633;6314.400131987546;6325.683715814283;6350.364963503613;4865.148598995162;5001.4998500149695;5932.228810254639;5951.642901425928;6069.3627169147485;5530.972345138329;5531.723413829292;5547.722613869371;5551.777928882881;5559.166125081189;6378;6458.61248325102;6498.772542960974;6508.056331831764;6518;4438.378626992288;6141.670122563492;6234.501239900887;6267;6341.112244285711;6478.838029049123;6481.254655714653;6491.05879647439;6505.674716264073;6512.413882750575;6283.340249273943;6299.401556852092;6328.398802113792;6331.240251169889;6331.271903730945;5119.0529751996;6050.364711705208;6092.878142437038;6280.340564240689;6285.779997700059;6212.409821066992;6230.439260466398;6258.155149054819;6272.435480806749;6297.937020629735;6247.562670613013;6248.562600617913;6252.749890004531;6283.874322513616;6295.276043254901;5825.417458254141;6044.969775151096;6066.939330606814;6070.696465176812;6164.506839452743;6171.043488259327;6229.159063526366;6282.748690052346;6286.371362863677;6287.905681414691;6085.2393450818645;6107.572469927062;6146.969265153645;6148.938510615016;6166.537509686881 16:04:02 INFO - PROCESS | 1641 | |1;ext.html;526.2921370756333;537.210300857739;556.4102051825066;573.3349314794839;591.8342863998082;37593.436098458544;39920.80039599805;39926.8003659982;39952.80023599882;40003.59996400039;40587.59412405875;41552.792236038775;42339;42370;42461;9430.43417394955;9546.761330966747;9595.616175353012;9672.274579406572;9706.417614943091;9582.425054496753;9600.99189585094;9679.75800604987;9695.757606059835;9706.320557560974;9547.665831695893;9583.952080239624;9623.663171788989;9683.370580912218;9721.465319407465;9009.054049324857;9026.60087686413;9556.856647150298;9586.568604412783;9675.70972870815;9539.379940303927;9547.809043819085;9593.088656577615;9642.228621710246;9673;39113.60886391156;39218;39449.211015779525;39570.0107497312;39738.20523589541;3414.709749671281;3512.4380099184264;3518.05012646584;3706.9073273168115;3974.966508707757;28513.429731405256;29476.41047179066;29577.55633665497;29658.70341296581;30323.090307290815;3186.1078897908624;3246.756493262991;3404.5233667286598;3415;3418.5214070030215;441026;515413;520728;520985.7901420975;522249;48878.51121488811;60204.39795602075;60489;60762.088568671505;60807.78384432287;61860.76278474405;63443;63615.045774313425;63694;63712;16801.15994200285;17069.231884565223;17078.914605426893;17137.914310428616;17194;11329.320240785579;12920.741585168244;13106.279154646585;13136.27750473714;13204.669883252996;13929.373178207004;15296.541103766906;15950.60123496922;16425.83574164243;16473.258703358377;7307.926920730831;7318.890216646754;7351.68421947085;7378.077740282465;7457.664405101675;868.5006121480175;1029.7322696098918;1071.0735214039798;1072.0619457974271;1078.7680648660576;30391.69608303933;30564.847175763978;30761.69238307633;30782.076537703906;30837.383252335276;1013.4426065663855;1025.144004756037;1025.789713108826;1080.0711388206137;1097.2922465010183;15660.843391566166;15721.978071425321;15728.842711572737;15772.369105235663;16075.758863617055;650272;664798;665825;667581.6620916864;668768;59328;59823;59919.10121348183;60068.399316007155;60254;1367.384676895401;1399.4751968011994;1413.250976982201;1418.9858101418854;1424.8860091192887;29604.407911842074;30455.390892182477;30592.896420266894;30713;30905.69094309028;50806;50822;50963.49036509587;51447.71380715512;51680.224796628834;505239;507569;507713;507719;632402;27006.594901076107;27293.454130916874;27381.17856464309;27975;28047;45916;46393.30410043919;47499.525004749514;47862.04275914392;48112.518874810805;38546.229075418894;38965.610343896195;39081.80459097686;40319;40453.79773101233;38634.6136538631;38775;38938;39859.60140398559;39945.40081898716;38008.23983520369;38522.0369490765;38627.22745545017;38795.03012424712;38928.805355974175;32562.185945351557;34644.653553464144;34907;35133.472997904544;35318;25809.354766130997;26024.739752602232;26084.478310434064;26341;27116.1865144046 16:04:02 INFO - PROCESS | 1641 | |2;jquery.html;381.69083042735406;387.35698740091453;389.3186922884952;398.4561074133807;411.9093799364141;16187.83812161878;19702.704459433102;19803.81177129373;21001.789982100174;21027.894860525696;20525.692114618294;20771.584568308663;21107.944602769876;21172.470688232763;21419.464513387175;5855.648661080349;6015.578909476339;6110.419510146551;6141.416565426277;6188.504919606421;5849.444302791228;6021.488173505258;6066.787662431816;6095.390460953891;6130.386961303879;5978.790742324019;6071.726772295257;6074.514038876901;6085.421884920926;6092.786752463665;5784.971075144618;5919.260092488439;6064.9090263646085;6100.084987251903;6157.907631385488;5860.853478663025;5974.880502389971;5982.371850955674;6018.337982821886;6047.092936059582;13172.60482185536;14355.641108972255;21690.48166628321;25668;25790.74209257921;2236.8769717665523;2284.623037198877;2366.4557151854997;2367.3016460144127;2905.186547766629;10114.393136411838;10393;10703.625373111903;13089.803652945213;14727.631809204748;1628.5521481592539;1890;1899.08843754997;1907.3610340535938;1909.8854068755918;125416;127583;130002.39995200043;132201;132811.99999999904;9393.76515587102;9574.712758617252;10061.591377207196;11329.923657252548;11851.111166662535;11910.761784764256;12591.748165036832;14828.70342593142;15178.31697573612;15217.6195595111;7724.536527808349;8119.066307374727;8230.547319897463;8460.450070745504;8475.915240847513;7982.560959147303;8333.708320208885;8503.65985360591;8637.870431943526;8662.751468199425;3495.685388315064;3847.0959324558708;4030.0327921299263;4049.655779258737;4075.144219713893;1955.4231501706747;1987.7117817916321;1992.4221975626906;1995.9301424450075;2010.497375656086;829.742779738283;916.8081494057699;939.276756897199;973.7146966004947;979.7844474215661;17413.91293043527;20702.58594828095;21586.676199857015;21608.675869861963;21724.23965161212;926.9443833370019;955.8805149356331;966.970990870275;1003.3678782367062;1003.3929472669076;9172;10534.315269507457;10600.575976960989;11229.213955023233;11351.318920864609;626531.8673406604;645435;648612;655608;658389;127664.36167819287;139816;140364;140704;141133;1037.242812746684;1043.3844032021143;1059.904608585231;1061.6708820265742;1063.9734006649896;19072.523186920436;20190.798092018893;20325.288614898407;20382.28661996823;20433.59132817365;46667.29999050085;52729.20906186487;54250.45749542454;54768;57894;76840.2315976833;81008.59495702719;81210.59394702989;81616;83346;7247.8550428990075;7413.2957369049855;7509.9249007509225;7662.655180517002;7753.147153812964;12676.809847852106;12904.612861614167;12911;12925.741485170056;13233.801492977806;11043.447827608748;11146.164037697366;11271.605493807678;11314.886851131383;11490.712732181428;11235.101191904792;11311.943440282746;11349.035331996873;11414.029807466459;11586.594469193538;8919.866202006846;11094.5562177512;11309.547618095185;11429.599964001553;11894.34581098048;7543.47195696296;7572.39420846342;7735.18780528059;7775;7907.130215676386;10783.245172837826;11020.669379918294;11246.493907774005;11356.659300221007;11510.53957841677 16:04:02 INFO - PROCESS | 1641 | |3;mootools.html;1332.7467781121586;1429.671175629605;1644.2107788261635;1737.8609711223103;1833.5324492254474;7597.772066838003;7857.21427857214;8061.234182752643;8536.573171341432;8549.57252137393;7084.397826184768;7972.9202707973045;8260.752177434671;8277.54473503957;8409;2365.113082594027;2374.988125059372;2383.976160238393;2388.9880550597222;2394.1141777542334;2387.9761202387926;2402.2192787344097;2414.360194548446;2435.2450740270483;2443.6945381827272;2334.6498025296255;2352.5177338645535;2371.7983971362455;2373.8694371809543;2402.4834660547976;2363.83453158279;2368.62102063669;2368.798652114572;2375.049980008002;2384.3204686664303;2378.8334816562847;2379.3575734551746;2385.666006759055;2390.6892104026538;2408.1089996701335;8342;8359.247667709875;8434.746957591282;8451.070382257947;8465.915340846574;4976.004799040206;5086.720230387365;5238.266642670029;5251.291075704808;5253.238280449314;3376.544166537535;3476.721862251014;3528.8412021459094;3573.535440392758;3595.586507551639;1077.7898309829586;1091.3615534912085;1100.9614663486739;1131.3947038334452;1135.256407053373;10948.835767463494;10950;10957;10958.890411095867;10979.670609881636;4460.687751857404;4657.813687452464;4745.644076694192;4778.04439112179;4781.139394908881;9526.571304291323;9527.333086684004;9528.714138575853;9564.13922746956;9583.281253905985;5205.713685747244;5221.738913054332;5230.34620672416;5236.214567814858;5250.291210686585;4324.654027677778;4333.349997500337;4747;4800.35195248637;4809.326694262806;3521.8201902362484;3605.5673319201337;3788.7158463115375;3954.4859168307935;4053.310937140693;1242.6520574239228;1294.1264646363668;1296.5786119511197;1298.104308027458;1304.9478020879249;896.2964073202505;921.4517362169498;926.8285367207088;934.1219253901311;950.9144177024101;5710.395006262065;5734.139879018097;5967.731452084667;5993.340732519419;6025.728842202111;436.91698577270853;441.042936827086;445.7392425431094;452.558755213664;455.58996902787754;3944.3294639911283;3946.4474973503734;3951.8221680024467;4063.329550624173;4063.4108054332546;9788.216942644714;10212.693619191436;10212.795744085077;10218.437985910698;10237.539310731036;10077.344972576759;10152.593896244067;10175.44035078063;10189.490525473697;10237.283390162765;7600.315971562586;7743.5353878767455;7920.207979202034;7926;7961;5541.750621222135;5654.123610840326;5672.347680016851;5737.311522617312;5807.564432667651;6834.667239888174;7810.88283675734;7950.681972721027;9057.592408341508;9295.72112836616;9390.624375025196;9400.95299523498;9490.857637135587;9504.572294246636;9541.284403669613;6679.732810687518;6887.793366199022;7058.61177635235;7271.600061996641;7304.780856574312;7841.215878412113;7992.40056995716;8102.473339232931;8105.918940810517;8121.4721043132;7072.893906591508;7139.785806425815;7285.453590980804;7322.194558598443;7355.889661654973;7050.858982820418;7120.394766444911;7128.9662998865915;7214.74748383804;7299.087614048244;6730.932690673227;6827.89758153638;6830.624315662686;6834.384905358542;6849.691763870737;4851.078295123915;4912.312276281252;4946.851594452172;4954.702717836942;5008.398992120969;4558.954410455985;4788.730986288567;4965.851024469272;4973.303737476684;5081.161608334583 16:04:02 INFO - PROCESS | 1641 | |4;prototype.html;300.65574916720357;314.5737525652741;320.54802728153294;324.61370968547413;326.31636721069367;4919.87700307492;5260.552853007499;5356.651817631851;5563.304586926634;5631.971840140804;5276.683398996063;5444.183372494128;5458.5087342139195;5492.176173573966;5513.862153446166;3879.439569883877;3924.646781789638;4221.873343799683;4268.615824575788;4272.671394090228;4021.8994525136814;4081.9591804082024;4181.686373521998;4187.623113919746;4246.766427846467;3825.9043523911987;4090.2637525245445;4172;4197.16056788642;4218.3250679891225;3922.666573341269;4165.937510937323;4168.687348448879;4195.475565554306;4227.260229459832;3522.7886326820217;3669.504616876714;3690.8777452964655;3697.242065376618;3722.3113723961155;4740.668153229275;5244;5291.206319052173;5356.491133342365;5366.731663416814;4293.677974151951;4837.492063333335;4839.540243676845;4840;4848.927266091011;4286.5284818669925;5761.703616686254;5775.826725198251;5805.854853628651;5857.384974577693;3792.260509200707;3919.431682406064;3937.42907278443;3942.48747662802;3964.960350396488;6062.756070750493;6125.5712100153005;6126.356732543108;6197.845053873644;6261.96869015652;3685.4840322354894;3842.4620553122586;3998.9400158997637;4001.7590149516363;4006.659433948089;3351.6983471487683;3792.355299599075;4040.6969477288735;4053.230495639025;4055.465334166146;4482.28283474649;4687.601553867891;4706.42334817833;4728.290756386569;4758.833440829554;5091.5672167865405;5563.749631266603;5748.741306641211;5866.7653293868625;5878.853028674317;1239.2254840724547;1298.8311519502404;1307.7580647580037;1315.6645055510976;1324.913880597758;864.2049314630555;895.647572166021;896.394933419939;902.7291812456236;912.6668765900531;4611.1930412177735;4743.075100355398;4808.605504403684;4894.975525122351;5275.630705850554;5098.1588037973315;5676.602637815396;5688.573356998241;5735.713214339266;5805.854853628609;4147.81334839933;4631.95368046322;4919.385076865392;5054.620903432257;5244.606654500928;2728.386113124571;2973.7918345716025;3019.350839569503;3021.531662592302;3054.068094629181;5742.799002034909;6071.635701857903;6077.088436734525;6098.72555734993;6103;5070.340855688737;5536.474034966672;5617.8033768817895;5946.494547963385;5995.670238136857;5193;5443.482869127427;5727.017494779485;6028.638281703112;6075.210222671025;4902.779374928065;5367.838964831061;5583.302087239095;5759.2512973313205;5854.0048191806645;5126.871828204325;5139.974300128475;5567;5604.775808967595;5769.307683078057;5084.161113416245;6035.305939816977;6044.64785354544;6103.877922441615;6130.47890929276;3457.3258214647904;3584.354816133121;3690.446433035066;3794.4877441545036;3809.8857034289017;4575.7416710404905;4688.320193571981;4741.415928319006;5060.84817455477;5826.76692932278;4670.486246512949;5037.312500312436;5756.453136951983;5850.94149058504;5869.530437564899;4854.5969744975055;5188.662736922088;5433.510984011458;5841.065429531254;5853.414658534112;5497.185928643458;5679.318481782213;5705.800296989752;5732.512736417451;5749;4991.703816244568;5078.390593128848;5789.218455508452;5851.561132915133;5865.949706152862;5006.724630145377;5251.737413129405;5672.347680016851;5710.714464276686;5787.768489260382 16:04:02 INFO - PROCESS | 1641 | |5;yui.html;764.1441585424326;771.479251505234;774.7211004038548;815.2173913043478;846.8094678697292;14699;15167.165805880672;15389.538313850575;15391.538253852403;15524.146171960552;13940.442382304722;14068;14072.64818379541;14083;14190.503332383343;844.3034496540348;855.179028132993;871.7994861181932;879.6965047058754;886.778305423644;840.4915026409026;865.3250464637592;873.8558137907238;881.0528681667201;883.2271762208068;909.9681511147111;925.4863550729342;926.4024704065873;927.9025702301262;928.9953550232273;912.548288597142;913.9634414623374;920.903305152956;922.2621902478024;922.2852289475677;651.5960104735047;674.4031532094083;683.111954459206;685.6194811879404;689.0525527399825;14283.21442320672;14368;14566.271686415637;14598;14753.483628072965;13363.532276370377;13739.96950228727;13781.379837907318;13789.965752568598;13917.652058698512;11347.886521134847;11442.084633229322;11698.94150529242;11752.453593727485;11898.643040708792;3709.981450092759;3744.6068162843053;3769.981150094259;3770.7926064066464;3850.5186851643543;87743.12256877414;89646.55176724074;89768.653470198;90468.09531904728;90710.546447268;10276.383416995004;10457.320274182153;10508.842367364496;10548.578056877639;10625.46872656364;20515.8974205131;20538.38384848457;20580.176792928116;20653;20794.79205207959;7765.1846556110795;7784.221577842283;7869.3311068559815;7913.320306057711;7984.68061277554;4389.144116897238;4430.468343798765;4503.572160644733;4508.256137737302;4511.323301504801;2983.5226363782126;3170.9841450792596;3204.6635103314284;3468.1849765305133;3510.947335789965;586.3286536915208;595.7825393731257;603.2760687175407;636.2110982381905;642.1780121444558;650.443870490733;660.181375094879;676.9593824370553;682.8907374820005;711.8967749676268;9029.548522573845;9220.400673956172;9311.90688093124;9459.574319155654;9613.903860961302;622.9190205273376;652.2759736692268;663.8614775659767;686.5606012152226;688.3300815101649;3175.491921292582;3178.3166619176986;3253.235489659928;3269.9182520436702;3301.422251106047;76161;76385;77906.2209377899;78872.60563697145;121877.39061304637;65149.674251628436;65879.67060164668;83828.58085709655;100038.99961000442;102800.48599757103;59185.70407148023;85426.14573854182;88215;89387.65918511228;92154.61768073484;6234.625922444668;6274.341194174728;6396.264429590657;6497.382748638872;6504.38208370204;52036.73981630219;52065.17771877966;52414.47585524096;55017.72491137519;56992.71503642455;66767.66616166888;68738.65630671814;74761.62619187086;76066;76166;12586.559470418491;12742.68143296425;12785.424655890693;12857.549985750455;13213.537526186537;13679.110857794212;13828;14047.36786844574;14085.859141408455;14140;10554.947225263824;11026.558937642725;11368.772624547628;12735.363231838186;13508.32458377097;12783.296918669563;12897.290649014394;13459.663508412368;13560.321983900963;13746.175229486264;12670.683232918882;13156.736865262832;13548.458061677422;13701.931490342884;13728.588142355746;10618.73453163677;10963.945180274048;11158.330500170016;11213;11510.482028308914;8174.754757357289;8383.413161078666;9382.906170938477;9834.912552688835;10544.959264008166 16:04:02 INFO - PROCESS | 1641 | __end_tp_report 16:04:02 INFO - PROCESS | 1641 | __start_cc_report 16:04:02 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,362 16:04:02 INFO - PROCESS | 1641 | __end_cc_report 16:04:02 INFO - PROCESS | 1641 | __startTimestamp1487376242483__endTimestamp 16:04:02 INFO - PROCESS | 1641 | 16:04:02 INFO - PROCESS | 1641 | ------- Summary: start ------- 16:04:02 INFO - PROCESS | 1641 | Number of tests: 6 16:04:02 INFO - PROCESS | 1641 | 16:04:02 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5811.42 Median:6070.70 stddev:674.95 (11.1%) stddev-sans-first:652.52 16:04:02 INFO - PROCESS | 1641 | Values: 3514.4 3548.2 3568.8 3583.7 3591.4 5100.6 5365.4 5819.9 5922.6 5988.8 4832.5 5860.3 5932.1 5960.3 5967.6 5102.6 5251.8 5290.9 5294.1 5353.7 5331.7 5355.7 5366.0 5391.1 5391.4 5189.7 5210.7 5338.6 5340.3 5363.4 3866.4 5283.8 5294.2 5296.7 5326.0 5186.6 5224.7 5238.6 5290.6 5312.6 5717.5 5896.9 5933.9 5960.6 6048.0 6222.1 6230.8 6263.0 6266.2 6286.2 6289.9 6319.1 6323.6 6328.1 6341.9 5860.9 5923.8 5954.8 5970.5 5983.3 6297.4 6348.4 6357.4 6376.0 6422.6 6281.8 6296.7 6307.5 6335.9 6344.5 6181.7 6232.4 6278.8 6308.1 6339.9 5209.6 6323.9 6333.5 6340.9 6346.0 6280.6 6285.2 6294.4 6303.0 6326.9 4928.4 4992.5 5008.5 5072.4 5115.0 4471.6 4478.0 4478.9 4516.1 4546.8 5838.2 5971.8 6040.9 6061.8 6072.0 5863.7 6233.9 6314.4 6325.7 6350.4 4865.1 5001.5 5932.2 5951.6 6069.4 5531.0 5531.7 5547.7 5551.8 5559.2 6378.0 6458.6 6498.8 6508.1 6518.0 4438.4 6141.7 6234.5 6267.0 6341.1 6478.8 6481.3 6491.1 6505.7 6512.4 6283.3 6299.4 6328.4 6331.2 6331.3 5119.1 6050.4 6092.9 6280.3 6285.8 6212.4 6230.4 6258.2 6272.4 6297.9 6247.6 6248.6 6252.7 6283.9 6295.3 5825.4 6045.0 6066.9 6070.7 6164.5 6171.0 6229.2 6282.7 6286.4 6287.9 6085.2 6107.6 6147.0 6148.9 6166.5 16:04:02 INFO - PROCESS | 1641 | 16:04:02 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:70103.75 Median:21917.28 stddev:152833.72 (697.3%) stddev-sans-first:153173.18 16:04:02 INFO - PROCESS | 1641 | Values: 526.3 537.2 556.4 573.3 591.8 37593.4 39920.8 39926.8 39952.8 40003.6 40587.6 41552.8 42339.0 42370.0 42461.0 9430.4 9546.8 9595.6 9672.3 9706.4 9582.4 9601.0 9679.8 9695.8 9706.3 9547.7 9584.0 9623.7 9683.4 9721.5 9009.1 9026.6 9556.9 9586.6 9675.7 9539.4 9547.8 9593.1 9642.2 9673.0 39113.6 39218.0 39449.2 39570.0 39738.2 3414.7 3512.4 3518.1 3706.9 3975.0 28513.4 29476.4 29577.6 29658.7 30323.1 3186.1 3246.8 3404.5 3415.0 3418.5 441026.0 515413.0 520728.0 520985.8 522249.0 48878.5 60204.4 60489.0 60762.1 60807.8 61860.8 63443.0 63615.0 63694.0 63712.0 16801.2 17069.2 17078.9 17137.9 17194.0 11329.3 12920.7 13106.3 13136.3 13204.7 13929.4 15296.5 15950.6 16425.8 16473.3 7307.9 7318.9 7351.7 7378.1 7457.7 868.5 1029.7 1071.1 1072.1 1078.8 30391.7 30564.8 30761.7 30782.1 30837.4 1013.4 1025.1 1025.8 1080.1 1097.3 15660.8 15722.0 15728.8 15772.4 16075.8 650272.0 664798.0 665825.0 667581.7 668768.0 59328.0 59823.0 59919.1 60068.4 60254.0 1367.4 1399.5 1413.3 1419.0 1424.9 29604.4 30455.4 30592.9 30713.0 30905.7 50806.0 50822.0 50963.5 51447.7 51680.2 505239.0 507569.0 507713.0 507719.0 632402.0 27006.6 27293.5 27381.2 27975.0 28047.0 45916.0 46393.3 47499.5 47862.0 48112.5 38546.2 38965.6 39081.8 40319.0 40453.8 38634.6 38775.0 38938.0 39859.6 39945.4 38008.2 38522.0 38627.2 38795.0 38928.8 32562.2 34644.7 34907.0 35133.5 35318.0 25809.4 26024.7 26084.5 26341.0 27116.2 16:04:02 INFO - PROCESS | 1641 | 16:04:02 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:36939.90 Median:11980.56 stddev:108124.32 (902.5%) stddev-sans-first:108392.79 16:04:02 INFO - PROCESS | 1641 | Values: 381.7 387.4 389.3 398.5 411.9 16187.8 19702.7 19803.8 21001.8 21027.9 20525.7 20771.6 21107.9 21172.5 21419.5 5855.6 6015.6 6110.4 6141.4 6188.5 5849.4 6021.5 6066.8 6095.4 6130.4 5978.8 6071.7 6074.5 6085.4 6092.8 5785.0 5919.3 6064.9 6100.1 6157.9 5860.9 5974.9 5982.4 6018.3 6047.1 13172.6 14355.6 21690.5 25668.0 25790.7 2236.9 2284.6 2366.5 2367.3 2905.2 10114.4 10393.0 10703.6 13089.8 14727.6 1628.6 1890.0 1899.1 1907.4 1909.9 125416.0 127583.0 130002.4 132201.0 132812.0 9393.8 9574.7 10061.6 11329.9 11851.1 11910.8 12591.7 14828.7 15178.3 15217.6 7724.5 8119.1 8230.5 8460.5 8475.9 7982.6 8333.7 8503.7 8637.9 8662.8 3495.7 3847.1 4030.0 4049.7 4075.1 1955.4 1987.7 1992.4 1995.9 2010.5 829.7 916.8 939.3 973.7 979.8 17413.9 20702.6 21586.7 21608.7 21724.2 926.9 955.9 967.0 1003.4 1003.4 9172.0 10534.3 10600.6 11229.2 11351.3 626531.9 645435.0 648612.0 655608.0 658389.0 127664.4 139816.0 140364.0 140704.0 141133.0 1037.2 1043.4 1059.9 1061.7 1064.0 19072.5 20190.8 20325.3 20382.3 20433.6 46667.3 52729.2 54250.5 54768.0 57894.0 76840.2 81008.6 81210.6 81616.0 83346.0 7247.9 7413.3 7509.9 7662.7 7753.1 12676.8 12904.6 12911.0 12925.7 13233.8 11043.4 11146.2 11271.6 11314.9 11490.7 11235.1 11311.9 11349.0 11414.0 11586.6 8919.9 11094.6 11309.5 11429.6 11894.3 7543.5 7572.4 7735.2 7775.0 7907.1 10783.2 11020.7 11246.5 11356.7 11510.5 16:04:02 INFO - PROCESS | 1641 | 16:04:02 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5465.03 Median:4969.58 stddev:2971.85 (59.8%) stddev-sans-first:2963.96 16:04:02 INFO - PROCESS | 1641 | Values: 1332.7 1429.7 1644.2 1737.9 1833.5 7597.8 7857.2 8061.2 8536.6 8549.6 7084.4 7972.9 8260.8 8277.5 8409.0 2365.1 2375.0 2384.0 2389.0 2394.1 2388.0 2402.2 2414.4 2435.2 2443.7 2334.6 2352.5 2371.8 2373.9 2402.5 2363.8 2368.6 2368.8 2375.0 2384.3 2378.8 2379.4 2385.7 2390.7 2408.1 8342.0 8359.2 8434.7 8451.1 8465.9 4976.0 5086.7 5238.3 5251.3 5253.2 3376.5 3476.7 3528.8 3573.5 3595.6 1077.8 1091.4 1101.0 1131.4 1135.3 10948.8 10950.0 10957.0 10958.9 10979.7 4460.7 4657.8 4745.6 4778.0 4781.1 9526.6 9527.3 9528.7 9564.1 9583.3 5205.7 5221.7 5230.3 5236.2 5250.3 4324.7 4333.3 4747.0 4800.4 4809.3 3521.8 3605.6 3788.7 3954.5 4053.3 1242.7 1294.1 1296.6 1298.1 1304.9 896.3 921.5 926.8 934.1 950.9 5710.4 5734.1 5967.7 5993.3 6025.7 436.9 441.0 445.7 452.6 455.6 3944.3 3946.4 3951.8 4063.3 4063.4 9788.2 10212.7 10212.8 10218.4 10237.5 10077.3 10152.6 10175.4 10189.5 10237.3 7600.3 7743.5 7920.2 7926.0 7961.0 5541.8 5654.1 5672.3 5737.3 5807.6 6834.7 7810.9 7950.7 9057.6 9295.7 9390.6 9401.0 9490.9 9504.6 9541.3 6679.7 6887.8 7058.6 7271.6 7304.8 7841.2 7992.4 8102.5 8105.9 8121.5 7072.9 7139.8 7285.5 7322.2 7355.9 7050.9 7120.4 7129.0 7214.7 7299.1 6730.9 6827.9 6830.6 6834.4 6849.7 4851.1 4912.3 4946.9 4954.7 5008.4 4559.0 4788.7 4965.9 4973.3 5081.2 16:04:02 INFO - PROCESS | 1641 | 16:04:02 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4599.70 Median:5065.59 stddev:1409.77 (27.8%) stddev-sans-first:1376.30 16:04:02 INFO - PROCESS | 1641 | Values: 300.7 314.6 320.5 324.6 326.3 4919.9 5260.6 5356.7 5563.3 5632.0 5276.7 5444.2 5458.5 5492.2 5513.9 3879.4 3924.6 4221.9 4268.6 4272.7 4021.9 4082.0 4181.7 4187.6 4246.8 3825.9 4090.3 4172.0 4197.2 4218.3 3922.7 4165.9 4168.7 4195.5 4227.3 3522.8 3669.5 3690.9 3697.2 3722.3 4740.7 5244.0 5291.2 5356.5 5366.7 4293.7 4837.5 4839.5 4840.0 4848.9 4286.5 5761.7 5775.8 5805.9 5857.4 3792.3 3919.4 3937.4 3942.5 3965.0 6062.8 6125.6 6126.4 6197.8 6262.0 3685.5 3842.5 3998.9 4001.8 4006.7 3351.7 3792.4 4040.7 4053.2 4055.5 4482.3 4687.6 4706.4 4728.3 4758.8 5091.6 5563.7 5748.7 5866.8 5878.9 1239.2 1298.8 1307.8 1315.7 1324.9 864.2 895.6 896.4 902.7 912.7 4611.2 4743.1 4808.6 4895.0 5275.6 5098.2 5676.6 5688.6 5735.7 5805.9 4147.8 4632.0 4919.4 5054.6 5244.6 2728.4 2973.8 3019.4 3021.5 3054.1 5742.8 6071.6 6077.1 6098.7 6103.0 5070.3 5536.5 5617.8 5946.5 5995.7 5193.0 5443.5 5727.0 6028.6 6075.2 4902.8 5367.8 5583.3 5759.3 5854.0 5126.9 5140.0 5567.0 5604.8 5769.3 5084.2 6035.3 6044.6 6103.9 6130.5 3457.3 3584.4 3690.4 3794.5 3809.9 4575.7 4688.3 4741.4 5060.8 5826.8 4670.5 5037.3 5756.5 5850.9 5869.5 4854.6 5188.7 5433.5 5841.1 5853.4 5497.2 5679.3 5705.8 5732.5 5749.0 4991.7 5078.4 5789.2 5851.6 5865.9 5006.7 5251.7 5672.3 5710.7 5787.8 16:04:02 INFO - PROCESS | 1641 | 16:04:02 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:19437.39 Median:52239.83 stddev:27676.64 (53.0%) stddev-sans-first:27718.77 16:04:02 INFO - PROCESS | 1641 | Values: 764.1 771.5 774.7 815.2 846.8 14699.0 15167.2 15389.5 15391.5 15524.1 13940.4 14068.0 14072.6 14083.0 14190.5 844.3 855.2 871.8 879.7 886.8 840.5 865.3 873.9 881.1 883.2 910.0 925.5 926.4 927.9 929.0 912.5 914.0 920.9 922.3 922.3 651.6 674.4 683.1 685.6 689.1 14283.2 14368.0 14566.3 14598.0 14753.5 13363.5 13740.0 13781.4 13790.0 13917.7 11347.9 11442.1 11698.9 11752.5 11898.6 3710.0 3744.6 3770.0 3770.8 3850.5 87743.1 89646.6 89768.7 90468.1 90710.5 10276.4 10457.3 10508.8 10548.6 10625.5 20515.9 20538.4 20580.2 20653.0 20794.8 7765.2 7784.2 7869.3 7913.3 7984.7 4389.1 4430.5 4503.6 4508.3 4511.3 2983.5 3171.0 3204.7 3468.2 3510.9 586.3 595.8 603.3 636.2 642.2 650.4 660.2 677.0 682.9 711.9 9029.5 9220.4 9311.9 9459.6 9613.9 622.9 652.3 663.9 686.6 688.3 3175.5 3178.3 3253.2 3269.9 3301.4 76161.0 76385.0 77906.2 78872.6 121877.4 65149.7 65879.7 83828.6 100039.0 102800.5 59185.7 85426.1 88215.0 89387.7 92154.6 6234.6 6274.3 6396.3 6497.4 6504.4 52036.7 52065.2 52414.5 55017.7 56992.7 66767.7 68738.7 74761.6 76066.0 76166.0 12586.6 12742.7 12785.4 12857.5 13213.5 13679.1 13828.0 14047.4 14085.9 14140.0 10554.9 11026.6 11368.8 12735.4 13508.3 12783.3 12897.3 13459.7 13560.3 13746.2 12670.7 13156.7 13548.5 13701.9 13728.6 10618.7 10963.9 11158.3 11213.0 11510.5 8174.8 8383.4 9382.9 9834.9 10545.0 16:04:02 INFO - PROCESS | 1641 | -------- Summary: end -------- 16:04:02 INFO - PROCESS | 1641 | 16:04:03 INFO - TEST-INFO | 1641: exit 0 16:04:03 INFO - TEST-OK | dromaeo_css | took 1114190ms 16:04:03 INFO - TEST-START | kraken 16:04:03 INFO - operating with platform_type : mac_ 16:04:03 INFO - Initialising browser for kraken test... 16:04:03 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/tmpntKkLQ/profile http://localhost:49239/getInfo.html) 16:04:05 INFO - PROCESS | 1654 | 2017-02-17 16:04:05.799 plugin-container[1656:9799] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x852f, name = 'com.apple.tsm.portname' 16:04:05 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:04:05 INFO - PROCESS | 1654 | 2017-02-17 16:04:05.799 plugin-container[1656:9799] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 16:04:05 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:04:05 INFO - PROCESS | 1654 | 2017-02-17 16:04:05.799 plugin-container[1656:9799] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:04:05 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 16:04:05 INFO - PROCESS | 1654 | colorDepth:24 16:04:05 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 16:04:05 INFO - PROCESS | 1654 | __metrics 16:04:05 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 16:04:06 INFO - TEST-INFO | 1654: exit 0 16:04:06 INFO - Browser initialized. 16:04:06 INFO - Running cycle 1/1 for kraken test... 16:04:06 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/T/tmpntKkLQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:04:08 INFO - PROCESS | 1657 | 2017-02-17 16:04:08.981 plugin-container[1658:9925] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 16:04:08 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:04:08 INFO - PROCESS | 1657 | 2017-02-17 16:04:08.981 plugin-container[1658:9925] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 16:04:08 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:04:08 INFO - PROCESS | 1657 | 2017-02-17 16:04:08.981 plugin-container[1658:9925] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:04:18 INFO - PROCESS | 1657 | RSS: Main: 177717248 16:04:18 INFO - PROCESS | 1657 | 16:04:21 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection) 16:04:22 INFO - PROCESS | 1657 | RSS: Main: 190619648 16:04:22 INFO - PROCESS | 1657 | 16:04:25 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-dft) 16:04:25 INFO - PROCESS | 1657 | RSS: Main: 198590464 16:04:25 INFO - PROCESS | 1657 | 16:04:29 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-fft) 16:04:29 INFO - PROCESS | 1657 | RSS: Main: 202080256 16:04:29 INFO - PROCESS | 1657 | 16:04:31 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator) 16:04:32 INFO - PROCESS | 1657 | RSS: Main: 202625024 16:04:32 INFO - PROCESS | 1657 | 16:04:34 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:04:34 INFO - PROCESS | 1657 | RSS: Main: 202702848 16:04:34 INFO - PROCESS | 1657 | 16:04:37 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom) 16:04:38 INFO - PROCESS | 1657 | RSS: Main: 206139392 16:04:38 INFO - PROCESS | 1657 | 16:04:41 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate) 16:04:41 INFO - PROCESS | 1657 | RSS: Main: 206290944 16:04:41 INFO - PROCESS | 1657 | 16:04:44 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial) 16:04:45 INFO - PROCESS | 1657 | RSS: Main: 206147584 16:04:45 INFO - PROCESS | 1657 | 16:04:46 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:04:47 INFO - PROCESS | 1657 | RSS: Main: 206442496 16:04:47 INFO - PROCESS | 1657 | 16:04:49 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:04:50 INFO - PROCESS | 1657 | RSS: Main: 206770176 16:04:50 INFO - PROCESS | 1657 | 16:04:52 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:04:52 INFO - PROCESS | 1657 | RSS: Main: 206770176 16:04:52 INFO - PROCESS | 1657 | 16:04:55 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:04:56 INFO - PROCESS | 1657 | RSS: Main: 206684160 16:04:56 INFO - PROCESS | 1657 | 16:04:59 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:04:59 INFO - PROCESS | 1657 | RSS: Main: 207065088 16:04:59 INFO - PROCESS | 1657 | 16:05:01 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49239/tests/kraken/driver.html?testName=ai-astar) 16:05:01 INFO - PROCESS | 1657 | RSS: Main: 209031168 16:05:01 INFO - PROCESS | 1657 | 16:05:01 INFO - PROCESS | 1657 | __start_tp_report 16:05:01 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 16:05:01 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 16:05:01 INFO - PROCESS | 1657 | |i|pagename|runs| 16:05:01 INFO - PROCESS | 1657 | |0;ai-astar;108;118;116;116;115;117;115;118;124;165 16:05:01 INFO - PROCESS | 1657 | |1;audio-beat-detection;140;111;141;143;109;114;146;142;140;112 16:05:01 INFO - PROCESS | 1657 | |2;audio-dft;199;201;190;288;247;201;194;196;198;194 16:05:01 INFO - PROCESS | 1657 | |3;audio-fft;70;56;72;105;87;79;58;58;76;59 16:05:01 INFO - PROCESS | 1657 | |4;audio-oscillator;84;70;88;72;68;71;113;56;88;122 16:05:01 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;88;86;93;122;90;89;89;100;136;134 16:05:01 INFO - PROCESS | 1657 | |6;imaging-darkroom;137;95;95;96;95;96;95;95;95;95 16:05:01 INFO - PROCESS | 1657 | |7;imaging-desaturate;85;91;86;87;90;88;89;90;96;89 16:05:01 INFO - PROCESS | 1657 | |8;json-parse-financial;57;56;53;57;59;59;61;60;59;69 16:05:01 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;68;75;68;72;72;69;71;94;103;102 16:05:01 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;87;90;103;81;80;83;83;88;83;83 16:05:01 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;163;177;154;140;139;138;141;140;185;141 16:05:01 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;165;166;164;161;166;174;165;165;165;164 16:05:01 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;70;67;66;66;67;67;66;68;67;67 16:05:01 INFO - PROCESS | 1657 | __end_tp_report 16:05:01 INFO - PROCESS | 1657 | __start_cc_report 16:05:01 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,946 16:05:01 INFO - PROCESS | 1657 | __end_cc_report 16:05:01 INFO - PROCESS | 1657 | __startTimestamp1487376301836__endTimestamp 16:05:01 INFO - PROCESS | 1657 | 16:05:01 INFO - PROCESS | 1657 | ------- Summary: start ------- 16:05:01 INFO - PROCESS | 1657 | Number of tests: 14 16:05:01 INFO - PROCESS | 1657 | 16:05:01 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:121.20 Median:117.50 stddev:15.88 (13.5%) stddev-sans-first:16.11 16:05:01 INFO - PROCESS | 1657 | Values: 108.0 118.0 116.0 116.0 115.0 117.0 115.0 118.0 124.0 165.0 16:05:01 INFO - PROCESS | 1657 | 16:05:01 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:129.80 Median:140.50 stddev:15.89 (11.3%) stddev-sans-first:16.42 16:05:01 INFO - PROCESS | 1657 | Values: 140.0 111.0 141.0 143.0 109.0 114.0 146.0 142.0 140.0 112.0 16:05:01 INFO - PROCESS | 1657 | 16:05:01 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:210.80 Median:200.00 stddev:31.59 (15.8%) stddev-sans-first:33.22 16:05:01 INFO - PROCESS | 1657 | Values: 199.0 201.0 190.0 288.0 247.0 201.0 194.0 196.0 198.0 194.0 16:05:01 INFO - PROCESS | 1657 | 16:05:01 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:72.00 Median:71.00 stddev:15.63 (22.0%) stddev-sans-first:16.57 16:05:01 INFO - PROCESS | 1657 | Values: 70.0 56.0 72.0 105.0 87.0 79.0 58.0 58.0 76.0 59.0 16:05:01 INFO - PROCESS | 1657 | 16:05:01 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:83.20 Median:71.50 stddev:20.71 (29.0%) stddev-sans-first:21.96 16:05:01 INFO - PROCESS | 1657 | Values: 84.0 70.0 88.0 72.0 68.0 71.0 113.0 56.0 88.0 122.0 16:05:01 INFO - PROCESS | 1657 | 16:05:01 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:102.70 Median:88.50 stddev:19.98 (22.6%) stddev-sans-first:20.48 16:05:01 INFO - PROCESS | 1657 | Values: 88.0 86.0 93.0 122.0 90.0 89.0 89.0 100.0 136.0 134.0 16:05:01 INFO - PROCESS | 1657 | 16:05:01 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:99.40 Median:95.00 stddev:13.22 (13.9%) stddev-sans-first:0.44 16:05:01 INFO - PROCESS | 1657 | Values: 137.0 95.0 95.0 96.0 95.0 96.0 95.0 95.0 95.0 95.0 16:05:01 INFO - PROCESS | 1657 | 16:05:01 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:89.10 Median:89.50 stddev:3.07 (3.4%) stddev-sans-first:2.88 16:05:01 INFO - PROCESS | 1657 | Values: 85.0 91.0 86.0 87.0 90.0 88.0 89.0 90.0 96.0 89.0 16:05:01 INFO - PROCESS | 1657 | 16:05:01 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:59.00 Median:59.00 stddev:4.19 (7.1%) stddev-sans-first:4.38 16:05:01 INFO - PROCESS | 1657 | Values: 57.0 56.0 53.0 57.0 59.0 59.0 61.0 60.0 59.0 69.0 16:05:01 INFO - PROCESS | 1657 | 16:05:01 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:79.40 Median:71.50 stddev:14.33 (20.0%) stddev-sans-first:14.59 16:05:01 INFO - PROCESS | 1657 | Values: 68.0 75.0 68.0 72.0 72.0 69.0 71.0 94.0 103.0 102.0 16:05:01 INFO - PROCESS | 1657 | 16:05:01 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:83.00 stddev:6.72 (8.1%) stddev-sans-first:7.12 16:05:01 INFO - PROCESS | 1657 | Values: 87.0 90.0 103.0 81.0 80.0 83.0 83.0 88.0 83.0 83.0 16:05:01 INFO - PROCESS | 1657 | 16:05:01 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:151.80 Median:147.50 stddev:17.43 (11.8%) stddev-sans-first:18.01 16:05:01 INFO - PROCESS | 1657 | Values: 163.0 177.0 154.0 140.0 139.0 138.0 141.0 140.0 185.0 141.0 16:05:01 INFO - PROCESS | 1657 | 16:05:01 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.50 Median:165.00 stddev:3.31 (2.0%) stddev-sans-first:3.50 16:05:01 INFO - PROCESS | 1657 | Values: 165.0 166.0 164.0 161.0 166.0 174.0 165.0 165.0 165.0 164.0 16:05:01 INFO - PROCESS | 1657 | 16:05:01 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:1.20 (1.8%) stddev-sans-first:0.67 16:05:01 INFO - PROCESS | 1657 | Values: 70.0 67.0 66.0 66.0 67.0 67.0 66.0 68.0 67.0 67.0 16:05:01 INFO - PROCESS | 1657 | -------- Summary: end -------- 16:05:01 INFO - PROCESS | 1657 | 16:05:02 INFO - TEST-INFO | 1657: exit 0 16:05:02 INFO - TEST-OK | kraken | took 59730ms 16:05:02 INFO - SUITE-END | took 1173s 16:05:02 INFO - Completed test suite (00:19:34) 16:05:02 INFO - javascript benchmark 16:05:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7258.255223124731, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3514.420120680088, 3548.2016546277073, 3568.80371579563, 3583.6774690277884, 3591.407417776068, 5100.642954993152, 5365.436629153941, 5819.9127013094785, 5922.555808314378, 5988.7604495820115, 4832.540908613676, 5860.267466566679, 5932.11018347247, 5960.284765828107, 5967.641941483504, 5102.591792656597, 5251.763670634821, 5290.867728306804, 5294.073537131006, 5353.705546194958, 5331.6534425262425, 5355.6786592804365, 5365.973170134144, 5391.056565101111, 5391.379991301012, 5189.662671926331, 5210.6613070150315, 5338.599605029638, 5340.279062326595, 5363.436839131893, 3866.420036994459, 5283.8150664726745, 5294.232336311214, 5296.655717378397, 5326.014687282865, 5186.611004174702, 5224.712640804754, 5238.607104467142, 5290.55030322423, 5312.568371223418, 5717.485426311652, 5896.911546326808, 5933.8813223735715, 5960.642361458326, 6047.969760151215, 6222.097795819581, 6230.750769969196, 6262.968685156545, 6266.185395898549, 6286.182796236506, 6289.87420251597, 6319.083732858756, 6323.620582765049, 6328.050792381134, 6341.936580634227, 5860.941390586125, 5923.763049478016, 5954.7915822946425, 5970.462658360769, 5983.252093488314, 6297.4017468340435, 6348.396902294275, 6357.427831495188, 6375.968120159462, 6422.550421470452, 6281.811545653638, 6296.65368404733, 6307.463865571478, 6335.936640633627, 6344.460720838688, 6181.690915454209, 6232.376762323822, 6278.843028924222, 6308.053791931247, 6339.904901426482, 5209.583233341399, 6323.9051414228825, 6333.524985626004, 6340.936590634127, 6346.01636746305, 6280.591761535486, 6285.214348206474, 6294.402031806971, 6303.0, 6326.936730632727, 4928.359313289249, 4992.525710057539, 5008.549230569266, 5072.365954255718, 5114.999999999925, 4471.619912307425, 4478.0, 4478.910421791546, 4516.1419330327135, 4546.840860569864, 5838.241028666331, 5971.820845374646, 6040.939590604125, 6061.818145455643, 6072.0, 5863.736131874076, 6233.906491402633, 6314.400131987546, 6325.683715814283, 6350.364963503613, 4865.148598995162, 5001.4998500149695, 5932.228810254639, 5951.642901425928, 6069.3627169147485, 5530.972345138329, 5531.723413829292, 5547.722613869371, 5551.777928882881, 5559.166125081189, 6378.0, 6458.61248325102, 6498.772542960974, 6508.056331831764, 6518.0, 4438.378626992288, 6141.670122563492, 6234.501239900887, 6267.0, 6341.112244285711, 6478.838029049123, 6481.254655714653, 6491.05879647439, 6505.674716264073, 6512.413882750575, 6283.340249273943, 6299.401556852092, 6328.398802113792, 6331.240251169889, 6331.271903730945, 5119.0529751996, 6050.364711705208, 6092.878142437038, 6280.340564240689, 6285.779997700059, 6212.409821066992, 6230.439260466398, 6258.155149054819, 6272.435480806749, 6297.937020629735, 6247.562670613013, 6248.562600617913, 6252.749890004531, 6283.874322513616, 6295.276043254901, 5825.417458254141, 6044.969775151096, 6066.939330606814, 6070.696465176812, 6164.506839452743, 6171.043488259327, 6229.159063526366, 6282.748690052346, 6286.371362863677, 6287.905681414691, 6085.2393450818645, 6107.572469927062, 6146.969265153645, 6148.938510615016, 6166.537509686881], "value": 5771.968609307082, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [526.2921370756333, 537.210300857739, 556.4102051825066, 573.3349314794839, 591.8342863998082, 37593.436098458544, 39920.80039599805, 39926.8003659982, 39952.80023599882, 40003.59996400039, 40587.59412405875, 41552.792236038775, 42339.0, 42370.0, 42461.0, 9430.43417394955, 9546.761330966747, 9595.616175353012, 9672.274579406572, 9706.417614943091, 9582.425054496753, 9600.99189585094, 9679.75800604987, 9695.757606059835, 9706.320557560974, 9547.665831695893, 9583.952080239624, 9623.663171788989, 9683.370580912218, 9721.465319407465, 9009.054049324857, 9026.60087686413, 9556.856647150298, 9586.568604412783, 9675.70972870815, 9539.379940303927, 9547.809043819085, 9593.088656577615, 9642.228621710246, 9673.0, 39113.60886391156, 39218.0, 39449.211015779525, 39570.0107497312, 39738.20523589541, 3414.709749671281, 3512.4380099184264, 3518.05012646584, 3706.9073273168115, 3974.966508707757, 28513.429731405256, 29476.41047179066, 29577.55633665497, 29658.70341296581, 30323.090307290815, 3186.1078897908624, 3246.756493262991, 3404.5233667286598, 3415.0, 3418.5214070030215, 441026.0, 515413.0, 520728.0, 520985.7901420975, 522249.0, 48878.51121488811, 60204.39795602075, 60489.0, 60762.088568671505, 60807.78384432287, 61860.76278474405, 63443.0, 63615.045774313425, 63694.0, 63712.0, 16801.15994200285, 17069.231884565223, 17078.914605426893, 17137.914310428616, 17194.0, 11329.320240785579, 12920.741585168244, 13106.279154646585, 13136.27750473714, 13204.669883252996, 13929.373178207004, 15296.541103766906, 15950.60123496922, 16425.83574164243, 16473.258703358377, 7307.926920730831, 7318.890216646754, 7351.68421947085, 7378.077740282465, 7457.664405101675, 868.5006121480175, 1029.7322696098918, 1071.0735214039798, 1072.0619457974271, 1078.7680648660576, 30391.69608303933, 30564.847175763978, 30761.69238307633, 30782.076537703906, 30837.383252335276, 1013.4426065663855, 1025.144004756037, 1025.789713108826, 1080.0711388206137, 1097.2922465010183, 15660.843391566166, 15721.978071425321, 15728.842711572737, 15772.369105235663, 16075.758863617055, 650272.0, 664798.0, 665825.0, 667581.6620916864, 668768.0, 59328.0, 59823.0, 59919.10121348183, 60068.399316007155, 60254.0, 1367.384676895401, 1399.4751968011994, 1413.250976982201, 1418.9858101418854, 1424.8860091192887, 29604.407911842074, 30455.390892182477, 30592.896420266894, 30713.0, 30905.69094309028, 50806.0, 50822.0, 50963.49036509587, 51447.71380715512, 51680.224796628834, 505239.0, 507569.0, 507713.0, 507719.0, 632402.0, 27006.594901076107, 27293.454130916874, 27381.17856464309, 27975.0, 28047.0, 45916.0, 46393.30410043919, 47499.525004749514, 47862.04275914392, 48112.518874810805, 38546.229075418894, 38965.610343896195, 39081.80459097686, 40319.0, 40453.79773101233, 38634.6136538631, 38775.0, 38938.0, 39859.60140398559, 39945.40081898716, 38008.23983520369, 38522.0369490765, 38627.22745545017, 38795.03012424712, 38928.805355974175, 32562.185945351557, 34644.653553464144, 34907.0, 35133.472997904544, 35318.0, 25809.354766130997, 26024.739752602232, 26084.478310434064, 26341.0, 27116.1865144046], "value": 20097.468180444903, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.69083042735406, 387.35698740091453, 389.3186922884952, 398.4561074133807, 411.9093799364141, 16187.83812161878, 19702.704459433102, 19803.81177129373, 21001.789982100174, 21027.894860525696, 20525.692114618294, 20771.584568308663, 21107.944602769876, 21172.470688232763, 21419.464513387175, 5855.648661080349, 6015.578909476339, 6110.419510146551, 6141.416565426277, 6188.504919606421, 5849.444302791228, 6021.488173505258, 6066.787662431816, 6095.390460953891, 6130.386961303879, 5978.790742324019, 6071.726772295257, 6074.514038876901, 6085.421884920926, 6092.786752463665, 5784.971075144618, 5919.260092488439, 6064.9090263646085, 6100.084987251903, 6157.907631385488, 5860.853478663025, 5974.880502389971, 5982.371850955674, 6018.337982821886, 6047.092936059582, 13172.60482185536, 14355.641108972255, 21690.48166628321, 25668.0, 25790.74209257921, 2236.8769717665523, 2284.623037198877, 2366.4557151854997, 2367.3016460144127, 2905.186547766629, 10114.393136411838, 10393.0, 10703.625373111903, 13089.803652945213, 14727.631809204748, 1628.5521481592539, 1890.0, 1899.08843754997, 1907.3610340535938, 1909.8854068755918, 125416.0, 127583.0, 130002.39995200043, 132201.0, 132811.99999999904, 9393.76515587102, 9574.712758617252, 10061.591377207196, 11329.923657252548, 11851.111166662535, 11910.761784764256, 12591.748165036832, 14828.70342593142, 15178.31697573612, 15217.6195595111, 7724.536527808349, 8119.066307374727, 8230.547319897463, 8460.450070745504, 8475.915240847513, 7982.560959147303, 8333.708320208885, 8503.65985360591, 8637.870431943526, 8662.751468199425, 3495.685388315064, 3847.0959324558708, 4030.0327921299263, 4049.655779258737, 4075.144219713893, 1955.4231501706747, 1987.7117817916321, 1992.4221975626906, 1995.9301424450075, 2010.497375656086, 829.742779738283, 916.8081494057699, 939.276756897199, 973.7146966004947, 979.7844474215661, 17413.91293043527, 20702.58594828095, 21586.676199857015, 21608.675869861963, 21724.23965161212, 926.9443833370019, 955.8805149356331, 966.970990870275, 1003.3678782367062, 1003.3929472669076, 9172.0, 10534.315269507457, 10600.575976960989, 11229.213955023233, 11351.318920864609, 626531.8673406604, 645435.0, 648612.0, 655608.0, 658389.0, 127664.36167819287, 139816.0, 140364.0, 140704.0, 141133.0, 1037.242812746684, 1043.3844032021143, 1059.904608585231, 1061.6708820265742, 1063.9734006649896, 19072.523186920436, 20190.798092018893, 20325.288614898407, 20382.28661996823, 20433.59132817365, 46667.29999050085, 52729.20906186487, 54250.45749542454, 54768.0, 57894.0, 76840.2315976833, 81008.59495702719, 81210.59394702989, 81616.0, 83346.0, 7247.8550428990075, 7413.2957369049855, 7509.9249007509225, 7662.655180517002, 7753.147153812964, 12676.809847852106, 12904.612861614167, 12911.0, 12925.741485170056, 13233.801492977806, 11043.447827608748, 11146.164037697366, 11271.605493807678, 11314.886851131383, 11490.712732181428, 11235.101191904792, 11311.943440282746, 11349.035331996873, 11414.029807466459, 11586.594469193538, 8919.866202006846, 11094.5562177512, 11309.547618095185, 11429.599964001553, 11894.34581098048, 7543.47195696296, 7572.39420846342, 7735.18780528059, 7775.0, 7907.130215676386, 10783.245172837826, 11020.669379918294, 11246.493907774005, 11356.659300221007, 11510.53957841677], "value": 9634.023881989915, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.7467781121586, 1429.671175629605, 1644.2107788261635, 1737.8609711223103, 1833.5324492254474, 7597.772066838003, 7857.21427857214, 8061.234182752643, 8536.573171341432, 8549.57252137393, 7084.397826184768, 7972.9202707973045, 8260.752177434671, 8277.54473503957, 8409.0, 2365.113082594027, 2374.988125059372, 2383.976160238393, 2388.9880550597222, 2394.1141777542334, 2387.9761202387926, 2402.2192787344097, 2414.360194548446, 2435.2450740270483, 2443.6945381827272, 2334.6498025296255, 2352.5177338645535, 2371.7983971362455, 2373.8694371809543, 2402.4834660547976, 2363.83453158279, 2368.62102063669, 2368.798652114572, 2375.049980008002, 2384.3204686664303, 2378.8334816562847, 2379.3575734551746, 2385.666006759055, 2390.6892104026538, 2408.1089996701335, 8342.0, 8359.247667709875, 8434.746957591282, 8451.070382257947, 8465.915340846574, 4976.004799040206, 5086.720230387365, 5238.266642670029, 5251.291075704808, 5253.238280449314, 3376.544166537535, 3476.721862251014, 3528.8412021459094, 3573.535440392758, 3595.586507551639, 1077.7898309829586, 1091.3615534912085, 1100.9614663486739, 1131.3947038334452, 1135.256407053373, 10948.835767463494, 10950.0, 10957.0, 10958.890411095867, 10979.670609881636, 4460.687751857404, 4657.813687452464, 4745.644076694192, 4778.04439112179, 4781.139394908881, 9526.571304291323, 9527.333086684004, 9528.714138575853, 9564.13922746956, 9583.281253905985, 5205.713685747244, 5221.738913054332, 5230.34620672416, 5236.214567814858, 5250.291210686585, 4324.654027677778, 4333.349997500337, 4747.0, 4800.35195248637, 4809.326694262806, 3521.8201902362484, 3605.5673319201337, 3788.7158463115375, 3954.4859168307935, 4053.310937140693, 1242.6520574239228, 1294.1264646363668, 1296.5786119511197, 1298.104308027458, 1304.9478020879249, 896.2964073202505, 921.4517362169498, 926.8285367207088, 934.1219253901311, 950.9144177024101, 5710.395006262065, 5734.139879018097, 5967.731452084667, 5993.340732519419, 6025.728842202111, 436.91698577270853, 441.042936827086, 445.7392425431094, 452.558755213664, 455.58996902787754, 3944.3294639911283, 3946.4474973503734, 3951.8221680024467, 4063.329550624173, 4063.4108054332546, 9788.216942644714, 10212.693619191436, 10212.795744085077, 10218.437985910698, 10237.539310731036, 10077.344972576759, 10152.593896244067, 10175.44035078063, 10189.490525473697, 10237.283390162765, 7600.315971562586, 7743.5353878767455, 7920.207979202034, 7926.0, 7961.0, 5541.750621222135, 5654.123610840326, 5672.347680016851, 5737.311522617312, 5807.564432667651, 6834.667239888174, 7810.88283675734, 7950.681972721027, 9057.592408341508, 9295.72112836616, 9390.624375025196, 9400.95299523498, 9490.857637135587, 9504.572294246636, 9541.284403669613, 6679.732810687518, 6887.793366199022, 7058.61177635235, 7271.600061996641, 7304.780856574312, 7841.215878412113, 7992.40056995716, 8102.473339232931, 8105.918940810517, 8121.4721043132, 7072.893906591508, 7139.785806425815, 7285.453590980804, 7322.194558598443, 7355.889661654973, 7050.858982820418, 7120.394766444911, 7128.9662998865915, 7214.74748383804, 7299.087614048244, 6730.932690673227, 6827.89758153638, 6830.624315662686, 6834.384905358542, 6849.691763870737, 4851.078295123915, 4912.312276281252, 4946.851594452172, 4954.702717836942, 5008.398992120969, 4558.954410455985, 4788.730986288567, 4965.851024469272, 4973.303737476684, 5081.161608334583], "value": 4385.071743807097, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.65574916720357, 314.5737525652741, 320.54802728153294, 324.61370968547413, 326.31636721069367, 4919.87700307492, 5260.552853007499, 5356.651817631851, 5563.304586926634, 5631.971840140804, 5276.683398996063, 5444.183372494128, 5458.5087342139195, 5492.176173573966, 5513.862153446166, 3879.439569883877, 3924.646781789638, 4221.873343799683, 4268.615824575788, 4272.671394090228, 4021.8994525136814, 4081.9591804082024, 4181.686373521998, 4187.623113919746, 4246.766427846467, 3825.9043523911987, 4090.2637525245445, 4172.0, 4197.16056788642, 4218.3250679891225, 3922.666573341269, 4165.937510937323, 4168.687348448879, 4195.475565554306, 4227.260229459832, 3522.7886326820217, 3669.504616876714, 3690.8777452964655, 3697.242065376618, 3722.3113723961155, 4740.668153229275, 5244.0, 5291.206319052173, 5356.491133342365, 5366.731663416814, 4293.677974151951, 4837.492063333335, 4839.540243676845, 4840.0, 4848.927266091011, 4286.5284818669925, 5761.703616686254, 5775.826725198251, 5805.854853628651, 5857.384974577693, 3792.260509200707, 3919.431682406064, 3937.42907278443, 3942.48747662802, 3964.960350396488, 6062.756070750493, 6125.5712100153005, 6126.356732543108, 6197.845053873644, 6261.96869015652, 3685.4840322354894, 3842.4620553122586, 3998.9400158997637, 4001.7590149516363, 4006.659433948089, 3351.6983471487683, 3792.355299599075, 4040.6969477288735, 4053.230495639025, 4055.465334166146, 4482.28283474649, 4687.601553867891, 4706.42334817833, 4728.290756386569, 4758.833440829554, 5091.5672167865405, 5563.749631266603, 5748.741306641211, 5866.7653293868625, 5878.853028674317, 1239.2254840724547, 1298.8311519502404, 1307.7580647580037, 1315.6645055510976, 1324.913880597758, 864.2049314630555, 895.647572166021, 896.394933419939, 902.7291812456236, 912.6668765900531, 4611.1930412177735, 4743.075100355398, 4808.605504403684, 4894.975525122351, 5275.630705850554, 5098.1588037973315, 5676.602637815396, 5688.573356998241, 5735.713214339266, 5805.854853628609, 4147.81334839933, 4631.95368046322, 4919.385076865392, 5054.620903432257, 5244.606654500928, 2728.386113124571, 2973.7918345716025, 3019.350839569503, 3021.531662592302, 3054.068094629181, 5742.799002034909, 6071.635701857903, 6077.088436734525, 6098.72555734993, 6103.0, 5070.340855688737, 5536.474034966672, 5617.8033768817895, 5946.494547963385, 5995.670238136857, 5193.0, 5443.482869127427, 5727.017494779485, 6028.638281703112, 6075.210222671025, 4902.779374928065, 5367.838964831061, 5583.302087239095, 5759.2512973313205, 5854.0048191806645, 5126.871828204325, 5139.974300128475, 5567.0, 5604.775808967595, 5769.307683078057, 5084.161113416245, 6035.305939816977, 6044.64785354544, 6103.877922441615, 6130.47890929276, 3457.3258214647904, 3584.354816133121, 3690.446433035066, 3794.4877441545036, 3809.8857034289017, 4575.7416710404905, 4688.320193571981, 4741.415928319006, 5060.84817455477, 5826.76692932278, 4670.486246512949, 5037.312500312436, 5756.453136951983, 5850.94149058504, 5869.530437564899, 4854.5969744975055, 5188.662736922088, 5433.510984011458, 5841.065429531254, 5853.414658534112, 5497.185928643458, 5679.318481782213, 5705.800296989752, 5732.512736417451, 5749.0, 4991.703816244568, 5078.390593128848, 5789.218455508452, 5851.561132915133, 5865.949706152862, 5006.724630145377, 5251.737413129405, 5672.347680016851, 5710.714464276686, 5787.768489260382], "value": 4150.510958804173, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [764.1441585424326, 771.479251505234, 774.7211004038548, 815.2173913043478, 846.8094678697292, 14699.0, 15167.165805880672, 15389.538313850575, 15391.538253852403, 15524.146171960552, 13940.442382304722, 14068.0, 14072.64818379541, 14083.0, 14190.503332383343, 844.3034496540348, 855.179028132993, 871.7994861181932, 879.6965047058754, 886.778305423644, 840.4915026409026, 865.3250464637592, 873.8558137907238, 881.0528681667201, 883.2271762208068, 909.9681511147111, 925.4863550729342, 926.4024704065873, 927.9025702301262, 928.9953550232273, 912.548288597142, 913.9634414623374, 920.903305152956, 922.2621902478024, 922.2852289475677, 651.5960104735047, 674.4031532094083, 683.111954459206, 685.6194811879404, 689.0525527399825, 14283.21442320672, 14368.0, 14566.271686415637, 14598.0, 14753.483628072965, 13363.532276370377, 13739.96950228727, 13781.379837907318, 13789.965752568598, 13917.652058698512, 11347.886521134847, 11442.084633229322, 11698.94150529242, 11752.453593727485, 11898.643040708792, 3709.981450092759, 3744.6068162843053, 3769.981150094259, 3770.7926064066464, 3850.5186851643543, 87743.12256877414, 89646.55176724074, 89768.653470198, 90468.09531904728, 90710.546447268, 10276.383416995004, 10457.320274182153, 10508.842367364496, 10548.578056877639, 10625.46872656364, 20515.8974205131, 20538.38384848457, 20580.176792928116, 20653.0, 20794.79205207959, 7765.1846556110795, 7784.221577842283, 7869.3311068559815, 7913.320306057711, 7984.68061277554, 4389.144116897238, 4430.468343798765, 4503.572160644733, 4508.256137737302, 4511.323301504801, 2983.5226363782126, 3170.9841450792596, 3204.6635103314284, 3468.1849765305133, 3510.947335789965, 586.3286536915208, 595.7825393731257, 603.2760687175407, 636.2110982381905, 642.1780121444558, 650.443870490733, 660.181375094879, 676.9593824370553, 682.8907374820005, 711.8967749676268, 9029.548522573845, 9220.400673956172, 9311.90688093124, 9459.574319155654, 9613.903860961302, 622.9190205273376, 652.2759736692268, 663.8614775659767, 686.5606012152226, 688.3300815101649, 3175.491921292582, 3178.3166619176986, 3253.235489659928, 3269.9182520436702, 3301.422251106047, 76161.0, 76385.0, 77906.2209377899, 78872.60563697145, 121877.39061304637, 65149.674251628436, 65879.67060164668, 83828.58085709655, 100038.99961000442, 102800.48599757103, 59185.70407148023, 85426.14573854182, 88215.0, 89387.65918511228, 92154.61768073484, 6234.625922444668, 6274.341194174728, 6396.264429590657, 6497.382748638872, 6504.38208370204, 52036.73981630219, 52065.17771877966, 52414.47585524096, 55017.72491137519, 56992.71503642455, 66767.66616166888, 68738.65630671814, 74761.62619187086, 76066.0, 76166.0, 12586.559470418491, 12742.68143296425, 12785.424655890693, 12857.549985750455, 13213.537526186537, 13679.110857794212, 13828.0, 14047.36786844574, 14085.859141408455, 14140.0, 10554.947225263824, 11026.558937642725, 11368.772624547628, 12735.363231838186, 13508.32458377097, 12783.296918669563, 12897.290649014394, 13459.663508412368, 13560.321983900963, 13746.175229486264, 12670.683232918882, 13156.736865262832, 13548.458061677422, 13701.931490342884, 13728.588142355746, 10618.73453163677, 10963.945180274048, 11158.330500170016, 11213.0, 11510.482028308914, 8174.754757357289, 8383.413161078666, 9382.906170938477, 9834.912552688835, 10544.959264008166], "value": 7187.785485446356, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1517.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 118.0, 116.0, 116.0, 115.0, 117.0, 115.0, 118.0, 124.0, 165.0], "value": 121.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 111.0, 141.0, 143.0, 109.0, 114.0, 146.0, 142.0, 140.0, 112.0], "value": 129.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 201.0, 190.0, 288.0, 247.0, 201.0, 194.0, 196.0, 198.0, 194.0], "value": 210.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 56.0, 72.0, 105.0, 87.0, 79.0, 58.0, 58.0, 76.0, 59.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 88.0, 72.0, 68.0, 71.0, 113.0, 56.0, 88.0, 122.0], "value": 83.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 93.0, 122.0, 90.0, 89.0, 89.0, 100.0, 136.0, 134.0], "value": 102.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 99.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 91.0, 86.0, 87.0, 90.0, 88.0, 89.0, 90.0, 96.0, 89.0], "value": 89.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 53.0, 57.0, 59.0, 59.0, 61.0, 60.0, 59.0, 69.0], "value": 59.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 68.0, 72.0, 72.0, 69.0, 71.0, 94.0, 103.0, 102.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 103.0, 81.0, 80.0, 83.0, 83.0, 88.0, 83.0, 83.0], "value": 86.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 177.0, 154.0, 140.0, 139.0, 138.0, 141.0, 140.0, 185.0, 141.0], "value": 151.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 164.0, 161.0, 166.0, 174.0, 165.0, 165.0, 165.0, 164.0], "value": 165.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 66.0, 66.0, 67.0, 67.0, 66.0, 68.0, 67.0, 67.0], "value": 67.1, "unit": "score"}]}]} 16:05:03 INFO - Return code: 0 16:05:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:05:03 ERROR - # TBPL SUCCESS # 16:05:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:05:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:05:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:05:03 INFO - 2017-02-17 16:05:03.160 screenresolution[1662:10312] starting screenresolution argv=screenresolution get 16:05:03 INFO - 2017-02-17 16:05:03.162 screenresolution[1662:10312] Display 0: 1600x1200x32@60 16:05:03 INFO - 2017-02-17 16:05:03.168 screenresolution[1663:10315] starting screenresolution argv=screenresolution list 16:05:03 INFO - Available Modes on Display 0 16:05:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:05:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:05:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:05:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:05:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:05:03 INFO - Intel Iris: 16:05:03 INFO - Chipset Model: Intel Iris 16:05:03 INFO - Type: GPU 16:05:03 INFO - Bus: Built-In 16:05:03 INFO - VRAM (Dynamic, Max): 1536 MB 16:05:03 INFO - Vendor: Intel (0x8086) 16:05:03 INFO - Device ID: 0x0a2e 16:05:03 INFO - Revision ID: 0x0009 16:05:03 INFO - Displays: 16:05:03 INFO - Display: 16:05:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:05:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:05:03 INFO - Display Serial Number: 1600x1200 60 16:05:03 INFO - Main Display: Yes 16:05:03 INFO - Mirror: Off 16:05:03 INFO - Online: Yes 16:05:03 INFO - Rotation: Supported 16:05:03 INFO - Return code: 0 16:05:03 INFO - Running post-action listener: _resource_record_post_action 16:05:03 INFO - [mozharness: 2017-02-18 00:05:03.409728Z] Finished run-tests step (success) 16:05:03 INFO - Running post-run listener: _resource_record_post_run 16:05:03 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 38356992; Write bytes: 505020928; Read time: 393; Write time: 1799 16:05:03 INFO - TinderboxPrint: CPU usage
54.3% 16:05:03 INFO - TinderboxPrint: I/O read bytes / time
38,356,992 / 393 16:05:03 INFO - TinderboxPrint: I/O write bytes / time
505,020,928 / 1,799 16:05:03 INFO - TinderboxPrint: CPU idle
2,186.8 (45.7%) 16:05:03 INFO - TinderboxPrint: CPU system
963.6 (20.1%) 16:05:03 INFO - TinderboxPrint: CPU user
1,635.3 (34.2%) 16:05:03 INFO - TinderboxPrint: Swap in / out
230,510,592 / 0 16:05:03 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 183538176; Write bytes: 179623936; Read time: 16936; Write time: 421 16:05:03 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 36571136; Write bytes: 307845632; Read time: 376; Write time: 1328 16:05:03 INFO - Running post-run listener: _upload_blobber_files 16:05:03 INFO - Blob upload gear active. 16:05:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:05:03 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 16:05:03 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:05:03 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:05:04 INFO - (blobuploader) - INFO - Open directory for files ... 16:05:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:05:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:05:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:05 INFO - (blobuploader) - INFO - Done attempting. 16:05:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:05:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:05:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:06 INFO - (blobuploader) - INFO - Done attempting. 16:05:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:05:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:05:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:08 INFO - (blobuploader) - INFO - Done attempting. 16:05:08 INFO - (blobuploader) - INFO - Iteration through files over. 16:05:08 INFO - Return code: 0 16:05:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:05:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:05:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2d4875602ba302efad95d56a26b2d4314379d0db63660e0d637beaaf773928f64d40a27e17a2c0868004018a5e700857d4929b967b9ea57f8d2fb86cf48e96ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20c800af360ac4cabdbd1d2a43850df73b1fb2b4f0c8ffeb2c54f9c2402474fed8727110debf1669733c3d8bd929cf993f869226f6a4aa3296b5ce306703c911", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9a4e3ac9f5d5281284889a152a192a7d5ed9b75fcb08f1ca8924bffafae57255f40d4a27c7cf6e606c6b3d4c9dbe9f57ddad4f345d31e106cec3b738c53e68d"} 16:05:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:05:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:05:08 INFO - Contents: 16:05:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2d4875602ba302efad95d56a26b2d4314379d0db63660e0d637beaaf773928f64d40a27e17a2c0868004018a5e700857d4929b967b9ea57f8d2fb86cf48e96ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20c800af360ac4cabdbd1d2a43850df73b1fb2b4f0c8ffeb2c54f9c2402474fed8727110debf1669733c3d8bd929cf993f869226f6a4aa3296b5ce306703c911", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9a4e3ac9f5d5281284889a152a192a7d5ed9b75fcb08f1ca8924bffafae57255f40d4a27c7cf6e606c6b3d4c9dbe9f57ddad4f345d31e106cec3b738c53e68d"} 16:05:08 INFO - Running post-run listener: copy_logs_to_upload_dir 16:05:08 INFO - Copying logs to upload dir... 16:05:08 INFO - mkdir: /builds/slave/test/build/upload/logs 16:05:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.753608 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-02-17 16:05:08.804645) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 16:05:08.807668) ========= 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.2EXI1MWJv8/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.SFTNkXK7d3/Listeners TMPDIR=/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/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-Beta/sha512/2d4875602ba302efad95d56a26b2d4314379d0db63660e0d637beaaf773928f64d40a27e17a2c0868004018a5e700857d4929b967b9ea57f8d2fb86cf48e96ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20c800af360ac4cabdbd1d2a43850df73b1fb2b4f0c8ffeb2c54f9c2402474fed8727110debf1669733c3d8bd929cf993f869226f6a4aa3296b5ce306703c911", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9a4e3ac9f5d5281284889a152a192a7d5ed9b75fcb08f1ca8924bffafae57255f40d4a27c7cf6e606c6b3d4c9dbe9f57ddad4f345d31e106cec3b738c53e68d"} build_url:https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010999 build_url: 'https://queue.taskcluster.net/v1/task/YFehOiFURAKFx5Rf3GKjEg/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2d4875602ba302efad95d56a26b2d4314379d0db63660e0d637beaaf773928f64d40a27e17a2c0868004018a5e700857d4929b967b9ea57f8d2fb86cf48e96ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20c800af360ac4cabdbd1d2a43850df73b1fb2b4f0c8ffeb2c54f9c2402474fed8727110debf1669733c3d8bd929cf993f869226f6a4aa3296b5ce306703c911", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9a4e3ac9f5d5281284889a152a192a7d5ed9b75fcb08f1ca8924bffafae57255f40d4a27c7cf6e606c6b3d4c9dbe9f57ddad4f345d31e106cec3b738c53e68d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 16:05:08.842077) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 16:05:08.845389) ========= 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.2EXI1MWJv8/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.SFTNkXK7d3/Listeners TMPDIR=/var/folders/ch/jbqcw7411bjcxr_98hzzbdlh00000w/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.005158 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 16:05:08.893915) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 16:05:08.894210) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 16:05:08.897522) ========= ========= Total master_lag: 0.21 =========