builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0024 starttime: 1480074144.76 results: success (0) buildid: 20161125011519 builduid: c980a8af63d04b6491ada8f38b84b2de revision: aaa739912343078f3b197fd494d061f23254ee9c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 03:42:24.764348) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 03:42:24.764826) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 03:42:24.765121) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 03:42:24.792232) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 03:42:24.792715) ========= 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.IfinvK0NJH/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.kXZRlfX4Em/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-25 03:42:24-- 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% 171M=0s 2016-11-25 03:42:24 (171 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127807 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 03:42:24.946456) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 03:42:24.947009) ========= 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.IfinvK0NJH/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.kXZRlfX4Em/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.026404 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 03:42:24.996538) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 03:42:24.996879) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev aaa739912343078f3b197fd494d061f23254ee9c --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 aaa739912343078f3b197fd494d061f23254ee9c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IfinvK0NJH/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.kXZRlfX4Em/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-25 03:42:25,092 truncating revision to first 12 chars 2016-11-25 03:42:25,092 Setting DEBUG logging. 2016-11-25 03:42:25,092 attempt 1/10 2016-11-25 03:42:25,092 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/aaa739912343?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-25 03:42:26,269 unpacking tar archive at: mozilla-beta-aaa739912343/testing/mozharness/ program finished with exit code 0 elapsedTime=1.457106 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 03:42:26.478507) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 03:42:26.478950) ========= script_repo_revision: aaa739912343078f3b197fd494d061f23254ee9c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 03:42:26.479483) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 03:42:26.479840) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 03:42:26.500018) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-11-25 03:42:26.500423) ========= /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.IfinvK0NJH/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.kXZRlfX4Em/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 03:42:26 INFO - MultiFileLogger online at 20161125 03:42:26 in /builds/slave/test 03:42:26 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 03:42:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:42:26 INFO - {'append_to_log': False, 03:42:26 INFO - 'base_work_dir': '/builds/slave/test', 03:42:26 INFO - 'blob_upload_branch': 'Mozilla-Beta', 03:42:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:42:26 INFO - 'branch': 'Mozilla-Beta', 03:42:26 INFO - 'buildbot_json_path': 'buildprops.json', 03:42:26 INFO - 'config_files': ('talos/mac_config.py',), 03:42:26 INFO - 'default_actions': ('clobber', 03:42:26 INFO - 'read-buildbot-config', 03:42:26 INFO - 'download-and-extract', 03:42:26 INFO - 'populate-webroot', 03:42:26 INFO - 'create-virtualenv', 03:42:26 INFO - 'install', 03:42:26 INFO - 'run-tests'), 03:42:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:42:26 INFO - 'download_minidump_stackwalk': True, 03:42:26 INFO - 'download_symbols': 'ondemand', 03:42:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:42:26 INFO - 'tooltool.py': '/tools/tooltool.py', 03:42:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:42:26 INFO - '/tools/misc-python/virtualenv.py')}, 03:42:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:42:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:42:26 INFO - 'installer_path': 'installer.exe', 03:42:26 INFO - 'log_level': 'info', 03:42:26 INFO - 'log_name': 'talos', 03:42:26 INFO - 'log_to_console': True, 03:42:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:42:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:42:26 INFO - 'opt_config_files': (), 03:42:26 INFO - 'pip_index': False, 03:42:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:42:26 INFO - 'cmd': ('bash', 03:42:26 INFO - '-c', 03:42:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:42:26 INFO - 'enabled': True, 03:42:26 INFO - 'halt_on_failure': False, 03:42:26 INFO - 'name': 'check_screen_resolution'},), 03:42:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:42:26 INFO - 'cmd': ('bash', 03:42:26 INFO - '-c', 03:42:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:42:26 INFO - 'enabled': True, 03:42:26 INFO - 'halt_on_failure': False, 03:42:26 INFO - 'name': 'check_screen_resolution'},), 03:42:26 INFO - 'run_cmd_checks_enabled': True, 03:42:26 INFO - 'sps_profile': False, 03:42:26 INFO - 'sps_profile_interval': 0, 03:42:26 INFO - 'suite': 'dromaeojs-e10s', 03:42:26 INFO - 'system_bits': '32', 03:42:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:42:26 INFO - 'title': 't-yosemite-r7-0024', 03:42:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:42:26 INFO - 'use_talos_json': True, 03:42:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:42:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:42:26 INFO - 'work_dir': 'build'} 03:42:26 INFO - [mozharness: 2016-11-25 11:42:26.701371Z] Running clobber step. 03:42:26 INFO - Running pre-action listener: _resource_record_pre_action 03:42:26 INFO - Running main action method: clobber 03:42:26 INFO - rmtree: /builds/slave/test/build 03:42:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:42:28 INFO - Running post-action listener: _resource_record_post_action 03:42:28 INFO - [mozharness: 2016-11-25 11:42:28.900568Z] Finished clobber step (success) 03:42:28 INFO - [mozharness: 2016-11-25 11:42:28.900691Z] Running read-buildbot-config step. 03:42:28 INFO - Running pre-action listener: _resource_record_pre_action 03:42:28 INFO - Running main action method: read_buildbot_config 03:42:28 INFO - Using buildbot properties: 03:42:28 INFO - { 03:42:28 INFO - "project": "", 03:42:28 INFO - "product": "firefox", 03:42:28 INFO - "script_repo_revision": "production", 03:42:28 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 03:42:28 INFO - "repository": "", 03:42:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 03:42:28 INFO - "buildid": "20161125011519", 03:42:28 INFO - "pgo_build": "False", 03:42:28 INFO - "basedir": "/builds/slave/test", 03:42:28 INFO - "buildnumber": 11, 03:42:28 INFO - "slavename": "t-yosemite-r7-0024", 03:42:28 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 03:42:28 INFO - "platform": "yosemite_r7", 03:42:28 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 03:42:28 INFO - "branch": "mozilla-beta", 03:42:28 INFO - "repo_path": "releases/mozilla-beta", 03:42:28 INFO - "revision": "aaa739912343078f3b197fd494d061f23254ee9c", 03:42:28 INFO - "stage_platform": "macosx64", 03:42:28 INFO - "builduid": "c980a8af63d04b6491ada8f38b84b2de", 03:42:28 INFO - "slavebuilddir": "test" 03:42:28 INFO - } 03:42:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.dmg. 03:42:28 INFO - Running post-action listener: _resource_record_post_action 03:42:28 INFO - [mozharness: 2016-11-25 11:42:28.903979Z] Finished read-buildbot-config step (success) 03:42:28 INFO - [mozharness: 2016-11-25 11:42:28.904090Z] Running download-and-extract step. 03:42:28 INFO - Running pre-action listener: _resource_record_pre_action 03:42:28 INFO - Running main action method: download_and_extract 03:42:28 INFO - mkdir: /builds/slave/test/build/tests 03:42: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')]} 03:42:28 INFO - trying https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json 03:42:28 INFO - Downloading https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 03:42:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json'}, attempt #1 03:42:29 INFO - Downloaded 1165 bytes. 03:42:29 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 03:42:29 INFO - Using the following test package requirements: 03:42:29 INFO - {u'common': [u'firefox-51.0.en-US.mac.common.tests.zip'], 03:42:29 INFO - u'cppunittest': [u'firefox-51.0.en-US.mac.common.tests.zip', 03:42:29 INFO - u'firefox-51.0.en-US.mac.cppunittest.tests.zip'], 03:42:29 INFO - u'gtest': [u'firefox-51.0.en-US.mac.common.tests.zip', 03:42:29 INFO - u'firefox-51.0.en-US.mac.gtest.tests.zip'], 03:42:29 INFO - u'jittest': [u'firefox-51.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 03:42:29 INFO - u'mochitest': [u'firefox-51.0.en-US.mac.common.tests.zip', 03:42:29 INFO - u'firefox-51.0.en-US.mac.mochitest.tests.zip'], 03:42:29 INFO - u'mozbase': [u'firefox-51.0.en-US.mac.common.tests.zip'], 03:42:29 INFO - u'reftest': [u'firefox-51.0.en-US.mac.common.tests.zip', 03:42:29 INFO - u'firefox-51.0.en-US.mac.reftest.tests.zip'], 03:42:29 INFO - u'talos': [u'firefox-51.0.en-US.mac.common.tests.zip', 03:42:29 INFO - u'firefox-51.0.en-US.mac.talos.tests.zip'], 03:42:29 INFO - u'web-platform': [u'firefox-51.0.en-US.mac.common.tests.zip', 03:42:29 INFO - u'firefox-51.0.en-US.mac.web-platform.tests.zip'], 03:42:29 INFO - u'xpcshell': [u'firefox-51.0.en-US.mac.common.tests.zip', 03:42:29 INFO - u'firefox-51.0.en-US.mac.xpcshell.tests.zip']} 03:42:29 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip'] for test suite category: common 03:42:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 03:42:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 03:42:29 INFO - Fetch https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 03:42:30 INFO - Expected file size: 16232529 03:42:37 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip', u'firefox-51.0.en-US.mac.talos.tests.zip'] for test suite category: talos 03:42:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 03:42:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 03:42:37 INFO - Fetch https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 03:42:37 INFO - Expected file size: 16232529 03:42:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip 03:42:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip'}, attempt #1 03:42:44 INFO - Fetch https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip into memory 03:42:45 INFO - Expected file size: 14414089 03:42:45 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')]} 03:42:45 INFO - trying https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.dmg 03:42:45 INFO - Downloading https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0.en-US.mac.dmg 03:42:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg'}, attempt #1 03:42:48 INFO - Downloaded 86048577 bytes. 03:42:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.dmg 03:42:48 INFO - mkdir: /builds/slave/test/properties 03:42:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:42:48 INFO - Writing to file /builds/slave/test/properties/build_url 03:42:48 INFO - Contents: 03:42:48 INFO - build_url:https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.dmg 03:42:48 INFO - Running post-action listener: _resource_record_post_action 03:42:48 INFO - Running post-action listener: set_extra_try_arguments 03:42:48 INFO - [mozharness: 2016-11-25 11:42:48.772931Z] Finished download-and-extract step (success) 03:42:48 INFO - [mozharness: 2016-11-25 11:42:48.773053Z] Running populate-webroot step. 03:42:48 INFO - Running pre-action listener: _resource_record_pre_action 03:42:48 INFO - Running main action method: populate_webroot 03:42:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:42:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:42:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:42:48 INFO - '--tppagecycles', 03:42:48 INFO - '7'], 03:42:48 INFO - 'tests': ['tsvgm']}}, 03:42:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:42:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:42:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:42:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:42:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:42:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:42:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:48 INFO - 'talos_options': ['--disable-e10s'], 03:42:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:42:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:42:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:48 INFO - 'talos_options': ['--disable-e10s'], 03:42:48 INFO - 'tests': ['damp', 'tps']}, 03:42:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:48 INFO - 'tests': ['damp', 'tps']}, 03:42:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:42:48 INFO - 'tests': ['dromaeo_dom']}, 03:42:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:42:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:42:48 INFO - 'tests': ['basic_compositor_video']}, 03:42:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:42:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:42:48 INFO - 'tests': ['a11yr', 03:42:48 INFO - 'ts_paint', 03:42:48 INFO - 'tpaint', 03:42:48 INFO - 'sessionrestore', 03:42:48 INFO - 'sessionrestore_no_auto_restore', 03:42:48 INFO - 'tabpaint']}, 03:42:48 INFO - 'other-e10s': {'tests': ['a11yr', 03:42:48 INFO - 'ts_paint', 03:42:48 INFO - 'tpaint', 03:42:48 INFO - 'sessionrestore', 03:42:48 INFO - 'sessionrestore_no_auto_restore', 03:42:48 INFO - 'tabpaint']}, 03:42:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:42:48 INFO - 'ts_paint', 03:42:48 INFO - 'tpaint', 03:42:48 INFO - 'sessionrestore', 03:42:48 INFO - 'sessionrestore_no_auto_restore', 03:42:48 INFO - 'tabpaint']}, 03:42:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:42:48 INFO - 'ts_paint', 03:42:48 INFO - 'tpaint', 03:42:48 INFO - 'sessionrestore', 03:42:48 INFO - 'sessionrestore_no_auto_restore', 03:42:48 INFO - 'tabpaint']}, 03:42:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:42:48 INFO - 'tests': ['a11yr', 03:42:48 INFO - 'ts_paint', 03:42:48 INFO - 'tpaint', 03:42:48 INFO - 'sessionrestore', 03:42:48 INFO - 'sessionrestore_no_auto_restore', 03:42:48 INFO - 'tabpaint']}, 03:42:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:42:48 INFO - 'tests': ['a11yr', 03:42:48 INFO - 'ts_paint', 03:42:48 INFO - 'tpaint', 03:42:48 INFO - 'sessionrestore', 03:42:48 INFO - 'sessionrestore_no_auto_restore', 03:42:48 INFO - 'tabpaint']}, 03:42:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:42:48 INFO - 'tests': ['tsvgx', 03:42:48 INFO - 'tsvgr_opacity', 03:42:48 INFO - 'tart', 03:42:48 INFO - 'tscrollx', 03:42:48 INFO - 'cart']}, 03:42:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:42:48 INFO - 'tsvgr_opacity', 03:42:48 INFO - 'tart', 03:42:48 INFO - 'tscrollx', 03:42:48 INFO - 'cart']}, 03:42:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:48 INFO - 'talos_options': ['--disable-e10s'], 03:42:48 INFO - 'tests': ['tp5o']}, 03:42:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:48 INFO - 'tests': ['tp5o']}, 03:42:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:48 INFO - 'talos_options': ['--disable-e10s', 03:42:48 INFO - '--xperf_path', 03:42:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:42:48 INFO - 'tests': ['tp5n']}, 03:42:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:48 INFO - 'talos_options': ['--xperf_path', 03:42:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:42:48 INFO - 'tests': ['tp5n']}}, 03:42:48 INFO - 'talos.zip': {'path': '', 03:42:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:42:48 INFO - Running post-action listener: _resource_record_post_action 03:42:48 INFO - [mozharness: 2016-11-25 11:42:48.791364Z] Finished populate-webroot step (success) 03:42:48 INFO - [mozharness: 2016-11-25 11:42:48.791470Z] Running create-virtualenv step. 03:42:48 INFO - Running pre-action listener: _resource_record_pre_action 03:42:48 INFO - Running main action method: create_virtualenv 03:42:48 INFO - Creating virtualenv /builds/slave/test/build/venv 03:42:48 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 03:42:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:42:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:42:48 INFO - Using real prefix '/tools/python27' 03:42:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:42:49 INFO - Installing distribute.............................................................................................................................................................................................done. 03:42:52 INFO - Installing pip.................done. 03:42:52 INFO - Return code: 0 03:42:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:42:52 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')]} 03:42:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:42:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:52 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')]} 03:42:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:42:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:52 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 0x10629e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106214570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6ea597a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060b2d30>, '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 0x105f18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060aab20>, '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.IfinvK0NJH/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.kXZRlfX4Em/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 03:42:52 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 03:42:52 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 03:42:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfinvK0NJH/Render', 03:42:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:42:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:42:52 INFO - 'HOME': '/Users/cltbld', 03:42:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:42:52 INFO - 'LOGNAME': 'cltbld', 03:42:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:42:52 INFO - 'MOZ_NO_REMOTE': '1', 03:42:52 INFO - 'NO_EM_RESTART': '1', 03:42:52 INFO - 'PAGER': '/bin/cat', 03:42:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:42:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:42:52 INFO - 'PWD': '/builds/slave/test', 03:42:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:42:52 INFO - 'SHELL': '/bin/bash', 03:42:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kXZRlfX4Em/Listeners', 03:42:52 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 03:42:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:42:52 INFO - 'USER': 'cltbld', 03:42:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:42:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:42:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:42:52 INFO - 'XPC_FLAGS': '0x0', 03:42:52 INFO - 'XPC_SERVICE_NAME': '0', 03:42:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:42:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:42:52 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 03:42:52 INFO - Cleaning up... 03:42:52 INFO - Return code: 0 03:42:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:42:52 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')]} 03:42:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:42:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:52 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')]} 03:42:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:42:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:52 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 0x10629e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106214570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6ea597a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060b2d30>, '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 0x105f18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060aab20>, '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.IfinvK0NJH/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.kXZRlfX4Em/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 03:42:52 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 03:42:52 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 03:42:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfinvK0NJH/Render', 03:42:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:42:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:42:52 INFO - 'HOME': '/Users/cltbld', 03:42:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:42:52 INFO - 'LOGNAME': 'cltbld', 03:42:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:42:52 INFO - 'MOZ_NO_REMOTE': '1', 03:42:52 INFO - 'NO_EM_RESTART': '1', 03:42:52 INFO - 'PAGER': '/bin/cat', 03:42:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:42:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:42:52 INFO - 'PWD': '/builds/slave/test', 03:42:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:42:52 INFO - 'SHELL': '/bin/bash', 03:42:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kXZRlfX4Em/Listeners', 03:42:52 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 03:42:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:42:52 INFO - 'USER': 'cltbld', 03:42:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:42:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:42:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:42:52 INFO - 'XPC_FLAGS': '0x0', 03:42:52 INFO - 'XPC_SERVICE_NAME': '0', 03:42:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:42:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:42:53 INFO - Downloading/unpacking psutil>=3.1.1 03:42:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:42:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:42:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:42:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:42:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:42:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:42:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:42:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:42:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:42:56 INFO - Installing collected packages: psutil 03:42:56 INFO - Running setup.py install for psutil 03:42:56 INFO - building 'psutil._psutil_osx' extension 03:42:56 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 03:42:56 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 03:42:56 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 03:42:56 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 03:42:56 INFO - building 'psutil._psutil_posix' extension 03:42:56 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 03:42:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:42:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:42:57 INFO - ^ 03:42:57 INFO - 1 warning generated. 03:42:57 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 03:42:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:42:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:42:57 INFO - Successfully installed psutil 03:42:57 INFO - Cleaning up... 03:42:57 INFO - Return code: 0 03:42:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:42:57 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')]} 03:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:42:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:57 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')]} 03:42:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:42:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:57 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 0x10629e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106214570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6ea597a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060b2d30>, '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 0x105f18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060aab20>, '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.IfinvK0NJH/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.kXZRlfX4Em/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 03:42:57 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 03:42:57 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 03:42:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfinvK0NJH/Render', 03:42:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:42:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:42:57 INFO - 'HOME': '/Users/cltbld', 03:42:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:42:57 INFO - 'LOGNAME': 'cltbld', 03:42:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:42:57 INFO - 'MOZ_NO_REMOTE': '1', 03:42:57 INFO - 'NO_EM_RESTART': '1', 03:42:57 INFO - 'PAGER': '/bin/cat', 03:42:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:42:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:42:57 INFO - 'PWD': '/builds/slave/test', 03:42:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:42:57 INFO - 'SHELL': '/bin/bash', 03:42:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kXZRlfX4Em/Listeners', 03:42:57 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 03:42:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:42:57 INFO - 'USER': 'cltbld', 03:42:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:42:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:42:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:42:57 INFO - 'XPC_FLAGS': '0x0', 03:42:57 INFO - 'XPC_SERVICE_NAME': '0', 03:42:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:42:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:42:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:42:57 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 03:42:57 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 03:42:57 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 03:42:57 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 03:42:57 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 03:42:57 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 03:43:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:43:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:43:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:43:00 INFO - Installing collected packages: mozsystemmonitor 03:43:00 INFO - Running setup.py install for mozsystemmonitor 03:43:00 INFO - Successfully installed mozsystemmonitor 03:43:00 INFO - Cleaning up... 03:43:00 INFO - Return code: 0 03:43:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:43:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10629e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106214570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6ea597a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060b2d30>, '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 0x105f18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060aab20>, '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.IfinvK0NJH/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.kXZRlfX4Em/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 03:43:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:43:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:43:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfinvK0NJH/Render', 03:43:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:43:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:43:00 INFO - 'HOME': '/Users/cltbld', 03:43:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:43:00 INFO - 'LOGNAME': 'cltbld', 03:43:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:00 INFO - 'MOZ_NO_REMOTE': '1', 03:43:00 INFO - 'NO_EM_RESTART': '1', 03:43:00 INFO - 'PAGER': '/bin/cat', 03:43:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:43:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:00 INFO - 'PWD': '/builds/slave/test', 03:43:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:43:00 INFO - 'SHELL': '/bin/bash', 03:43:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kXZRlfX4Em/Listeners', 03:43:00 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 03:43:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:43:00 INFO - 'USER': 'cltbld', 03:43:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:43:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:43:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:00 INFO - 'XPC_FLAGS': '0x0', 03:43:00 INFO - 'XPC_SERVICE_NAME': '0', 03:43:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:43:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:00 INFO - Downloading/unpacking jsonschema==2.5.1 03:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:43:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:03 INFO - Downloading functools32-3.2.3-2.tar.gz 03:43:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:43:03 INFO - warning: no files found matching '*.txt' 03:43:03 INFO - no previously-included directories found matching 'build' 03:43:03 INFO - no previously-included directories found matching 'dist' 03:43:03 INFO - no previously-included directories found matching '.git*' 03:43:03 INFO - Installing collected packages: jsonschema, functools32 03:43:03 INFO - Running setup.py install for functools32 03:43:03 INFO - warning: no files found matching '*.txt' 03:43:03 INFO - no previously-included directories found matching 'build' 03:43:03 INFO - no previously-included directories found matching 'dist' 03:43:03 INFO - no previously-included directories found matching '.git*' 03:43:03 INFO - Successfully installed jsonschema functools32 03:43:03 INFO - Cleaning up... 03:43:03 INFO - Return code: 0 03:43:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:43:03 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')]} 03:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:03 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')]} 03:43:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:03 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 0x10629e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106214570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6ea597a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060b2d30>, '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 0x105f18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060aab20>, '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.IfinvK0NJH/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.kXZRlfX4Em/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 03:43:03 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 03:43:03 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 03:43:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfinvK0NJH/Render', 03:43:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:43:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:43:03 INFO - 'HOME': '/Users/cltbld', 03:43:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:43:03 INFO - 'LOGNAME': 'cltbld', 03:43:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:03 INFO - 'MOZ_NO_REMOTE': '1', 03:43:03 INFO - 'NO_EM_RESTART': '1', 03:43:03 INFO - 'PAGER': '/bin/cat', 03:43:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:43:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:03 INFO - 'PWD': '/builds/slave/test', 03:43:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:43:03 INFO - 'SHELL': '/bin/bash', 03:43:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kXZRlfX4Em/Listeners', 03:43:03 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 03:43:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:43:03 INFO - 'USER': 'cltbld', 03:43:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:43:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:43:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:03 INFO - 'XPC_FLAGS': '0x0', 03:43:03 INFO - 'XPC_SERVICE_NAME': '0', 03:43:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:43:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:43:04 INFO - Cleaning up... 03:43:04 INFO - Return code: 0 03:43:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:43: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')]} 03:43:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43: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')]} 03:43:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:04 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 0x10629e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106214570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6ea597a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060b2d30>, '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 0x105f18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060aab20>, '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.IfinvK0NJH/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.kXZRlfX4Em/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 03:43:04 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 03:43:04 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 03:43:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfinvK0NJH/Render', 03:43:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:43:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:43:04 INFO - 'HOME': '/Users/cltbld', 03:43:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:43:04 INFO - 'LOGNAME': 'cltbld', 03:43:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:04 INFO - 'MOZ_NO_REMOTE': '1', 03:43:04 INFO - 'NO_EM_RESTART': '1', 03:43:04 INFO - 'PAGER': '/bin/cat', 03:43:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:43:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:04 INFO - 'PWD': '/builds/slave/test', 03:43:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:43:04 INFO - 'SHELL': '/bin/bash', 03:43:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kXZRlfX4Em/Listeners', 03:43:04 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 03:43:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:43:04 INFO - 'USER': 'cltbld', 03:43:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:43:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:43:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:04 INFO - 'XPC_FLAGS': '0x0', 03:43:04 INFO - 'XPC_SERVICE_NAME': '0', 03:43:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:43:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:04 INFO - Downloading/unpacking blobuploader==1.2.4 03:43:04 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 03:43:04 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 03:43:04 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 03:43:04 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 03:43:04 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 03:43:04 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 03:43:06 INFO - Downloading blobuploader-1.2.4.tar.gz 03:43:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:43:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:43:07 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 03:43:07 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 03:43:07 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 03:43:07 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 03:43:07 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 03:43:07 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 03:43:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:43:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:43:07 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 03:43:07 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 03:43:07 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 03:43:07 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 03:43:07 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 03:43:07 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 03:43:08 INFO - Downloading docopt-0.6.1.tar.gz 03:43:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:43:08 INFO - Installing collected packages: blobuploader, requests, docopt 03:43:08 INFO - Running setup.py install for blobuploader 03:43:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:43:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:43:08 INFO - Running setup.py install for requests 03:43:08 INFO - Running setup.py install for docopt 03:43:09 INFO - Successfully installed blobuploader requests docopt 03:43:09 INFO - Cleaning up... 03:43:09 INFO - Return code: 0 03:43:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:09 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 0x10629e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106214570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6ea597a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060b2d30>, '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 0x105f18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060aab20>, '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.IfinvK0NJH/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.kXZRlfX4Em/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 03:43:09 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 03:43:09 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 03:43:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfinvK0NJH/Render', 03:43:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:43:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:43:09 INFO - 'HOME': '/Users/cltbld', 03:43:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:43:09 INFO - 'LOGNAME': 'cltbld', 03:43:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:09 INFO - 'MOZ_NO_REMOTE': '1', 03:43:09 INFO - 'NO_EM_RESTART': '1', 03:43:09 INFO - 'PAGER': '/bin/cat', 03:43:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:43:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:09 INFO - 'PWD': '/builds/slave/test', 03:43:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:43:09 INFO - 'SHELL': '/bin/bash', 03:43:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kXZRlfX4Em/Listeners', 03:43:09 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 03:43:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:43:09 INFO - 'USER': 'cltbld', 03:43:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:43:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:43:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:09 INFO - 'XPC_FLAGS': '0x0', 03:43:09 INFO - 'XPC_SERVICE_NAME': '0', 03:43:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:43:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:43:09 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-3U10W6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:43:09 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-KVKwtg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:43:09 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-AE43iy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:43:09 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-biZ19j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:43:09 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-7eHy1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:43:09 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-p8nqiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:43:10 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-maUIWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:43:10 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-F57R3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:43:10 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-wLQv7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:43:10 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Y8sfEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:43:10 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-cKcnFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:43:10 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-nBaGYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:43:10 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-eryfMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:43:10 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-ifMZkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:10 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-eqnrmR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:43:10 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-NgsACS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:43:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:43:11 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-hDobqz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:43:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:43:11 INFO - Running setup.py install for manifestparser 03:43:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:43:11 INFO - Running setup.py install for mozcrash 03:43:11 INFO - Running setup.py install for mozdebug 03:43:11 INFO - Running setup.py install for mozdevice 03:43:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:43:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:43:11 INFO - Running setup.py install for mozfile 03:43:11 INFO - Running setup.py install for mozhttpd 03:43:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:43:12 INFO - Running setup.py install for mozinfo 03:43:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:43:12 INFO - Running setup.py install for mozInstall 03:43:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:43:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:43:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:43:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:43:12 INFO - Running setup.py install for mozleak 03:43:12 INFO - Running setup.py install for mozlog 03:43:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:43:12 INFO - Running setup.py install for moznetwork 03:43:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:43:12 INFO - Running setup.py install for mozprocess 03:43:12 INFO - Running setup.py install for mozprofile 03:43:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:43:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:43:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:43:13 INFO - Running setup.py install for mozrunner 03:43:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:43:13 INFO - Running setup.py install for mozscreenshot 03:43:13 INFO - Running setup.py install for moztest 03:43:13 INFO - Running setup.py install for mozversion 03:43:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:43:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:43:13 INFO - Cleaning up... 03:43:13 INFO - Return code: 0 03:43:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:13 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 0x10629e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106214570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6ea597a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060b2d30>, '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 0x105f18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060aab20>, '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.IfinvK0NJH/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.kXZRlfX4Em/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 03:43:13 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 03:43:13 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 03:43:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfinvK0NJH/Render', 03:43:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:43:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:43:13 INFO - 'HOME': '/Users/cltbld', 03:43:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:43:13 INFO - 'LOGNAME': 'cltbld', 03:43:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:13 INFO - 'MOZ_NO_REMOTE': '1', 03:43:13 INFO - 'NO_EM_RESTART': '1', 03:43:13 INFO - 'PAGER': '/bin/cat', 03:43:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:43:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:13 INFO - 'PWD': '/builds/slave/test', 03:43:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:43:13 INFO - 'SHELL': '/bin/bash', 03:43:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kXZRlfX4Em/Listeners', 03:43:13 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 03:43:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:43:13 INFO - 'USER': 'cltbld', 03:43:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:43:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:43:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:13 INFO - 'XPC_FLAGS': '0x0', 03:43:13 INFO - 'XPC_SERVICE_NAME': '0', 03:43:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:43:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:43:14 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-fu8MAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:43:14 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)) 03:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:43:14 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-w0B1Bd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:43:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 03:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:43:14 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-FtZyO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:43:14 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)) 03:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:43:14 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-qyLJ8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:43:14 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)) 03:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:43:14 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-NGLCPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:43:14 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)) 03:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:43:14 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-ZzytFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:43:14 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)) 03:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:43:14 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-0kXiSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:43:14 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)) 03:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:43:14 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-2H8J0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:43:14 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)) 03:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:43:14 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-llORGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:43:14 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)) 03:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:43:14 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-4sbgxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:43:15 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)) 03:43:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:43:15 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-FamEBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:43:15 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)) 03:43:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:43:15 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-E6Kfqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:43:15 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)) 03:43:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:43:15 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-rrtKNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:43:15 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)) 03:43:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:43:15 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-iHg8d8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:43:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 03:43:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:15 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-oPNJIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:15 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)) 03:43:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:43:15 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-qSH5gE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:43:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:43:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:43:15 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-unZi4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:43:15 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)) 03:43:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:43:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:43:15 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)) 03:43:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:43:15 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)) 03:43:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:43:15 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 03:43:15 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 03:43:15 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 03:43:15 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 03:43:15 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 03:43:15 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 03:43:18 INFO - Downloading blessings-1.6.tar.gz 03:43:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:43:18 INFO - Installing collected packages: blessings 03:43:18 INFO - Running setup.py install for blessings 03:43:18 INFO - Successfully installed blessings 03:43:18 INFO - Cleaning up... 03:43:18 INFO - Return code: 0 03:43:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:43:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:43:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:43:18 INFO - Reading from file tmpfile_stdout 03:43:18 INFO - Current package versions: 03:43:18 INFO - blessings == 1.6 03:43:18 INFO - blobuploader == 1.2.4 03:43:18 INFO - docopt == 0.6.1 03:43:18 INFO - functools32 == 3.2.3-2 03:43:18 INFO - jsonschema == 2.5.1 03:43:18 INFO - manifestparser == 1.1 03:43:18 INFO - mozInstall == 1.12 03:43:18 INFO - mozcrash == 0.17 03:43:18 INFO - mozdebug == 0.1 03:43:18 INFO - mozdevice == 0.48 03:43:18 INFO - mozfile == 1.2 03:43:18 INFO - mozhttpd == 0.7 03:43:18 INFO - mozinfo == 0.9 03:43:18 INFO - mozleak == 0.1 03:43:18 INFO - mozlog == 3.4 03:43:18 INFO - moznetwork == 0.27 03:43:18 INFO - mozprocess == 0.23 03:43:18 INFO - mozprofile == 0.28 03:43:18 INFO - mozrunner == 6.12 03:43:18 INFO - mozscreenshot == 0.1 03:43:18 INFO - mozsystemmonitor == 0.3 03:43:18 INFO - moztest == 0.7 03:43:18 INFO - mozversion == 1.4 03:43:18 INFO - psutil == 3.1.1 03:43:18 INFO - requests == 1.2.3 03:43:18 INFO - wsgiref == 0.1.2 03:43:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:18 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 0x10629e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106214570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6ea597a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060b2d30>, '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 0x105f18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060aab20>, '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.IfinvK0NJH/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.kXZRlfX4Em/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 03:43:18 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 03:43:18 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 03:43:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfinvK0NJH/Render', 03:43:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:43:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:43:18 INFO - 'HOME': '/Users/cltbld', 03:43:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:43:18 INFO - 'LOGNAME': 'cltbld', 03:43:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:18 INFO - 'MOZ_NO_REMOTE': '1', 03:43:18 INFO - 'NO_EM_RESTART': '1', 03:43:18 INFO - 'PAGER': '/bin/cat', 03:43:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:43:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:18 INFO - 'PWD': '/builds/slave/test', 03:43:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:43:18 INFO - 'SHELL': '/bin/bash', 03:43:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kXZRlfX4Em/Listeners', 03:43:18 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 03:43:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:43:18 INFO - 'USER': 'cltbld', 03:43:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:43:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:43:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:18 INFO - 'XPC_FLAGS': '0x0', 03:43:18 INFO - 'XPC_SERVICE_NAME': '0', 03:43:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:43:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:19 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)) 03:43:19 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)) 03:43:19 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)) 03:43:19 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)) 03:43:19 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)) 03:43:19 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)) 03:43:19 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)) 03:43:19 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)) 03:43:19 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)) 03:43:19 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)) 03:43:19 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)) 03:43:19 INFO - Cleaning up... 03:43:19 INFO - Return code: 0 03:43:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:43:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10629e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106214570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6ea597a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060b2d30>, '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 0x105f18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060aab20>, '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.IfinvK0NJH/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.kXZRlfX4Em/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 03:43:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:43:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:43:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfinvK0NJH/Render', 03:43:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:43:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:43:19 INFO - 'HOME': '/Users/cltbld', 03:43:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:43:19 INFO - 'LOGNAME': 'cltbld', 03:43:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:19 INFO - 'MOZ_NO_REMOTE': '1', 03:43:19 INFO - 'NO_EM_RESTART': '1', 03:43:19 INFO - 'PAGER': '/bin/cat', 03:43:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:43:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:19 INFO - 'PWD': '/builds/slave/test', 03:43:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:43:19 INFO - 'SHELL': '/bin/bash', 03:43:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kXZRlfX4Em/Listeners', 03:43:19 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 03:43:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:43:19 INFO - 'USER': 'cltbld', 03:43:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:43:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:43:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:19 INFO - 'XPC_FLAGS': '0x0', 03:43:19 INFO - 'XPC_SERVICE_NAME': '0', 03:43:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:43:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:43:19 INFO - Cleaning up... 03:43:19 INFO - Return code: 0 03:43:19 INFO - Running post-action listener: _resource_record_post_action 03:43:19 INFO - Running post-action listener: _start_resource_monitoring 03:43:19 INFO - Starting resource monitoring. 03:43:19 INFO - [mozharness: 2016-11-25 11:43:19.494826Z] Finished create-virtualenv step (success) 03:43:19 INFO - [mozharness: 2016-11-25 11:43:19.495762Z] Running install step. 03:43:19 INFO - Running pre-action listener: _resource_record_pre_action 03:43:19 INFO - Running main action method: install 03:43:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:43:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:43:19 INFO - Reading from file tmpfile_stdout 03:43:19 INFO - Detecting whether we're running mozinstall >=1.0... 03:43:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:43:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:43:19 INFO - Reading from file tmpfile_stdout 03:43:19 INFO - Output received: 03:43:19 INFO - Usage: mozinstall [options] installer 03:43:19 INFO - Options: 03:43:19 INFO - -h, --help show this help message and exit 03:43:19 INFO - -d DEST, --destination=DEST 03:43:19 INFO - Directory to install application into. [default: 03:43:19 INFO - "/builds/slave/test"] 03:43:19 INFO - --app=APP Application being installed. [default: firefox] 03:43:19 INFO - mkdir: /builds/slave/test/build/application 03:43:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:43:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.mac.dmg --destination /builds/slave/test/build/application 03:43:42 INFO - Reading from file tmpfile_stdout 03:43:42 INFO - Output received: 03:43:42 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 03:43:42 INFO - Running post-action listener: _resource_record_post_action 03:43:42 INFO - [mozharness: 2016-11-25 11:43:42.190003Z] Finished install step (success) 03:43:42 INFO - [mozharness: 2016-11-25 11:43:42.190156Z] Running run-tests step. 03:43:42 INFO - Running pre-action listener: _resource_record_pre_action 03:43:42 INFO - Running main action method: run_tests 03:43:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:43:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:43:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:43:42 INFO - 2016-11-25 03:43:42.204 screenresolution[1618:7384] starting screenresolution argv=screenresolution get 03:43:42 INFO - 2016-11-25 03:43:42.207 screenresolution[1618:7384] Display 0: 1600x1200x32@60 03:43:42 INFO - 2016-11-25 03:43:42.213 screenresolution[1619:7386] starting screenresolution argv=screenresolution list 03:43:42 INFO - Available Modes on Display 0 03:43:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:43:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:43:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:43:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:43:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:43:42 INFO - Intel Iris: 03:43:42 INFO - Chipset Model: Intel Iris 03:43:42 INFO - Type: GPU 03:43:42 INFO - Bus: Built-In 03:43:42 INFO - VRAM (Dynamic, Max): 1536 MB 03:43:42 INFO - Vendor: Intel (0x8086) 03:43:42 INFO - Device ID: 0x0a2e 03:43:42 INFO - Revision ID: 0x0009 03:43:42 INFO - Displays: 03:43:42 INFO - Display: 03:43:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:43:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:43:42 INFO - Display Serial Number: 1600x1200 60 03:43:42 INFO - Main Display: Yes 03:43:42 INFO - Mirror: Off 03:43:42 INFO - Online: Yes 03:43:42 INFO - Rotation: Supported 03:43:42 INFO - Return code: 0 03:43:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:43:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:43:42 INFO - Python 2.7.3 03:43:42 INFO - Return code: 0 03:43:42 INFO - grabbing minidump binary from tooltool 03:43: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')]} 03:43:42 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 0x1060b2d30>, '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 0x105f18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060aab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:43:42 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 03:43:42 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 03:43:42 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 03:43:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:43:42 INFO - Return code: 0 03:43:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:43:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:43:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:43:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:43:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:43:42 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/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--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 03:43:42 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/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0024 --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 03:43:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfinvK0NJH/Render', 03:43:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:43:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:43:42 INFO - 'HOME': '/Users/cltbld', 03:43:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:43:42 INFO - 'LOGNAME': 'cltbld', 03:43:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:43:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:43:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:42 INFO - 'MOZ_NO_REMOTE': '1', 03:43:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:43:42 INFO - 'NO_EM_RESTART': '1', 03:43:42 INFO - 'PAGER': '/bin/cat', 03:43:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:43:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:42 INFO - 'PWD': '/builds/slave/test', 03:43:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:43:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:43:42 INFO - 'SHELL': '/bin/bash', 03:43:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kXZRlfX4Em/Listeners', 03:43:42 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 03:43:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:43:42 INFO - 'USER': 'cltbld', 03:43:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:43:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:43:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:42 INFO - 'XPC_FLAGS': '0x0', 03:43:42 INFO - 'XPC_SERVICE_NAME': '0', 03:43:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:43:42 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/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--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 03:43:42 INFO - mozversion application_buildid: 20161125011519 03:43:42 INFO - mozversion application_changeset: aaa739912343078f3b197fd494d061f23254ee9c 03:43:42 INFO - mozversion application_display_name: Firefox 03:43:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:43:42 INFO - mozversion application_name: Firefox 03:43:42 INFO - mozversion application_remotingname: firefox 03:43:42 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 03:43:42 INFO - mozversion application_vendor: Mozilla 03:43:42 INFO - mozversion application_version: 51.0 03:43:42 INFO - mozversion platform_buildid: 20161125011519 03:43:42 INFO - mozversion platform_changeset: aaa739912343078f3b197fd494d061f23254ee9c 03:43:42 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 03:43:42 INFO - mozversion platform_version: 51.0 03:43:42 INFO - using testdate: 1480074222 03:43:42 INFO - actual date: 1480074222 03:43:42 INFO - starting webserver on 'localhost:49244' 03:43:42 INFO - SUITE-START | Running 2 tests 03:43:42 INFO - TEST-START | dromaeo_css 03:43:42 INFO - operating with platform_type : mac_ 03:43:42 INFO - Initialising browser for dromaeo_css test... 03:43:42 INFO - TEST-INFO | started process 1629 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpyrCBEg/profile http://localhost:49244/getInfo.html) 03:43:45 INFO - PROCESS | 1629 | __metrics Screen width/height:1600/1200 03:43:45 INFO - PROCESS | 1629 | colorDepth:24 03:43:45 INFO - PROCESS | 1629 | Browser inner width/height: 1024/689 03:43:45 INFO - PROCESS | 1629 | __metrics 03:43:45 ERROR - PROCESS | 1629 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3015: TypeError: this.tabs is undefined 03:43:46 INFO - TEST-INFO | 1629: exit 0 03:43:46 INFO - Browser initialized. 03:43:46 INFO - Running cycle 1/1 for dromaeo_css test... 03:43:46 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpyrCBEg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:43:58 INFO - PROCESS | 1633 | RSS: Main: 173932544 03:43:58 INFO - PROCESS | 1633 | 03:44:47 INFO - PROCESS | 1633 | 1480074287790 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:46:59 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 03:47:00 INFO - PROCESS | 1633 | RSS: Main: 183435264 03:47:00 INFO - PROCESS | 1633 | 03:50:02 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 03:50:03 INFO - PROCESS | 1633 | RSS: Main: 195608576 03:50:03 INFO - PROCESS | 1633 | 03:53:05 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 03:53:05 INFO - PROCESS | 1633 | RSS: Main: 198582272 03:53:05 INFO - PROCESS | 1633 | 03:56:07 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 03:56:08 INFO - PROCESS | 1633 | RSS: Main: 204972032 03:56:08 INFO - PROCESS | 1633 | 03:59:11 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 03:59:11 INFO - PROCESS | 1633 | RSS: Main: 207011840 03:59:11 INFO - PROCESS | 1633 | 04:02:14 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 04:02:14 INFO - PROCESS | 1633 | RSS: Main: 207699968 04:02:14 INFO - PROCESS | 1633 | 04:02:14 INFO - PROCESS | 1633 | __start_tp_report 04:02:14 INFO - PROCESS | 1633 | _x_x_mozilla_page_load 04:02:14 INFO - PROCESS | 1633 | _x_x_mozilla_page_load_details 04:02:14 INFO - PROCESS | 1633 | |i|pagename|runs| 04:02:14 INFO - PROCESS | 1633 | |0;dojo.html;3473.35742887566;3588.300281445118;3603.549556305462;3632.6004139544652;3681.723870709696;4865.197242454995;5582.358028826686;6021.036634138539;6042.546808989321;6064.272287325516;6017.338092809799;6116;6129.356417576158;6139.539534534905;6157.661328626924;5359.062164121283;5461.317335333083;5468.343798744157;5484.478974497416;5555.916661250085;5003.224500202475;5225.947740522603;5552.444755524456;5561.972190139064;5591.6365436246715;5433.239346491494;5443.8094666686675;5470.206820011096;5555.444455554432;5580.553555715533;5486.478784515464;5487.2317875497465;5514.972425137848;5549.750261238254;5635.3237611486475;5387.218853266294;5487.780488780444;5496.450354964471;5507.889842203133;5571;5362;5910.290765108171;5941.910871336933;5986.311574168983;6169.969150154266;5524.972375138099;6218.564700470969;6229.6573688447115;6251.718672659696;6255.437010669016;6275.6862156891975;6319.715612797435;6374;6390.968045159791;6402.103705481236;6053.636781793107;6103.694815259264;6113.205283313186;6119.571629985859;6130.509559235251;6419.1334169887405;6528.281888992207;6563.1796025496815;6566;6587.176602924635;6278.215223097113;6285.151504546828;6289.5911765736;6301;6317.210348706411;6236.18929539157;6280.120783090371;6294.087357333161;6390.297067322591;6393.904091438632;6236.501079913687;6318.336574659686;6333.050042493663;6336.841578960563;6340.873182536324;6256.74973001084;6303.716332765037;6315.936840631627;6332.050192471166;6496.967515162394;5048.343715316985;5095.770690318945;5133.153029750124;5150.175971844487;5190.987757387349;4561.589456948891;4588.564086411786;4600.125976064537;4601.194790911577;4617.792199351037;6138.600990935575;6184;6220.129181914445;6223.315435302113;6268.623882567061;6232.345603711636;6437.967810160919;6449.290578036413;6455.354464553507;6457.483401327882;4658.417697787777;6148.1699970503405;6150.538709596798;6196.535259855529;6259.78090766821;5653.802116925888;5653.886922261532;5660.773569057274;5668.234788303609;5675.262215911987;6557.180352455943;6625.701843417154;6628.767993120217;6634.402903738686;6650.800475985729;5163.27714120031;6190.535709821871;6272.18461599989;6310.337414571404;6313.210848643919;6654.3012983637955;6661.034150048313;6662.866742665023;6663.233728121328;6664.233613134552;6352.174217351715;6352.777652782131;6357.745690172526;6360.014197799349;6372.776952806629;5240.266362709148;5584.380529646357;6136.335271946613;6232.8130156095385;6359.268684101387;6195.597286176384;6277.340879207617;6290;6292.024736165901;6295.464885484785;6257;6292;6292.622442653455;6298.370162983665;6301;5337.679739215504;5522;6126.571140020156;6198.752049917953;6229.50163986889;6171.722272497658;6221.96889015552;6254.405126692664;6274.309825919054;6295.055741638791;6174.38256174379;6189.3501182377;6216.689165541795;6238.157848690369;6255.46828519581 04:02:14 INFO - PROCESS | 1633 | |1;ext.html;480.9134355815953;515.5128403658543;534.9946500534993;553.9889701295137;561.8735784448497;38743.41884871726;41647.79176104119;41689.58310416895;41751.79124104375;41799;41909.58090419095;42338.57661423385;42409;42467;42525.574744252626;9975.755455022616;10159.238057145707;10165.390076595428;10181.236407269447;10184.032516910882;9996.500174991257;10055.49722513875;10056.547455364489;10094.09153176214;10107.241956853266;10064.94967525165;10080.445575493344;10122.038406351385;10137.18902487803;10160.847587286196;10047.397156170617;10111.949440252825;10115.797684046278;10117.342372745748;10130.037646423578;9999.25005624581;10041.447720375376;10098;10100.191984641211;10124.493775311204;40674.38988415176;40913.59086409157;41015.7949210255;41088.589114109076;41158;3357.462805951036;3507.8596856125723;3570.1610121621397;3600.0459878132315;3753.3056384568667;30780.53829192564;30815;31504.842475787704;32131.678683213104;32499.83750081258;3326.800391976489;3351.949720754191;3357.227837597342;3358.9664103358896;3424.1610805352598;498409;499605;499774;500503;501880;34080.48879266813;34474.65525344714;58024.709876450346;58378.7081064592;58781.70609146927;30851.69148308533;35727.285454290766;36152.096197595274;36173.55305787739;36198.8190059048;13133.474661013644;15728;15735;15748.448804292024;15758.606034849221;13186.934065329804;13259.005574581945;13303.268320242287;13305.866941330463;13367.064305498716;8074.798130046795;8098.4331096823835;8112.067112282045;8126.984126984127;8170.978627671541;3400.1499625093725;3426.9485957710654;3432.5194472773833;3443.483477478398;3444.1045328214345;943.217129782279;981.9459929703792;1000.2947921715173;1043.3322673488974;1099.9725006874892;31054.68945310518;31170.22074448157;31460;31551.526727099113;31564.210894727814;1083.3391631105019;1104.762665814293;1150.6202953025481;1155.5204590095016;1211.4911737070274;13494.987875909346;15454.150021748686;15727.05637661744;16229.918850405673;16301.91849040771;716135;721014;728042.3597882083;729869;730554;59032.11451828226;59322;59362;59702;59717.40282597205;1399.4192410149674;1404.192589261179;1428.2073449235775;1436.5690292912084;1439.1365180891382;30106.24734381658;30424.84787576048;31009.069727908198;31172.68827311698;31306.373872522876;49787.506374808814;50162;50226;50304.74847625739;50377.74811125921;556319;595977;600461;601166;601304;27114.593281100406;27843.303917401416;28375.432491349646;28429.289267768472;28577.42845143127;47928.76035619939;47939.520604793506;48237.03525929532;48243.5175648239;48684.75657621831;30669.693303066684;38954;39408.6059139405;39722.60277397189;40698.38952415657;32587.69649214005;39086.804565976985;40235.79882100571;41184;41448.37827432651;38269;38737.418938715375;38925.02687432837;39118.63084792018;40652.1869562613;32045.839770800998;34981.47527787034;35276.29447410986;35486.46770298499;35758.64241357657;26595.468090637693;26738.732612673622;26801.865990670703;27084.885511349683;27154.456910861278 04:02:14 INFO - PROCESS | 1633 | |2;jquery.html;362.99274014519705;371.09082747269196;371.2426649634745;378.16803033326687;386.72542494828673;11859.110566707499;12097.758044839098;12225.021998240141;12321.383930803446;20221.595568088647;22695.319140425814;22706.886465567648;22830;23199.072037118494;23436.179733709323;6794.116764820591;6851.212110607269;7162.964185179067;7176.748813791518;7210.387117095053;6690.364415380531;7129.750458733919;7129.964350178242;7135.143782746078;7139.750108746195;6835.487338449611;6879.89680154798;6982.162140543117;6991.510594258404;7086.433085353159;6903.344182302673;6920.100386949714;7049.154101507801;7099.4675399345;7120.216776154619;6775.728970841161;6845.8973115403305;6847.863042739167;6851.588904665686;6857.27998560149;28285.85857070722;29062.0082465192;29418.264543386373;29453.263668408246;29560.817567297283;2418.6492958521094;2503.3366157968153;2696.245051385615;2786.3591373984095;2838.120182743356;14957.925210373987;17173.570660733458;17855;17877.910610446994;18101.54746131344;2036.2364113457454;2127.5213077057692;2143.2927134045726;2191.386411804697;2207.1612787140944;112750.30874536888;116785.41607291994;118634;149585;151322;22370.440738981655;23147;23264;23349;23505;36539.71142597179;36674.8166259167;36901.077473062855;37322.62677373246;37497.81251093727;11345.773084538263;11541.538338466535;11659.941700291614;11680;11802.822957655642;9363.531823408803;9379.718608441757;9388.812223755487;9568.043195680377;9640.084192001748;10008.39949603026;10303.536340864679;10374.34010558307;10376.273660843819;10424.374537527621;3546.716262698978;3919.235749028912;3976.363781794899;3997.620226078518;4041.6362527372676;749.3031480722871;846.7671390367698;935.4808081514689;956.1490273656451;976.5556671714353;16577.342265773324;21218;21284.148634054774;21416.571668566852;21452.570948581255;962.6486332488591;1004.1715584642699;1038.200585549138;1043.4939054558533;1051.8790339110947;10719.624813131502;11344.20590558653;11358.60244891425;11373.20387572862;11401;549470;555061;555755;610915;650887;108321;132842;138585;145801;147985;798.5009369144285;862.4351050062219;926.9073092690677;960.8126415348939;987.7333120057688;15707.528774136794;17530.47408577745;18473.907630461763;19243.230270789572;19275.22899084021;57470.71264643817;58279.12581311199;58774;58926.410735893805;59283.703581481815;83010.92472688231;83753.58123209345;84000.15999839923;85094.57452712697;86829.56585217033;7831.921680783119;8143.51138931666;8208.507489550646;8406.117357677356;8528.530930798866;11559.786222446523;12369.30587033954;12935.676608084874;13149.474021039052;13431.529896453969;8992.315874230831;10632.670916135483;11339.319640821577;11662.766744665229;11708.004819590431;9538.904610953801;10830.916908309106;10945.781084378426;11100.944495277796;11197.776044479227;11316.37759923212;11377.975982161644;11563.942180289045;11873.82189267179;11910.821337679768;8328.042275138436;8364.581770911309;8452.873206902024;8475.533845638549;8484.872726908978;11183.32900026001;11449;11568.07455403549;11795.410229488663;11805.468753905923 04:02:14 INFO - PROCESS | 1633 | |3;mootools.html;1570.9057456552612;1606.068480281437;1843.1245158549682;1939.8836069835802;1953.4042117154263;8330;8570.571471426434;8687.131286871318;9215.400998935072;9238;8781.868271975909;9059.50172740499;9059.818803623924;9125.178733913946;9199.724008279762;2416.3717433467295;2451.9946821803064;2452.448199155194;2454.619533972237;2464.531738969599;2430.5989511730545;2434.075051480444;2456.263121063683;2460.8646524441147;2471.876406179693;2446.926592202237;2448.6694296270043;2452.877356132195;2454.2759885833634;2476.405662640971;2426.648136020267;2428.101602407103;2428.562858685436;2437.7196622388387;2439.316991242446;2085.9478513037143;2424.860315651641;2430.501747141832;2439.9996001639242;2443.1082654831034;9088.318376121817;9130.817383652357;9185.67850125249;9198.218151457135;9201.03389144137;5422.75597598109;5526.668399896019;5594.0769772987405;5607.859803504904;5622.971885140548;3552.828915756211;3684.613115622875;3728.272986767581;3746.775193488372;3748.475213470116;1086.8261078227445;1103.9668809935715;1107.1143085531542;1124.3534967393782;1130.7512347283584;11744;11847.170698051139;12026.218295810831;12041.759164816742;12056.336901470415;4783.832565860178;4794.640401969867;4841.9031619367415;4911.8457162566765;5008.29883816266;10235.846462303072;10241.436720980417;10261.973802619828;10360.948195258976;10370.27408081427;5498.752556134984;5536.4186760390385;5554.611177217636;5595.244641973363;5611.719414029282;4570.245909424974;4731.455882573479;4965.106280869478;5077.31456253401;5083.542481176712;3607.1703508193264;4138.8791388791415;4142.150859073882;4240;4278.144371125787;1319.64369620202;1321.3723481346476;1329.4283458112911;1336.5121730568274;1339.1228745171927;796.8207053560762;838.8322335532918;926.4719110107309;929.5166513412987;946.2099147212017;5847.38602446737;5971.014782560916;6058.666773327509;6072.666003369857;6299.496040316763;441.7548260715334;442;449.7166784925476;454.470541818784;459.04976698235004;3965.960340396559;4081.2041651878194;4124.443200167999;4151.045259590251;4194.2450358935685;10894.836577451344;11239.381833999045;11248.606298779503;11260.774784504263;11273.605423810128;11159.163062770162;11164.553417863195;11177.944110279397;11189.328640281608;11195.888041119648;8318.958405208055;8496.53269070207;8648.956755216184;8685.044645089036;8713.390062695677;5724.542036636975;5838.474537291664;5950.9404905952115;6036.245469316335;6060;10085.89914100879;10173.186145108522;10274.280800344202;10396.844047339448;10437.895621043692;9478.194353480032;10397.32417392867;10415.791684166426;10528;10561.471926403496;7257.419406447577;7454.030975973089;7625.428092892944;7821.687132514636;8013.4791238569305;8497.362697797813;8608.268297194809;8728.301735861243;8735;8735.519546424754;6577.375149360803;7598.848023039619;7766.184550622103;7816.10114836801;7843.294103530709;6704.99425086241;7499.5875226863045;7659.540427574363;7662.195469475625;7700.306972372513;6829.897551536632;7101.289871013064;7101.644917754195;7105.2894710528535;7113.679884405317;5029.698218106925;5032.345795046621;5161.66449180802;5286.074936885952;5332.68003919766;4453.465584129925;5223.268742376146;5421;5421.783128674809;5509.201165831012 04:02:14 INFO - PROCESS | 1633 | |4;prototype.html;282.98868045278175;302.2127358231806;318.0839183152521;321.6172754422236;322.9015150379136;5638.859028524289;5696.629719068258;5698.601097923147;5712.200291959129;5739.426057394258;4394.560543945612;4996.45251871171;5555.416681248471;5571.944280557193;5691.032524470839;3268.2319654881085;3633.509476220716;3815.771053736785;3951.0837243936667;4211.936820947688;3638.7089032877307;4140.50313962325;4304.655627549789;4317.179735850178;4395.351743096339;3739.383001804699;3855.1904100139004;4200.411942328076;4296.183725092238;4308.82764689412;3987.521497420328;4241.36379543068;4243.384709217177;4353.869383918472;4362.367456718774;3105.9534106988413;3270.4628824452707;3454.3091381723757;3633.418653015505;3634.528016153462;5504.614676972614;5508.834734957958;5516.365617953947;5545.417731138253;5551.417101204356;4531.501534831166;4710.599599034086;4868.02639472107;4870.780814863304;4886.902261954741;4721.457032441244;4971.688165003291;5192.273081768555;5773.855653608652;5952.970235148797;3863.497745293094;4020.3165461871554;4026.5973402659793;4031.879043628696;4032.528127233568;4791.377120974286;5597;6118.235220597426;6267.71795269214;6310.305866354697;4054.2904991626347;4067.6745860331102;4123.587641235912;4130.6282434581035;4140.358244472111;4164.292070348048;4165;4168;4175.498940127143;4190.622843943998;3744.8689295874515;4139.151473947833;4459.598636122764;4531.071130418256;4627.953720462752;4757.881052973703;5123.5644970177955;5697.373288938214;5986.431289027535;5989.460948514655;1141.3323205924628;1288.7229245712217;1333.4932725564224;1395.4976208564908;1406.6847497589786;804.1717031457608;810.4043528006911;837.9023479242212;863.8531449653574;871.6861929705301;4983.750812459362;5446.564274858002;5455;5644;5704.828855134353;5409.890972350659;5628.690422026746;5852;6031.93317613207;6207.720652570645;4929.334539837076;5258.1849813279005;5517.779288828402;5575.414581468968;5630.40880707528;3040.376722771871;3119.53207018949;3137.897608602242;3255.453659511713;3271.6237632672546;5295.841124766263;5897.469227769521;5995.670238136944;6360.840978975562;6508.772192973223;5242.977619364264;5401.702906340189;5761.596688231783;6239.032949892774;6319.52603554726;5840.707964601753;6374.681265936777;6383.521235907233;6444.226692796894;6472.352764723584;5770.971145144247;6100.285373008968;6197.225346831646;6203.534734894991;6260.40526150015;5353.116735738559;6019.488343490677;6167.321594624677;6185.474234689922;6225.8132256032395;5803.274590676166;6206.286277078013;6371;6428.228612566522;6507.577007494498;4069.7558146511305;4091.468109145792;4094.61101195386;4100.630943215125;4104.507459104927;5930.318013428511;6238.313785483684;6282.183316168869;6282.497400207881;6303.281064171977;5647.915281270702;5989.263113696979;6049.606775559574;6101.9931711267145;6155;5371.758270877898;5969.970150149222;6069.905882470541;6123.969380153071;6130.386961303834;5006.4242612098615;5238.319018527567;5653.491185793298;5667.4615911488345;5902.1736956826935;5947.464728174485;6011.248593925759;6030.281369809583;6061.090836374403;6144.53915956314;5929.673867937132;5937.139114828412;5938.001309338756;5954.821355359346;5991.370906054802 04:02:14 INFO - PROCESS | 1633 | |5;yui.html;714.5248409807479;718.8849784034555;763.0080894836711;822.9053658829233;852.4586887326548;12850.871491285096;16654.916725416388;17127.97232166071;17136.48590542286;17149.82850171498;14790.112593244412;15262.542123736277;15510;15517.379304827822;15536.53390398287;721.3615949884364;743.2938708227178;801.4389927051059;876.8772371867959;892.3485855325616;888.9599968001455;889.1686273334453;896.5920506169709;912.3522299167599;913.3606475467167;960.4669408478292;962.9518524073803;963.3449254506933;965.0012237334361;966.912977831995;855.2174760094537;947.1239103828999;960.99095949253;961.4904100826573;964.7105868239535;689.899964505149;692.9688164032631;699.1854489519703;711.5695004522292;711.7615598774416;16130.919345403197;16187.757183642254;16201.675966480605;16420.83579164205;16430.507084787358;13655.658608534766;13703.246321452316;13726.862731372657;14092.506762263374;14142.858571414257;13147.211167329991;13603.387847546885;13659.180449173082;13981.161130332111;14030.92984535081;4680.531946805327;4693.718376897397;4719.150552900476;4733.5266473352385;4733.573978341966;121583;123446;125676;125764;129555.7044429553;11023.503942322615;11239.04468120219;11309.60416385439;11343.716407089723;11471.885281147082;21131.04910279041;21307.46731331698;22073.44816379571;22075.227367042076;22944.541109177724;8387.077421483631;8444.493330400195;8483;8536.317094632415;8631.395802293904;4619.86140415788;4628.190066738307;4628.514006029386;4715.104130215248;4775.0927323809055;3633.527641406653;3773.509443772292;3778.3576791945434;3783.3379158647676;3787.958311464314;667.286003975751;680.1532092544813;682.1405029662661;692.5671455340412;703.1421665567999;564.7712676366078;582.5791014609104;663.0882536512296;672.9293424190488;698.9755358562426;8349.749507514784;9521.52416375463;9593.616255349847;9734.853977190347;10094.192464602815;563.937966823649;578.6643746626946;618.3352895637208;689.2174654647512;705.6894966214849;4829.6860704054125;4898;4943.629227807857;4950.950490495049;4969.478204788517;161721;171297.43053854202;171745;173113.13443432702;174535.12732436258;137192;143745.28127359296;150976;154207;157891.21054394654;100527;122709;123326;129273.70726292617;130005;9876;10396.116330112025;10441.895581044093;10474.633387831389;10750.892491074988;64832;67275.66362168158;67626.66186669035;68840.3115968854;68921.65539172271;80133.5993320053;96250;98741;101951.49024254832;102384.97615023755;10087.546060427152;11179.72050698739;12055.397230138282;13324.200547967153;13449.865501345254;15322.693546129238;15716.214189290262;15734;15800;15800.446984355493;13882.722345552831;13909.513167039106;14267.714645707236;14342.569722908329;14715.632109197355;11999.700007499883;14305.21321327337;14762.926185369004;15167.393304267705;15485.380584776933;14370.856291436952;14894.180820055;15085.47200847965;15172;15393.76909346383;11190;11552.480138393961;12021.398930053287;12125.454354553889;12240.632781016584;8525.573721314033;11566.942165289456;11903.583374581847;12180.634580962585;12228.510859565873 04:02:14 INFO - PROCESS | 1633 | __end_tp_report 04:02:14 INFO - PROCESS | 1633 | __start_cc_report 04:02:14 INFO - PROCESS | 1633 | _x_x_mozilla_cycle_collect,347 04:02:14 INFO - PROCESS | 1633 | __end_cc_report 04:02:14 INFO - PROCESS | 1633 | __startTimestamp1480075334673__endTimestamp 04:02:14 INFO - PROCESS | 1633 | 04:02:14 INFO - PROCESS | 1633 | ------- Summary: start ------- 04:02:14 INFO - PROCESS | 1633 | Number of tests: 6 04:02:14 INFO - PROCESS | 1633 | 04:02:14 INFO - PROCESS | 1633 | [#0] dojo.html Cycles:165 Average:5921.21 Median:6195.60 stddev:643.96 (10.4%) stddev-sans-first:616.64 04:02:14 INFO - PROCESS | 1633 | Values: 3473.4 3588.3 3603.5 3632.6 3681.7 4865.2 5582.4 6021.0 6042.5 6064.3 6017.3 6116.0 6129.4 6139.5 6157.7 5359.1 5461.3 5468.3 5484.5 5555.9 5003.2 5225.9 5552.4 5562.0 5591.6 5433.2 5443.8 5470.2 5555.4 5580.6 5486.5 5487.2 5515.0 5549.8 5635.3 5387.2 5487.8 5496.5 5507.9 5571.0 5362.0 5910.3 5941.9 5986.3 6170.0 5525.0 6218.6 6229.7 6251.7 6255.4 6275.7 6319.7 6374.0 6391.0 6402.1 6053.6 6103.7 6113.2 6119.6 6130.5 6419.1 6528.3 6563.2 6566.0 6587.2 6278.2 6285.2 6289.6 6301.0 6317.2 6236.2 6280.1 6294.1 6390.3 6393.9 6236.5 6318.3 6333.1 6336.8 6340.9 6256.7 6303.7 6315.9 6332.1 6497.0 5048.3 5095.8 5133.2 5150.2 5191.0 4561.6 4588.6 4600.1 4601.2 4617.8 6138.6 6184.0 6220.1 6223.3 6268.6 6232.3 6438.0 6449.3 6455.4 6457.5 4658.4 6148.2 6150.5 6196.5 6259.8 5653.8 5653.9 5660.8 5668.2 5675.3 6557.2 6625.7 6628.8 6634.4 6650.8 5163.3 6190.5 6272.2 6310.3 6313.2 6654.3 6661.0 6662.9 6663.2 6664.2 6352.2 6352.8 6357.7 6360.0 6372.8 5240.3 5584.4 6136.3 6232.8 6359.3 6195.6 6277.3 6290.0 6292.0 6295.5 6257.0 6292.0 6292.6 6298.4 6301.0 5337.7 5522.0 6126.6 6198.8 6229.5 6171.7 6222.0 6254.4 6274.3 6295.1 6174.4 6189.4 6216.7 6238.2 6255.5 04:02:14 INFO - PROCESS | 1633 | 04:02:14 INFO - PROCESS | 1633 | [#1] ext.html Cycles:180 Average:72180.87 Median:18959.38 stddev:164320.74 (866.7%) stddev-sans-first:164693.52 04:02:14 INFO - PROCESS | 1633 | Values: 480.9 515.5 535.0 554.0 561.9 38743.4 41647.8 41689.6 41751.8 41799.0 41909.6 42338.6 42409.0 42467.0 42525.6 9975.8 10159.2 10165.4 10181.2 10184.0 9996.5 10055.5 10056.5 10094.1 10107.2 10064.9 10080.4 10122.0 10137.2 10160.8 10047.4 10111.9 10115.8 10117.3 10130.0 9999.3 10041.4 10098.0 10100.2 10124.5 40674.4 40913.6 41015.8 41088.6 41158.0 3357.5 3507.9 3570.2 3600.0 3753.3 30780.5 30815.0 31504.8 32131.7 32499.8 3326.8 3351.9 3357.2 3359.0 3424.2 498409.0 499605.0 499774.0 500503.0 501880.0 34080.5 34474.7 58024.7 58378.7 58781.7 30851.7 35727.3 36152.1 36173.6 36198.8 13133.5 15728.0 15735.0 15748.4 15758.6 13186.9 13259.0 13303.3 13305.9 13367.1 8074.8 8098.4 8112.1 8127.0 8171.0 3400.1 3426.9 3432.5 3443.5 3444.1 943.2 981.9 1000.3 1043.3 1100.0 31054.7 31170.2 31460.0 31551.5 31564.2 1083.3 1104.8 1150.6 1155.5 1211.5 13495.0 15454.2 15727.1 16229.9 16301.9 716135.0 721014.0 728042.4 729869.0 730554.0 59032.1 59322.0 59362.0 59702.0 59717.4 1399.4 1404.2 1428.2 1436.6 1439.1 30106.2 30424.8 31009.1 31172.7 31306.4 49787.5 50162.0 50226.0 50304.7 50377.7 556319.0 595977.0 600461.0 601166.0 601304.0 27114.6 27843.3 28375.4 28429.3 28577.4 47928.8 47939.5 48237.0 48243.5 48684.8 30669.7 38954.0 39408.6 39722.6 40698.4 32587.7 39086.8 40235.8 41184.0 41448.4 38269.0 38737.4 38925.0 39118.6 40652.2 32045.8 34981.5 35276.3 35486.5 35758.6 26595.5 26738.7 26801.9 27084.9 27154.5 04:02:14 INFO - PROCESS | 1633 | 04:02:14 INFO - PROCESS | 1633 | [#2] jquery.html Cycles:180 Average:37142.20 Median:15536.11 stddev:98254.07 (632.4%) stddev-sans-first:98490.89 04:02:14 INFO - PROCESS | 1633 | Values: 363.0 371.1 371.2 378.2 386.7 11859.1 12097.8 12225.0 12321.4 20221.6 22695.3 22706.9 22830.0 23199.1 23436.2 6794.1 6851.2 7163.0 7176.7 7210.4 6690.4 7129.8 7130.0 7135.1 7139.8 6835.5 6879.9 6982.2 6991.5 7086.4 6903.3 6920.1 7049.2 7099.5 7120.2 6775.7 6845.9 6847.9 6851.6 6857.3 28285.9 29062.0 29418.3 29453.3 29560.8 2418.6 2503.3 2696.2 2786.4 2838.1 14957.9 17173.6 17855.0 17877.9 18101.5 2036.2 2127.5 2143.3 2191.4 2207.2 112750.3 116785.4 118634.0 149585.0 151322.0 22370.4 23147.0 23264.0 23349.0 23505.0 36539.7 36674.8 36901.1 37322.6 37497.8 11345.8 11541.5 11659.9 11680.0 11802.8 9363.5 9379.7 9388.8 9568.0 9640.1 10008.4 10303.5 10374.3 10376.3 10424.4 3546.7 3919.2 3976.4 3997.6 4041.6 749.3 846.8 935.5 956.1 976.6 16577.3 21218.0 21284.1 21416.6 21452.6 962.6 1004.2 1038.2 1043.5 1051.9 10719.6 11344.2 11358.6 11373.2 11401.0 549470.0 555061.0 555755.0 610915.0 650887.0 108321.0 132842.0 138585.0 145801.0 147985.0 798.5 862.4 926.9 960.8 987.7 15707.5 17530.5 18473.9 19243.2 19275.2 57470.7 58279.1 58774.0 58926.4 59283.7 83010.9 83753.6 84000.2 85094.6 86829.6 7831.9 8143.5 8208.5 8406.1 8528.5 11559.8 12369.3 12935.7 13149.5 13431.5 8992.3 10632.7 11339.3 11662.8 11708.0 9538.9 10830.9 10945.8 11100.9 11197.8 11316.4 11378.0 11563.9 11873.8 11910.8 8328.0 8364.6 8452.9 8475.5 8484.9 11183.3 11449.0 11568.1 11795.4 11805.5 04:02:14 INFO - PROCESS | 1633 | 04:02:14 INFO - PROCESS | 1633 | [#3] mootools.html Cycles:180 Average:5884.66 Median:2595.25 stddev:3320.79 (128.0%) stddev-sans-first:3314.28 04:02:14 INFO - PROCESS | 1633 | Values: 1570.9 1606.1 1843.1 1939.9 1953.4 8330.0 8570.6 8687.1 9215.4 9238.0 8781.9 9059.5 9059.8 9125.2 9199.7 2416.4 2452.0 2452.4 2454.6 2464.5 2430.6 2434.1 2456.3 2460.9 2471.9 2446.9 2448.7 2452.9 2454.3 2476.4 2426.6 2428.1 2428.6 2437.7 2439.3 2085.9 2424.9 2430.5 2440.0 2443.1 9088.3 9130.8 9185.7 9198.2 9201.0 5422.8 5526.7 5594.1 5607.9 5623.0 3552.8 3684.6 3728.3 3746.8 3748.5 1086.8 1104.0 1107.1 1124.4 1130.8 11744.0 11847.2 12026.2 12041.8 12056.3 4783.8 4794.6 4841.9 4911.8 5008.3 10235.8 10241.4 10262.0 10360.9 10370.3 5498.8 5536.4 5554.6 5595.2 5611.7 4570.2 4731.5 4965.1 5077.3 5083.5 3607.2 4138.9 4142.2 4240.0 4278.1 1319.6 1321.4 1329.4 1336.5 1339.1 796.8 838.8 926.5 929.5 946.2 5847.4 5971.0 6058.7 6072.7 6299.5 441.8 442.0 449.7 454.5 459.0 3966.0 4081.2 4124.4 4151.0 4194.2 10894.8 11239.4 11248.6 11260.8 11273.6 11159.2 11164.6 11177.9 11189.3 11195.9 8319.0 8496.5 8649.0 8685.0 8713.4 5724.5 5838.5 5950.9 6036.2 6060.0 10085.9 10173.2 10274.3 10396.8 10437.9 9478.2 10397.3 10415.8 10528.0 10561.5 7257.4 7454.0 7625.4 7821.7 8013.5 8497.4 8608.3 8728.3 8735.0 8735.5 6577.4 7598.8 7766.2 7816.1 7843.3 6705.0 7499.6 7659.5 7662.2 7700.3 6829.9 7101.3 7101.6 7105.3 7113.7 5029.7 5032.3 5161.7 5286.1 5332.7 4453.5 5223.3 5421.0 5421.8 5509.2 04:02:14 INFO - PROCESS | 1633 | 04:02:14 INFO - PROCESS | 1633 | [#4] prototype.html Cycles:180 Average:4794.02 Median:5479.81 stddev:1521.33 (27.8%) stddev-sans-first:1487.45 04:02:14 INFO - PROCESS | 1633 | Values: 283.0 302.2 318.1 321.6 322.9 5638.9 5696.6 5698.6 5712.2 5739.4 4394.6 4996.5 5555.4 5571.9 5691.0 3268.2 3633.5 3815.8 3951.1 4211.9 3638.7 4140.5 4304.7 4317.2 4395.4 3739.4 3855.2 4200.4 4296.2 4308.8 3987.5 4241.4 4243.4 4353.9 4362.4 3106.0 3270.5 3454.3 3633.4 3634.5 5504.6 5508.8 5516.4 5545.4 5551.4 4531.5 4710.6 4868.0 4870.8 4886.9 4721.5 4971.7 5192.3 5773.9 5953.0 3863.5 4020.3 4026.6 4031.9 4032.5 4791.4 5597.0 6118.2 6267.7 6310.3 4054.3 4067.7 4123.6 4130.6 4140.4 4164.3 4165.0 4168.0 4175.5 4190.6 3744.9 4139.2 4459.6 4531.1 4628.0 4757.9 5123.6 5697.4 5986.4 5989.5 1141.3 1288.7 1333.5 1395.5 1406.7 804.2 810.4 837.9 863.9 871.7 4983.8 5446.6 5455.0 5644.0 5704.8 5409.9 5628.7 5852.0 6031.9 6207.7 4929.3 5258.2 5517.8 5575.4 5630.4 3040.4 3119.5 3137.9 3255.5 3271.6 5295.8 5897.5 5995.7 6360.8 6508.8 5243.0 5401.7 5761.6 6239.0 6319.5 5840.7 6374.7 6383.5 6444.2 6472.4 5771.0 6100.3 6197.2 6203.5 6260.4 5353.1 6019.5 6167.3 6185.5 6225.8 5803.3 6206.3 6371.0 6428.2 6507.6 4069.8 4091.5 4094.6 4100.6 4104.5 5930.3 6238.3 6282.2 6282.5 6303.3 5647.9 5989.3 6049.6 6102.0 6155.0 5371.8 5970.0 6069.9 6124.0 6130.4 5006.4 5238.3 5653.5 5667.5 5902.2 5947.5 6011.2 6030.3 6061.1 6144.5 5929.7 5937.1 5938.0 5954.8 5991.4 04:02:14 INFO - PROCESS | 1633 | 04:02:14 INFO - PROCESS | 1633 | [#5] yui.html Cycles:180 Average:27370.85 Median:97833.09 stddev:44851.88 (45.8%) stddev-sans-first:44933.05 04:02:14 INFO - PROCESS | 1633 | Values: 714.5 718.9 763.0 822.9 852.5 12850.9 16654.9 17128.0 17136.5 17149.8 14790.1 15262.5 15510.0 15517.4 15536.5 721.4 743.3 801.4 876.9 892.3 889.0 889.2 896.6 912.4 913.4 960.5 963.0 963.3 965.0 966.9 855.2 947.1 961.0 961.5 964.7 689.9 693.0 699.2 711.6 711.8 16130.9 16187.8 16201.7 16420.8 16430.5 13655.7 13703.2 13726.9 14092.5 14142.9 13147.2 13603.4 13659.2 13981.2 14030.9 4680.5 4693.7 4719.2 4733.5 4733.6 121583.0 123446.0 125676.0 125764.0 129555.7 11023.5 11239.0 11309.6 11343.7 11471.9 21131.0 21307.5 22073.4 22075.2 22944.5 8387.1 8444.5 8483.0 8536.3 8631.4 4619.9 4628.2 4628.5 4715.1 4775.1 3633.5 3773.5 3778.4 3783.3 3788.0 667.3 680.2 682.1 692.6 703.1 564.8 582.6 663.1 672.9 699.0 8349.7 9521.5 9593.6 9734.9 10094.2 563.9 578.7 618.3 689.2 705.7 4829.7 4898.0 4943.6 4951.0 4969.5 161721.0 171297.4 171745.0 173113.1 174535.1 137192.0 143745.3 150976.0 154207.0 157891.2 100527.0 122709.0 123326.0 129273.7 130005.0 9876.0 10396.1 10441.9 10474.6 10750.9 64832.0 67275.7 67626.7 68840.3 68921.7 80133.6 96250.0 98741.0 101951.5 102385.0 10087.5 11179.7 12055.4 13324.2 13449.9 15322.7 15716.2 15734.0 15800.0 15800.4 13882.7 13909.5 14267.7 14342.6 14715.6 11999.7 14305.2 14762.9 15167.4 15485.4 14370.9 14894.2 15085.5 15172.0 15393.8 11190.0 11552.5 12021.4 12125.5 12240.6 8525.6 11566.9 11903.6 12180.6 12228.5 04:02:14 INFO - PROCESS | 1633 | -------- Summary: end -------- 04:02:14 INFO - PROCESS | 1633 | 04:02:15 INFO - TEST-INFO | 1633: exit 0 04:02:15 INFO - TEST-OK | dromaeo_css | took 1112517ms 04:02:15 INFO - TEST-START | kraken 04:02:15 INFO - operating with platform_type : mac_ 04:02:15 INFO - Initialising browser for kraken test... 04:02:15 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmprhiL6d/profile http://localhost:49244/getInfo.html) 04:02:17 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 04:02:17 INFO - PROCESS | 1648 | colorDepth:24 04:02:17 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 04:02:17 INFO - PROCESS | 1648 | __metrics 04:02:17 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3015: TypeError: this.tabs is undefined 04:02:18 INFO - TEST-INFO | 1648: exit 0 04:02:18 INFO - Browser initialized. 04:02:18 INFO - Running cycle 1/1 for kraken test... 04:02:18 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmprhiL6d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:02:30 INFO - PROCESS | 1651 | RSS: Main: 174125056 04:02:30 INFO - PROCESS | 1651 | 04:02:33 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 04:02:33 INFO - PROCESS | 1651 | RSS: Main: 186302464 04:02:33 INFO - PROCESS | 1651 | 04:02:36 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 04:02:36 INFO - PROCESS | 1651 | RSS: Main: 193740800 04:02:36 INFO - PROCESS | 1651 | 04:02:40 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 04:02:40 INFO - PROCESS | 1651 | RSS: Main: 197591040 04:02:40 INFO - PROCESS | 1651 | 04:02:42 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 04:02:43 INFO - PROCESS | 1651 | RSS: Main: 197988352 04:02:43 INFO - PROCESS | 1651 | 04:02:45 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:02:46 INFO - PROCESS | 1651 | RSS: Main: 199217152 04:02:46 INFO - PROCESS | 1651 | 04:02:48 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 04:02:49 INFO - PROCESS | 1651 | RSS: Main: 199770112 04:02:49 INFO - PROCESS | 1651 | 04:02:52 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 04:02:53 INFO - PROCESS | 1651 | RSS: Main: 200376320 04:02:53 INFO - PROCESS | 1651 | 04:02:56 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 04:02:56 INFO - PROCESS | 1651 | RSS: Main: 200343552 04:02:56 INFO - PROCESS | 1651 | 04:02:58 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:02:59 INFO - PROCESS | 1651 | RSS: Main: 199852032 04:02:59 INFO - PROCESS | 1651 | 04:03:01 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:03:01 INFO - PROCESS | 1651 | RSS: Main: 200015872 04:03:01 INFO - PROCESS | 1651 | 04:03:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:03:04 INFO - PROCESS | 1651 | RSS: Main: 200015872 04:03:04 INFO - PROCESS | 1651 | 04:03:07 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:03:08 INFO - PROCESS | 1651 | RSS: Main: 200515584 04:03:08 INFO - PROCESS | 1651 | 04:03:10 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:03:11 INFO - PROCESS | 1651 | RSS: Main: 200241152 04:03:11 INFO - PROCESS | 1651 | 04:03:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 04:03:13 INFO - PROCESS | 1651 | RSS: Main: 201977856 04:03:13 INFO - PROCESS | 1651 | 04:03:13 INFO - PROCESS | 1651 | __start_tp_report 04:03:13 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 04:03:13 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 04:03:13 INFO - PROCESS | 1651 | |i|pagename|runs| 04:03:13 INFO - PROCESS | 1651 | |0;ai-astar;106;116;118;118;119;119;118;117;118;118 04:03:13 INFO - PROCESS | 1651 | |1;audio-beat-detection;145;113;108;140;112;111;109;109;136;113 04:03:13 INFO - PROCESS | 1651 | |2;audio-dft;193;194;239;282;233;192;193;193;197;201 04:03:13 INFO - PROCESS | 1651 | |3;audio-fft;86;60;61;88;84;50;49;48;76;85 04:03:13 INFO - PROCESS | 1651 | |4;audio-oscillator;81;70;85;84;89;84;87;68;70;83 04:03:13 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;91;89;91;88;90;88;92;89;90;89 04:03:13 INFO - PROCESS | 1651 | |6;imaging-darkroom;96;108;128;140;151;108;151;133;96;96 04:03:13 INFO - PROCESS | 1651 | |7;imaging-desaturate;131;151;92;116;86;147;128;90;93;122 04:03:13 INFO - PROCESS | 1651 | |8;json-parse-financial;55;52;55;73;54;56;62;86;87;56 04:03:13 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;67;73;75;76;74;67;69;65;67;81 04:03:13 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;71;86;91;96;97;103;105;87;74;105 04:03:13 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;133;143;157;139;143;152;148;139;186;138 04:03:13 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;159;164;165;163;177;163;161;162;146;175 04:03:13 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;68;66;67;66;65;67;68;68;63;65 04:03:13 INFO - PROCESS | 1651 | __end_tp_report 04:03:13 INFO - PROCESS | 1651 | __start_cc_report 04:03:13 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,877 04:03:13 INFO - PROCESS | 1651 | __end_cc_report 04:03:13 INFO - PROCESS | 1651 | __startTimestamp1480075393353__endTimestamp 04:03:13 INFO - PROCESS | 1651 | 04:03:13 INFO - PROCESS | 1651 | ------- Summary: start ------- 04:03:13 INFO - PROCESS | 1651 | Number of tests: 14 04:03:13 INFO - PROCESS | 1651 | 04:03:13 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:116.70 Median:118.00 stddev:3.86 (3.3%) stddev-sans-first:0.93 04:03:13 INFO - PROCESS | 1651 | Values: 106.0 116.0 118.0 118.0 119.0 119.0 118.0 117.0 118.0 118.0 04:03:13 INFO - PROCESS | 1651 | 04:03:13 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:119.60 Median:113.00 stddev:14.56 (12.9%) stddev-sans-first:12.20 04:03:13 INFO - PROCESS | 1651 | Values: 145.0 113.0 108.0 140.0 112.0 111.0 109.0 109.0 136.0 113.0 04:03:13 INFO - PROCESS | 1651 | 04:03:13 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:211.70 Median:199.00 stddev:30.23 (15.2%) stddev-sans-first:31.29 04:03:13 INFO - PROCESS | 1651 | Values: 193.0 194.0 239.0 282.0 233.0 192.0 193.0 193.0 197.0 201.0 04:03:13 INFO - PROCESS | 1651 | 04:03:13 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:68.70 Median:80.00 stddev:16.75 (20.9%) stddev-sans-first:16.56 04:03:13 INFO - PROCESS | 1651 | Values: 86.0 60.0 61.0 88.0 84.0 50.0 49.0 48.0 76.0 85.0 04:03:13 INFO - PROCESS | 1651 | 04:03:13 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:80.10 Median:84.00 stddev:7.75 (9.2%) stddev-sans-first:8.22 04:03:13 INFO - PROCESS | 1651 | Values: 81.0 70.0 85.0 84.0 89.0 84.0 87.0 68.0 70.0 83.0 04:03:13 INFO - PROCESS | 1651 | 04:03:13 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:90.00 stddev:1.34 (1.5%) stddev-sans-first:1.33 04:03:13 INFO - PROCESS | 1651 | Values: 91.0 89.0 91.0 88.0 90.0 88.0 92.0 89.0 90.0 89.0 04:03:13 INFO - PROCESS | 1651 | 04:03:13 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:120.70 Median:151.00 stddev:22.52 (14.9%) stddev-sans-first:22.05 04:03:13 INFO - PROCESS | 1651 | Values: 96.0 108.0 128.0 140.0 151.0 108.0 151.0 133.0 96.0 96.0 04:03:13 INFO - PROCESS | 1651 | 04:03:13 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:115.60 Median:118.50 stddev:24.20 (20.4%) stddev-sans-first:25.02 04:03:13 INFO - PROCESS | 1651 | Values: 131.0 151.0 92.0 116.0 86.0 147.0 128.0 90.0 93.0 122.0 04:03:13 INFO - PROCESS | 1651 | 04:03:13 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:59.00 stddev:13.46 (22.8%) stddev-sans-first:13.91 04:03:13 INFO - PROCESS | 1651 | Values: 55.0 52.0 55.0 73.0 54.0 56.0 62.0 86.0 87.0 56.0 04:03:13 INFO - PROCESS | 1651 | 04:03:13 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:73.50 stddev:5.17 (7.0%) stddev-sans-first:5.23 04:03:13 INFO - PROCESS | 1651 | Values: 67.0 73.0 75.0 76.0 74.0 67.0 69.0 65.0 67.0 81.0 04:03:13 INFO - PROCESS | 1651 | 04:03:13 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:91.50 Median:86.50 stddev:12.13 (14.0%) stddev-sans-first:10.35 04:03:13 INFO - PROCESS | 1651 | Values: 71.0 86.0 91.0 96.0 97.0 103.0 105.0 87.0 74.0 105.0 04:03:13 INFO - PROCESS | 1651 | 04:03:13 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:147.80 Median:145.50 stddev:15.19 (10.4%) stddev-sans-first:15.14 04:03:13 INFO - PROCESS | 1651 | Values: 133.0 143.0 157.0 139.0 143.0 152.0 148.0 139.0 186.0 138.0 04:03:13 INFO - PROCESS | 1651 | 04:03:13 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.50 Median:163.50 stddev:8.51 (5.2%) stddev-sans-first:8.87 04:03:13 INFO - PROCESS | 1651 | Values: 159.0 164.0 165.0 163.0 177.0 163.0 161.0 162.0 146.0 175.0 04:03:13 INFO - PROCESS | 1651 | 04:03:13 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:67.00 stddev:1.64 (2.4%) stddev-sans-first:1.62 04:03:13 INFO - PROCESS | 1651 | Values: 68.0 66.0 67.0 66.0 65.0 67.0 68.0 68.0 63.0 65.0 04:03:13 INFO - PROCESS | 1651 | -------- Summary: end -------- 04:03:13 INFO - PROCESS | 1651 | 04:03:14 INFO - TEST-INFO | 1651: exit 0 04:03:14 INFO - TEST-OK | kraken | took 58837ms 04:03:14 INFO - SUITE-END | took 1171s 04:03:14 INFO - Completed test suite (00:19:31) 04:03:14 INFO - javascript benchmark 04:03:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7702.382258695981, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3473.35742887566, 3588.300281445118, 3603.549556305462, 3632.6004139544652, 3681.723870709696, 4865.197242454995, 5582.358028826686, 6021.036634138539, 6042.546808989321, 6064.272287325516, 6017.338092809799, 6116.0, 6129.356417576158, 6139.539534534905, 6157.661328626924, 5359.062164121283, 5461.317335333083, 5468.343798744157, 5484.478974497416, 5555.916661250085, 5003.224500202475, 5225.947740522603, 5552.444755524456, 5561.972190139064, 5591.6365436246715, 5433.239346491494, 5443.8094666686675, 5470.206820011096, 5555.444455554432, 5580.553555715533, 5486.478784515464, 5487.2317875497465, 5514.972425137848, 5549.750261238254, 5635.3237611486475, 5387.218853266294, 5487.780488780444, 5496.450354964471, 5507.889842203133, 5571.0, 5362.0, 5910.290765108171, 5941.910871336933, 5986.311574168983, 6169.969150154266, 5524.972375138099, 6218.564700470969, 6229.6573688447115, 6251.718672659696, 6255.437010669016, 6275.6862156891975, 6319.715612797435, 6374.0, 6390.968045159791, 6402.103705481236, 6053.636781793107, 6103.694815259264, 6113.205283313186, 6119.571629985859, 6130.509559235251, 6419.1334169887405, 6528.281888992207, 6563.1796025496815, 6566.0, 6587.176602924635, 6278.215223097113, 6285.151504546828, 6289.5911765736, 6301.0, 6317.210348706411, 6236.18929539157, 6280.120783090371, 6294.087357333161, 6390.297067322591, 6393.904091438632, 6236.501079913687, 6318.336574659686, 6333.050042493663, 6336.841578960563, 6340.873182536324, 6256.74973001084, 6303.716332765037, 6315.936840631627, 6332.050192471166, 6496.967515162394, 5048.343715316985, 5095.770690318945, 5133.153029750124, 5150.175971844487, 5190.987757387349, 4561.589456948891, 4588.564086411786, 4600.125976064537, 4601.194790911577, 4617.792199351037, 6138.600990935575, 6184.0, 6220.129181914445, 6223.315435302113, 6268.623882567061, 6232.345603711636, 6437.967810160919, 6449.290578036413, 6455.354464553507, 6457.483401327882, 4658.417697787777, 6148.1699970503405, 6150.538709596798, 6196.535259855529, 6259.78090766821, 5653.802116925888, 5653.886922261532, 5660.773569057274, 5668.234788303609, 5675.262215911987, 6557.180352455943, 6625.701843417154, 6628.767993120217, 6634.402903738686, 6650.800475985729, 5163.27714120031, 6190.535709821871, 6272.18461599989, 6310.337414571404, 6313.210848643919, 6654.3012983637955, 6661.034150048313, 6662.866742665023, 6663.233728121328, 6664.233613134552, 6352.174217351715, 6352.777652782131, 6357.745690172526, 6360.014197799349, 6372.776952806629, 5240.266362709148, 5584.380529646357, 6136.335271946613, 6232.8130156095385, 6359.268684101387, 6195.597286176384, 6277.340879207617, 6290.0, 6292.024736165901, 6295.464885484785, 6257.0, 6292.0, 6292.622442653455, 6298.370162983665, 6301.0, 5337.679739215504, 5522.0, 6126.571140020156, 6198.752049917953, 6229.50163986889, 6171.722272497658, 6221.96889015552, 6254.405126692664, 6274.309825919054, 6295.055741638791, 6174.38256174379, 6189.3501182377, 6216.689165541795, 6238.157848690369, 6255.46828519581], "value": 5884.19823065289, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.9134355815953, 515.5128403658543, 534.9946500534993, 553.9889701295137, 561.8735784448497, 38743.41884871726, 41647.79176104119, 41689.58310416895, 41751.79124104375, 41799.0, 41909.58090419095, 42338.57661423385, 42409.0, 42467.0, 42525.574744252626, 9975.755455022616, 10159.238057145707, 10165.390076595428, 10181.236407269447, 10184.032516910882, 9996.500174991257, 10055.49722513875, 10056.547455364489, 10094.09153176214, 10107.241956853266, 10064.94967525165, 10080.445575493344, 10122.038406351385, 10137.18902487803, 10160.847587286196, 10047.397156170617, 10111.949440252825, 10115.797684046278, 10117.342372745748, 10130.037646423578, 9999.25005624581, 10041.447720375376, 10098.0, 10100.191984641211, 10124.493775311204, 40674.38988415176, 40913.59086409157, 41015.7949210255, 41088.589114109076, 41158.0, 3357.462805951036, 3507.8596856125723, 3570.1610121621397, 3600.0459878132315, 3753.3056384568667, 30780.53829192564, 30815.0, 31504.842475787704, 32131.678683213104, 32499.83750081258, 3326.800391976489, 3351.949720754191, 3357.227837597342, 3358.9664103358896, 3424.1610805352598, 498409.0, 499605.0, 499774.0, 500503.0, 501880.0, 34080.48879266813, 34474.65525344714, 58024.709876450346, 58378.7081064592, 58781.70609146927, 30851.69148308533, 35727.285454290766, 36152.096197595274, 36173.55305787739, 36198.8190059048, 13133.474661013644, 15728.0, 15735.0, 15748.448804292024, 15758.606034849221, 13186.934065329804, 13259.005574581945, 13303.268320242287, 13305.866941330463, 13367.064305498716, 8074.798130046795, 8098.4331096823835, 8112.067112282045, 8126.984126984127, 8170.978627671541, 3400.1499625093725, 3426.9485957710654, 3432.5194472773833, 3443.483477478398, 3444.1045328214345, 943.217129782279, 981.9459929703792, 1000.2947921715173, 1043.3322673488974, 1099.9725006874892, 31054.68945310518, 31170.22074448157, 31460.0, 31551.526727099113, 31564.210894727814, 1083.3391631105019, 1104.762665814293, 1150.6202953025481, 1155.5204590095016, 1211.4911737070274, 13494.987875909346, 15454.150021748686, 15727.05637661744, 16229.918850405673, 16301.91849040771, 716135.0, 721014.0, 728042.3597882083, 729869.0, 730554.0, 59032.11451828226, 59322.0, 59362.0, 59702.0, 59717.40282597205, 1399.4192410149674, 1404.192589261179, 1428.2073449235775, 1436.5690292912084, 1439.1365180891382, 30106.24734381658, 30424.84787576048, 31009.069727908198, 31172.68827311698, 31306.373872522876, 49787.506374808814, 50162.0, 50226.0, 50304.74847625739, 50377.74811125921, 556319.0, 595977.0, 600461.0, 601166.0, 601304.0, 27114.593281100406, 27843.303917401416, 28375.432491349646, 28429.289267768472, 28577.42845143127, 47928.76035619939, 47939.520604793506, 48237.03525929532, 48243.5175648239, 48684.75657621831, 30669.693303066684, 38954.0, 39408.6059139405, 39722.60277397189, 40698.38952415657, 32587.69649214005, 39086.804565976985, 40235.79882100571, 41184.0, 41448.37827432651, 38269.0, 38737.418938715375, 38925.02687432837, 39118.63084792018, 40652.1869562613, 32045.839770800998, 34981.47527787034, 35276.29447410986, 35486.46770298499, 35758.64241357657, 26595.468090637693, 26738.732612673622, 26801.865990670703, 27084.885511349683, 27154.456910861278], "value": 19222.562050492183, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.99274014519705, 371.09082747269196, 371.2426649634745, 378.16803033326687, 386.72542494828673, 11859.110566707499, 12097.758044839098, 12225.021998240141, 12321.383930803446, 20221.595568088647, 22695.319140425814, 22706.886465567648, 22830.0, 23199.072037118494, 23436.179733709323, 6794.116764820591, 6851.212110607269, 7162.964185179067, 7176.748813791518, 7210.387117095053, 6690.364415380531, 7129.750458733919, 7129.964350178242, 7135.143782746078, 7139.750108746195, 6835.487338449611, 6879.89680154798, 6982.162140543117, 6991.510594258404, 7086.433085353159, 6903.344182302673, 6920.100386949714, 7049.154101507801, 7099.4675399345, 7120.216776154619, 6775.728970841161, 6845.8973115403305, 6847.863042739167, 6851.588904665686, 6857.27998560149, 28285.85857070722, 29062.0082465192, 29418.264543386373, 29453.263668408246, 29560.817567297283, 2418.6492958521094, 2503.3366157968153, 2696.245051385615, 2786.3591373984095, 2838.120182743356, 14957.925210373987, 17173.570660733458, 17855.0, 17877.910610446994, 18101.54746131344, 2036.2364113457454, 2127.5213077057692, 2143.2927134045726, 2191.386411804697, 2207.1612787140944, 112750.30874536888, 116785.41607291994, 118634.0, 149585.0, 151322.0, 22370.440738981655, 23147.0, 23264.0, 23349.0, 23505.0, 36539.71142597179, 36674.8166259167, 36901.077473062855, 37322.62677373246, 37497.81251093727, 11345.773084538263, 11541.538338466535, 11659.941700291614, 11680.0, 11802.822957655642, 9363.531823408803, 9379.718608441757, 9388.812223755487, 9568.043195680377, 9640.084192001748, 10008.39949603026, 10303.536340864679, 10374.34010558307, 10376.273660843819, 10424.374537527621, 3546.716262698978, 3919.235749028912, 3976.363781794899, 3997.620226078518, 4041.6362527372676, 749.3031480722871, 846.7671390367698, 935.4808081514689, 956.1490273656451, 976.5556671714353, 16577.342265773324, 21218.0, 21284.148634054774, 21416.571668566852, 21452.570948581255, 962.6486332488591, 1004.1715584642699, 1038.200585549138, 1043.4939054558533, 1051.8790339110947, 10719.624813131502, 11344.20590558653, 11358.60244891425, 11373.20387572862, 11401.0, 549470.0, 555061.0, 555755.0, 610915.0, 650887.0, 108321.0, 132842.0, 138585.0, 145801.0, 147985.0, 798.5009369144285, 862.4351050062219, 926.9073092690677, 960.8126415348939, 987.7333120057688, 15707.528774136794, 17530.47408577745, 18473.907630461763, 19243.230270789572, 19275.22899084021, 57470.71264643817, 58279.12581311199, 58774.0, 58926.410735893805, 59283.703581481815, 83010.92472688231, 83753.58123209345, 84000.15999839923, 85094.57452712697, 86829.56585217033, 7831.921680783119, 8143.51138931666, 8208.507489550646, 8406.117357677356, 8528.530930798866, 11559.786222446523, 12369.30587033954, 12935.676608084874, 13149.474021039052, 13431.529896453969, 8992.315874230831, 10632.670916135483, 11339.319640821577, 11662.766744665229, 11708.004819590431, 9538.904610953801, 10830.916908309106, 10945.781084378426, 11100.944495277796, 11197.776044479227, 11316.37759923212, 11377.975982161644, 11563.942180289045, 11873.82189267179, 11910.821337679768, 8328.042275138436, 8364.581770911309, 8452.873206902024, 8475.533845638549, 8484.872726908978, 11183.32900026001, 11449.0, 11568.07455403549, 11795.410229488663, 11805.468753905923], "value": 11148.327038292757, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1570.9057456552612, 1606.068480281437, 1843.1245158549682, 1939.8836069835802, 1953.4042117154263, 8330.0, 8570.571471426434, 8687.131286871318, 9215.400998935072, 9238.0, 8781.868271975909, 9059.50172740499, 9059.818803623924, 9125.178733913946, 9199.724008279762, 2416.3717433467295, 2451.9946821803064, 2452.448199155194, 2454.619533972237, 2464.531738969599, 2430.5989511730545, 2434.075051480444, 2456.263121063683, 2460.8646524441147, 2471.876406179693, 2446.926592202237, 2448.6694296270043, 2452.877356132195, 2454.2759885833634, 2476.405662640971, 2426.648136020267, 2428.101602407103, 2428.562858685436, 2437.7196622388387, 2439.316991242446, 2085.9478513037143, 2424.860315651641, 2430.501747141832, 2439.9996001639242, 2443.1082654831034, 9088.318376121817, 9130.817383652357, 9185.67850125249, 9198.218151457135, 9201.03389144137, 5422.75597598109, 5526.668399896019, 5594.0769772987405, 5607.859803504904, 5622.971885140548, 3552.828915756211, 3684.613115622875, 3728.272986767581, 3746.775193488372, 3748.475213470116, 1086.8261078227445, 1103.9668809935715, 1107.1143085531542, 1124.3534967393782, 1130.7512347283584, 11744.0, 11847.170698051139, 12026.218295810831, 12041.759164816742, 12056.336901470415, 4783.832565860178, 4794.640401969867, 4841.9031619367415, 4911.8457162566765, 5008.29883816266, 10235.846462303072, 10241.436720980417, 10261.973802619828, 10360.948195258976, 10370.27408081427, 5498.752556134984, 5536.4186760390385, 5554.611177217636, 5595.244641973363, 5611.719414029282, 4570.245909424974, 4731.455882573479, 4965.106280869478, 5077.31456253401, 5083.542481176712, 3607.1703508193264, 4138.8791388791415, 4142.150859073882, 4240.0, 4278.144371125787, 1319.64369620202, 1321.3723481346476, 1329.4283458112911, 1336.5121730568274, 1339.1228745171927, 796.8207053560762, 838.8322335532918, 926.4719110107309, 929.5166513412987, 946.2099147212017, 5847.38602446737, 5971.014782560916, 6058.666773327509, 6072.666003369857, 6299.496040316763, 441.7548260715334, 442.0, 449.7166784925476, 454.470541818784, 459.04976698235004, 3965.960340396559, 4081.2041651878194, 4124.443200167999, 4151.045259590251, 4194.2450358935685, 10894.836577451344, 11239.381833999045, 11248.606298779503, 11260.774784504263, 11273.605423810128, 11159.163062770162, 11164.553417863195, 11177.944110279397, 11189.328640281608, 11195.888041119648, 8318.958405208055, 8496.53269070207, 8648.956755216184, 8685.044645089036, 8713.390062695677, 5724.542036636975, 5838.474537291664, 5950.9404905952115, 6036.245469316335, 6060.0, 10085.89914100879, 10173.186145108522, 10274.280800344202, 10396.844047339448, 10437.895621043692, 9478.194353480032, 10397.32417392867, 10415.791684166426, 10528.0, 10561.471926403496, 7257.419406447577, 7454.030975973089, 7625.428092892944, 7821.687132514636, 8013.4791238569305, 8497.362697797813, 8608.268297194809, 8728.301735861243, 8735.0, 8735.519546424754, 6577.375149360803, 7598.848023039619, 7766.184550622103, 7816.10114836801, 7843.294103530709, 6704.99425086241, 7499.5875226863045, 7659.540427574363, 7662.195469475625, 7700.306972372513, 6829.897551536632, 7101.289871013064, 7101.644917754195, 7105.2894710528535, 7113.679884405317, 5029.698218106925, 5032.345795046621, 5161.66449180802, 5286.074936885952, 5332.68003919766, 4453.465584129925, 5223.268742376146, 5421.0, 5421.783128674809, 5509.201165831012], "value": 4646.117216571844, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [282.98868045278175, 302.2127358231806, 318.0839183152521, 321.6172754422236, 322.9015150379136, 5638.859028524289, 5696.629719068258, 5698.601097923147, 5712.200291959129, 5739.426057394258, 4394.560543945612, 4996.45251871171, 5555.416681248471, 5571.944280557193, 5691.032524470839, 3268.2319654881085, 3633.509476220716, 3815.771053736785, 3951.0837243936667, 4211.936820947688, 3638.7089032877307, 4140.50313962325, 4304.655627549789, 4317.179735850178, 4395.351743096339, 3739.383001804699, 3855.1904100139004, 4200.411942328076, 4296.183725092238, 4308.82764689412, 3987.521497420328, 4241.36379543068, 4243.384709217177, 4353.869383918472, 4362.367456718774, 3105.9534106988413, 3270.4628824452707, 3454.3091381723757, 3633.418653015505, 3634.528016153462, 5504.614676972614, 5508.834734957958, 5516.365617953947, 5545.417731138253, 5551.417101204356, 4531.501534831166, 4710.599599034086, 4868.02639472107, 4870.780814863304, 4886.902261954741, 4721.457032441244, 4971.688165003291, 5192.273081768555, 5773.855653608652, 5952.970235148797, 3863.497745293094, 4020.3165461871554, 4026.5973402659793, 4031.879043628696, 4032.528127233568, 4791.377120974286, 5597.0, 6118.235220597426, 6267.71795269214, 6310.305866354697, 4054.2904991626347, 4067.6745860331102, 4123.587641235912, 4130.6282434581035, 4140.358244472111, 4164.292070348048, 4165.0, 4168.0, 4175.498940127143, 4190.622843943998, 3744.8689295874515, 4139.151473947833, 4459.598636122764, 4531.071130418256, 4627.953720462752, 4757.881052973703, 5123.5644970177955, 5697.373288938214, 5986.431289027535, 5989.460948514655, 1141.3323205924628, 1288.7229245712217, 1333.4932725564224, 1395.4976208564908, 1406.6847497589786, 804.1717031457608, 810.4043528006911, 837.9023479242212, 863.8531449653574, 871.6861929705301, 4983.750812459362, 5446.564274858002, 5455.0, 5644.0, 5704.828855134353, 5409.890972350659, 5628.690422026746, 5852.0, 6031.93317613207, 6207.720652570645, 4929.334539837076, 5258.1849813279005, 5517.779288828402, 5575.414581468968, 5630.40880707528, 3040.376722771871, 3119.53207018949, 3137.897608602242, 3255.453659511713, 3271.6237632672546, 5295.841124766263, 5897.469227769521, 5995.670238136944, 6360.840978975562, 6508.772192973223, 5242.977619364264, 5401.702906340189, 5761.596688231783, 6239.032949892774, 6319.52603554726, 5840.707964601753, 6374.681265936777, 6383.521235907233, 6444.226692796894, 6472.352764723584, 5770.971145144247, 6100.285373008968, 6197.225346831646, 6203.534734894991, 6260.40526150015, 5353.116735738559, 6019.488343490677, 6167.321594624677, 6185.474234689922, 6225.8132256032395, 5803.274590676166, 6206.286277078013, 6371.0, 6428.228612566522, 6507.577007494498, 4069.7558146511305, 4091.468109145792, 4094.61101195386, 4100.630943215125, 4104.507459104927, 5930.318013428511, 6238.313785483684, 6282.183316168869, 6282.497400207881, 6303.281064171977, 5647.915281270702, 5989.263113696979, 6049.606775559574, 6101.9931711267145, 6155.0, 5371.758270877898, 5969.970150149222, 6069.905882470541, 6123.969380153071, 6130.386961303834, 5006.4242612098615, 5238.319018527567, 5653.491185793298, 5667.4615911488345, 5902.1736956826935, 5947.464728174485, 6011.248593925759, 6030.281369809583, 6061.090836374403, 6144.53915956314, 5929.673867937132, 5937.139114828412, 5938.001309338756, 5954.821355359346, 5991.370906054802], "value": 4294.147466958071, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.5248409807479, 718.8849784034555, 763.0080894836711, 822.9053658829233, 852.4586887326548, 12850.871491285096, 16654.916725416388, 17127.97232166071, 17136.48590542286, 17149.82850171498, 14790.112593244412, 15262.542123736277, 15510.0, 15517.379304827822, 15536.53390398287, 721.3615949884364, 743.2938708227178, 801.4389927051059, 876.8772371867959, 892.3485855325616, 888.9599968001455, 889.1686273334453, 896.5920506169709, 912.3522299167599, 913.3606475467167, 960.4669408478292, 962.9518524073803, 963.3449254506933, 965.0012237334361, 966.912977831995, 855.2174760094537, 947.1239103828999, 960.99095949253, 961.4904100826573, 964.7105868239535, 689.899964505149, 692.9688164032631, 699.1854489519703, 711.5695004522292, 711.7615598774416, 16130.919345403197, 16187.757183642254, 16201.675966480605, 16420.83579164205, 16430.507084787358, 13655.658608534766, 13703.246321452316, 13726.862731372657, 14092.506762263374, 14142.858571414257, 13147.211167329991, 13603.387847546885, 13659.180449173082, 13981.161130332111, 14030.92984535081, 4680.531946805327, 4693.718376897397, 4719.150552900476, 4733.5266473352385, 4733.573978341966, 121583.0, 123446.0, 125676.0, 125764.0, 129555.7044429553, 11023.503942322615, 11239.04468120219, 11309.60416385439, 11343.716407089723, 11471.885281147082, 21131.04910279041, 21307.46731331698, 22073.44816379571, 22075.227367042076, 22944.541109177724, 8387.077421483631, 8444.493330400195, 8483.0, 8536.317094632415, 8631.395802293904, 4619.86140415788, 4628.190066738307, 4628.514006029386, 4715.104130215248, 4775.0927323809055, 3633.527641406653, 3773.509443772292, 3778.3576791945434, 3783.3379158647676, 3787.958311464314, 667.286003975751, 680.1532092544813, 682.1405029662661, 692.5671455340412, 703.1421665567999, 564.7712676366078, 582.5791014609104, 663.0882536512296, 672.9293424190488, 698.9755358562426, 8349.749507514784, 9521.52416375463, 9593.616255349847, 9734.853977190347, 10094.192464602815, 563.937966823649, 578.6643746626946, 618.3352895637208, 689.2174654647512, 705.6894966214849, 4829.6860704054125, 4898.0, 4943.629227807857, 4950.950490495049, 4969.478204788517, 161721.0, 171297.43053854202, 171745.0, 173113.13443432702, 174535.12732436258, 137192.0, 143745.28127359296, 150976.0, 154207.0, 157891.21054394654, 100527.0, 122709.0, 123326.0, 129273.70726292617, 130005.0, 9876.0, 10396.116330112025, 10441.895581044093, 10474.633387831389, 10750.892491074988, 64832.0, 67275.66362168158, 67626.66186669035, 68840.3115968854, 68921.65539172271, 80133.5993320053, 96250.0, 98741.0, 101951.49024254832, 102384.97615023755, 10087.546060427152, 11179.72050698739, 12055.397230138282, 13324.200547967153, 13449.865501345254, 15322.693546129238, 15716.214189290262, 15734.0, 15800.0, 15800.446984355493, 13882.722345552831, 13909.513167039106, 14267.714645707236, 14342.569722908329, 14715.632109197355, 11999.700007499883, 14305.21321327337, 14762.926185369004, 15167.393304267705, 15485.380584776933, 14370.856291436952, 14894.180820055, 15085.47200847965, 15172.0, 15393.76909346383, 11190.0, 11552.480138393961, 12021.398930053287, 12125.454354553889, 12240.632781016584, 8525.573721314033, 11566.942165289456, 11903.583374581847, 12180.634580962585, 12228.510859565873], "value": 8299.10093574163, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1526.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 118.0, 118.0, 119.0, 119.0, 118.0, 117.0, 118.0, 118.0], "value": 116.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 113.0, 108.0, 140.0, 112.0, 111.0, 109.0, 109.0, 136.0, 113.0], "value": 119.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 194.0, 239.0, 282.0, 233.0, 192.0, 193.0, 193.0, 197.0, 201.0], "value": 211.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 60.0, 61.0, 88.0, 84.0, 50.0, 49.0, 48.0, 76.0, 85.0], "value": 68.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 70.0, 85.0, 84.0, 89.0, 84.0, 87.0, 68.0, 70.0, 83.0], "value": 80.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 91.0, 88.0, 90.0, 88.0, 92.0, 89.0, 90.0, 89.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 108.0, 128.0, 140.0, 151.0, 108.0, 151.0, 133.0, 96.0, 96.0], "value": 120.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 151.0, 92.0, 116.0, 86.0, 147.0, 128.0, 90.0, 93.0, 122.0], "value": 115.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 55.0, 73.0, 54.0, 56.0, 62.0, 86.0, 87.0, 56.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 75.0, 76.0, 74.0, 67.0, 69.0, 65.0, 67.0, 81.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 86.0, 91.0, 96.0, 97.0, 103.0, 105.0, 87.0, 74.0, 105.0], "value": 91.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 143.0, 157.0, 139.0, 143.0, 152.0, 148.0, 139.0, 186.0, 138.0], "value": 147.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 164.0, 165.0, 163.0, 177.0, 163.0, 161.0, 162.0, 146.0, 175.0], "value": 163.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 66.0, 65.0, 67.0, 68.0, 68.0, 63.0, 65.0], "value": 66.3, "unit": "score"}]}]} 04:03:14 INFO - Return code: 0 04:03:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:03:14 ERROR - # TBPL SUCCESS # 04:03:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:03:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:03:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:03:14 INFO - 2016-11-25 04:03:14.662 screenresolution[1656:10390] starting screenresolution argv=screenresolution get 04:03:14 INFO - 2016-11-25 04:03:14.665 screenresolution[1656:10390] Display 0: 1600x1200x32@60 04:03:14 INFO - 2016-11-25 04:03:14.671 screenresolution[1657:10393] starting screenresolution argv=screenresolution list 04:03:14 INFO - Available Modes on Display 0 04:03:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:03:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:03:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:03:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:03:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:03:14 INFO - Intel Iris: 04:03:14 INFO - Chipset Model: Intel Iris 04:03:14 INFO - Type: GPU 04:03:14 INFO - Bus: Built-In 04:03:14 INFO - VRAM (Dynamic, Max): 1536 MB 04:03:14 INFO - Vendor: Intel (0x8086) 04:03:14 INFO - Device ID: 0x0a2e 04:03:14 INFO - Revision ID: 0x0009 04:03:14 INFO - Displays: 04:03:14 INFO - Display: 04:03:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:03:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:03:14 INFO - Display Serial Number: 1600x1200 60 04:03:14 INFO - Main Display: Yes 04:03:14 INFO - Mirror: Off 04:03:14 INFO - Online: Yes 04:03:14 INFO - Rotation: Supported 04:03:14 INFO - Return code: 0 04:03:14 INFO - Running post-action listener: _resource_record_post_action 04:03:14 INFO - [mozharness: 2016-11-25 12:03:14.905847Z] Finished run-tests step (success) 04:03:14 INFO - Running post-run listener: _resource_record_post_run 04:03:14 INFO - Total resource usage - Wall time: 1194s; CPU: 54.0%; Read bytes: 39216128; Write bytes: 495833088; Read time: 385; Write time: 1737 04:03:14 INFO - TinderboxPrint: CPU usage
54.2% 04:03:14 INFO - TinderboxPrint: I/O read bytes / time
39,216,128 / 385 04:03:14 INFO - TinderboxPrint: I/O write bytes / time
495,833,088 / 1,737 04:03:14 INFO - TinderboxPrint: CPU idle
2,188.2 (45.8%) 04:03:14 INFO - TinderboxPrint: CPU system
969.5 (20.3%) 04:03:14 INFO - TinderboxPrint: CPU user
1,620.0 (33.9%) 04:03:14 INFO - TinderboxPrint: Swap in / out
234,041,344 / 0 04:03:14 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 188187136; Write bytes: 187471360; Read time: 17728; Write time: 390 04:03:14 INFO - run-tests - Wall time: 1173s; CPU: 54.0%; Read bytes: 38282240; Write bytes: 298199552; Read time: 371; Write time: 1312 04:03:15 INFO - Running post-run listener: _upload_blobber_files 04:03:15 INFO - Blob upload gear active. 04:03:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:03:15 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 04:03:15 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'] 04:03:15 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 04:03:15 INFO - (blobuploader) - INFO - Open directory for files ... 04:03:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:03:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:03:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:17 INFO - (blobuploader) - INFO - Done attempting. 04:03:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:03:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:03:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:18 INFO - (blobuploader) - INFO - Done attempting. 04:03:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:03:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:03:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:20 INFO - (blobuploader) - INFO - Done attempting. 04:03:20 INFO - (blobuploader) - INFO - Iteration through files over. 04:03:20 INFO - Return code: 0 04:03:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:03:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:03:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ac48816b7fd10dfe4109e7f90fc1c80568ae609975c8ba74f3537b438b68337bf445b4d1671f17a6d983dd48b6242c08a088f42f7a70be9fbf96c36bda19a599", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1bf996aaebb356e0c9bd99f7ed4ba1309e8dfeab360b56bdabdc9f2c5128d3286998e8b7bcf03bbb8ee1fb30f2e85c8d5b1de6e54c8d3dea91671377c345af9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9c75aa4b1d7273d13dccb935ada8fdd18b4c554f84cec3efe746a4f5dc12c8cc37556fbbe73cac6f8be3dafb18eeb7edd29f0af6263e94a11cb7bc105896511e"} 04:03:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:03:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:03:20 INFO - Contents: 04:03:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ac48816b7fd10dfe4109e7f90fc1c80568ae609975c8ba74f3537b438b68337bf445b4d1671f17a6d983dd48b6242c08a088f42f7a70be9fbf96c36bda19a599", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1bf996aaebb356e0c9bd99f7ed4ba1309e8dfeab360b56bdabdc9f2c5128d3286998e8b7bcf03bbb8ee1fb30f2e85c8d5b1de6e54c8d3dea91671377c345af9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9c75aa4b1d7273d13dccb935ada8fdd18b4c554f84cec3efe746a4f5dc12c8cc37556fbbe73cac6f8be3dafb18eeb7edd29f0af6263e94a11cb7bc105896511e"} 04:03:20 INFO - Running post-run listener: copy_logs_to_upload_dir 04:03:20 INFO - Copying logs to upload dir... 04:03:20 INFO - mkdir: /builds/slave/test/build/upload/logs 04:03:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.868597 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-11-25 04:03:20.409867) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 04:03:20.413966) ========= 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.IfinvK0NJH/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.kXZRlfX4Em/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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/ac48816b7fd10dfe4109e7f90fc1c80568ae609975c8ba74f3537b438b68337bf445b4d1671f17a6d983dd48b6242c08a088f42f7a70be9fbf96c36bda19a599", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1bf996aaebb356e0c9bd99f7ed4ba1309e8dfeab360b56bdabdc9f2c5128d3286998e8b7bcf03bbb8ee1fb30f2e85c8d5b1de6e54c8d3dea91671377c345af9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9c75aa4b1d7273d13dccb935ada8fdd18b4c554f84cec3efe746a4f5dc12c8cc37556fbbe73cac6f8be3dafb18eeb7edd29f0af6263e94a11cb7bc105896511e"} build_url:https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010985 build_url: 'https://queue.taskcluster.net/v1/task/P5X-NJzlR5uXtutx-48FPw/artifacts/public/build/firefox-51.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ac48816b7fd10dfe4109e7f90fc1c80568ae609975c8ba74f3537b438b68337bf445b4d1671f17a6d983dd48b6242c08a088f42f7a70be9fbf96c36bda19a599", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1bf996aaebb356e0c9bd99f7ed4ba1309e8dfeab360b56bdabdc9f2c5128d3286998e8b7bcf03bbb8ee1fb30f2e85c8d5b1de6e54c8d3dea91671377c345af9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9c75aa4b1d7273d13dccb935ada8fdd18b4c554f84cec3efe746a4f5dc12c8cc37556fbbe73cac6f8be3dafb18eeb7edd29f0af6263e94a11cb7bc105896511e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 04:03:20.448638) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 04:03:20.448974) ========= 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.IfinvK0NJH/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.kXZRlfX4Em/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.005059 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 04:03:20.498928) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 04:03:20.499482) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 04:03:20.500011) ========= ========= Total master_lag: 0.18 =========