builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0200 starttime: 1473375573.62 results: success (0) buildid: 20160908150426 builduid: 5ee3f8e477ee478da254f7294eccebca revision: 5d854c8d076533b9426bc3040979ccf8e0b2404b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 15:59:33.620322) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 15:59:33.620814) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 15:59:33.621099) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 15:59:33.646238) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 15:59:33.646604) ========= 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.KSflfz5VIR/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.0Uy9sxkGEF/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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-09-08 15:59:33-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2016-09-08 15:59:33 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.165306 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 15:59:33.834729) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 15:59:33.835094) ========= 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.KSflfz5VIR/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.0Uy9sxkGEF/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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.028181 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 15:59:33.885976) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 15:59:33.886462) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5d854c8d076533b9426bc3040979ccf8e0b2404b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5d854c8d076533b9426bc3040979ccf8e0b2404b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KSflfz5VIR/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.0Uy9sxkGEF/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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-09-08 15:59:33,967 truncating revision to first 12 chars 2016-09-08 15:59:33,968 Setting DEBUG logging. 2016-09-08 15:59:33,968 attempt 1/10 2016-09-08 15:59:33,968 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5d854c8d0765?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-08 15:59:34,609 unpacking tar archive at: mozilla-central-5d854c8d0765/testing/mozharness/ program finished with exit code 0 elapsedTime=0.913398 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 15:59:34.825714) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 15:59:34.826253) ========= script_repo_revision: 5d854c8d076533b9426bc3040979ccf8e0b2404b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 15:59:34.826884) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 15:59:34.827262) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 15:59:34.846421) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-09-08 15:59:34.846688) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KSflfz5VIR/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.0Uy9sxkGEF/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:59:35 INFO - MultiFileLogger online at 20160908 15:59:35 in /builds/slave/test 15:59:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:59:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:59:35 INFO - {'append_to_log': False, 15:59:35 INFO - 'base_work_dir': '/builds/slave/test', 15:59:35 INFO - 'blob_upload_branch': 'Firefox', 15:59:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:59:35 INFO - 'branch': 'Firefox', 15:59:35 INFO - 'buildbot_json_path': 'buildprops.json', 15:59:35 INFO - 'config_files': ('talos/mac_config.py',), 15:59:35 INFO - 'default_actions': ('clobber', 15:59:35 INFO - 'read-buildbot-config', 15:59:35 INFO - 'download-and-extract', 15:59:35 INFO - 'populate-webroot', 15:59:35 INFO - 'create-virtualenv', 15:59:35 INFO - 'install', 15:59:35 INFO - 'run-tests'), 15:59:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:59:35 INFO - 'download_minidump_stackwalk': True, 15:59:35 INFO - 'download_symbols': 'ondemand', 15:59:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:59:35 INFO - 'tooltool.py': '/tools/tooltool.py', 15:59:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:59:35 INFO - '/tools/misc-python/virtualenv.py')}, 15:59:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:59:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:59:35 INFO - 'installer_path': 'installer.exe', 15:59:35 INFO - 'log_level': 'info', 15:59:35 INFO - 'log_name': 'talos', 15:59:35 INFO - 'log_to_console': True, 15:59:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:59:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:59:35 INFO - 'opt_config_files': (), 15:59:35 INFO - 'pip_index': False, 15:59:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:59:35 INFO - 'cmd': ('bash', 15:59:35 INFO - '-c', 15:59:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:59:35 INFO - 'enabled': True, 15:59:35 INFO - 'halt_on_failure': False, 15:59:35 INFO - 'name': 'check_screen_resolution'},), 15:59:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:59:35 INFO - 'cmd': ('bash', 15:59:35 INFO - '-c', 15:59:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:59:35 INFO - 'enabled': True, 15:59:35 INFO - 'halt_on_failure': False, 15:59:35 INFO - 'name': 'check_screen_resolution'},), 15:59:35 INFO - 'run_cmd_checks_enabled': True, 15:59:35 INFO - 'sps_profile': False, 15:59:35 INFO - 'sps_profile_interval': 0, 15:59:35 INFO - 'suite': 'dromaeojs-e10s', 15:59:35 INFO - 'system_bits': '32', 15:59:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:59:35 INFO - 'title': 't-yosemite-r7-0200', 15:59:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:59:35 INFO - 'use_talos_json': True, 15:59:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:59:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:59:35 INFO - 'work_dir': 'build'} 15:59:35 INFO - [mozharness: 2016-09-08 22:59:35.040594Z] Running clobber step. 15:59:35 INFO - Running pre-action listener: _resource_record_pre_action 15:59:35 INFO - Running main action method: clobber 15:59:35 INFO - rmtree: /builds/slave/test/build 15:59:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:59:37 INFO - Running post-action listener: _resource_record_post_action 15:59:37 INFO - [mozharness: 2016-09-08 22:59:37.368307Z] Finished clobber step (success) 15:59:37 INFO - [mozharness: 2016-09-08 22:59:37.368465Z] Running read-buildbot-config step. 15:59:37 INFO - Running pre-action listener: _resource_record_pre_action 15:59:37 INFO - Running main action method: read_buildbot_config 15:59:37 INFO - Using buildbot properties: 15:59:37 INFO - { 15:59:37 INFO - "project": "", 15:59:37 INFO - "product": "firefox", 15:59:37 INFO - "script_repo_revision": "production", 15:59:37 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 15:59:37 INFO - "repository": "", 15:59:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 15:59:37 INFO - "buildid": "20160908150426", 15:59:37 INFO - "pgo_build": "False", 15:59:37 INFO - "basedir": "/builds/slave/test", 15:59:37 INFO - "buildnumber": 31, 15:59:37 INFO - "slavename": "t-yosemite-r7-0200", 15:59:37 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:59:37 INFO - "platform": "yosemite_r7", 15:59:37 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:59:37 INFO - "branch": "mozilla-central", 15:59:37 INFO - "repo_path": "mozilla-central", 15:59:37 INFO - "revision": "5d854c8d076533b9426bc3040979ccf8e0b2404b", 15:59:37 INFO - "stage_platform": "macosx64", 15:59:37 INFO - "builduid": "5ee3f8e477ee478da254f7294eccebca", 15:59:37 INFO - "slavebuilddir": "test" 15:59:37 INFO - } 15:59:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/VkPgQpKjR-672wOStJoDfw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 15:59:37 INFO - Running post-action listener: _resource_record_post_action 15:59:37 INFO - [mozharness: 2016-09-08 22:59:37.371700Z] Finished read-buildbot-config step (success) 15:59:37 INFO - [mozharness: 2016-09-08 22:59:37.371824Z] Running download-and-extract step. 15:59:37 INFO - Running pre-action listener: _resource_record_pre_action 15:59:37 INFO - Running main action method: download_and_extract 15:59:37 INFO - mkdir: /builds/slave/test/build/tests 15:59:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:37 INFO - trying https://queue.taskcluster.net/v1/task/VkPgQpKjR-672wOStJoDfw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 15:59:37 INFO - Downloading https://queue.taskcluster.net/v1/task/VkPgQpKjR-672wOStJoDfw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:59:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VkPgQpKjR-672wOStJoDfw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 15:59:37 INFO - Downloaded 1199 bytes. 15:59:37 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:59:37 INFO - Using the following test package requirements: 15:59:37 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:59:37 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:59:37 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 15:59:37 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:59:37 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 15:59:37 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:59:37 INFO - u'jsshell-mac.zip'], 15:59:37 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:59:37 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 15:59:37 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:59:37 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:59:37 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 15:59:37 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:59:37 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 15:59:37 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:59:37 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 15:59:37 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:59:37 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 15:59:37 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:59:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VkPgQpKjR-672wOStJoDfw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:59:38 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:59:45 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:59:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VkPgQpKjR-672wOStJoDfw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:59:46 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:59:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VkPgQpKjR-672wOStJoDfw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 15:59:53 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:59:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:54 INFO - trying https://queue.taskcluster.net/v1/task/VkPgQpKjR-672wOStJoDfw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:59:54 INFO - Downloading https://queue.taskcluster.net/v1/task/VkPgQpKjR-672wOStJoDfw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 15:59:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VkPgQpKjR-672wOStJoDfw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 15:59:57 INFO - Downloaded 111517425 bytes. 15:59:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VkPgQpKjR-672wOStJoDfw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:59:57 INFO - mkdir: /builds/slave/test/properties 15:59:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:59:57 INFO - Writing to file /builds/slave/test/properties/build_url 15:59:57 INFO - Contents: 15:59:57 INFO - build_url:https://queue.taskcluster.net/v1/task/VkPgQpKjR-672wOStJoDfw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:59:57 INFO - Running post-action listener: _resource_record_post_action 15:59:57 INFO - Running post-action listener: set_extra_try_arguments 15:59:57 INFO - [mozharness: 2016-09-08 22:59:57.710260Z] Finished download-and-extract step (success) 15:59:57 INFO - [mozharness: 2016-09-08 22:59:57.710371Z] Running populate-webroot step. 15:59:57 INFO - Running pre-action listener: _resource_record_pre_action 15:59:57 INFO - Running main action method: populate_webroot 15:59:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:59:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:59:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:59:57 INFO - '--tppagecycles', 15:59:57 INFO - '7'], 15:59:57 INFO - 'tests': ['tsvgm']}}, 15:59:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:59:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:59:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:59:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:59:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:59:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:59:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:57 INFO - 'talos_options': ['--disable-e10s'], 15:59:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:59:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:59:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:57 INFO - 'talos_options': ['--disable-e10s'], 15:59:57 INFO - 'tests': ['damp', 'tps']}, 15:59:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:57 INFO - 'tests': ['damp', 'tps']}, 15:59:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:59:57 INFO - 'tests': ['dromaeo_dom']}, 15:59:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:59:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:59:57 INFO - 'tests': ['basic_compositor_video']}, 15:59:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:59:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:59:57 INFO - 'tests': ['a11yr', 15:59:57 INFO - 'ts_paint', 15:59:57 INFO - 'tpaint', 15:59:57 INFO - 'sessionrestore', 15:59:57 INFO - 'sessionrestore_no_auto_restore', 15:59:57 INFO - 'tabpaint']}, 15:59:57 INFO - 'other-e10s': {'tests': ['a11yr', 15:59:57 INFO - 'ts_paint', 15:59:57 INFO - 'tpaint', 15:59:57 INFO - 'sessionrestore', 15:59:57 INFO - 'sessionrestore_no_auto_restore', 15:59:57 INFO - 'tabpaint']}, 15:59:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:59:57 INFO - 'ts_paint', 15:59:57 INFO - 'tpaint', 15:59:57 INFO - 'sessionrestore', 15:59:57 INFO - 'sessionrestore_no_auto_restore', 15:59:57 INFO - 'tabpaint']}, 15:59:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:59:57 INFO - 'ts_paint', 15:59:57 INFO - 'tpaint', 15:59:57 INFO - 'sessionrestore', 15:59:57 INFO - 'sessionrestore_no_auto_restore', 15:59:57 INFO - 'tabpaint']}, 15:59:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:59:57 INFO - 'tests': ['a11yr', 15:59:57 INFO - 'ts_paint', 15:59:57 INFO - 'tpaint', 15:59:57 INFO - 'sessionrestore', 15:59:57 INFO - 'sessionrestore_no_auto_restore', 15:59:57 INFO - 'tabpaint']}, 15:59:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:59:57 INFO - 'tests': ['a11yr', 15:59:57 INFO - 'ts_paint', 15:59:57 INFO - 'tpaint', 15:59:57 INFO - 'sessionrestore', 15:59:57 INFO - 'sessionrestore_no_auto_restore', 15:59:57 INFO - 'tabpaint']}, 15:59:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:59:57 INFO - 'tests': ['tsvgx', 15:59:57 INFO - 'tsvgr_opacity', 15:59:57 INFO - 'tart', 15:59:57 INFO - 'tscrollx', 15:59:57 INFO - 'cart']}, 15:59:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:59:57 INFO - 'tsvgr_opacity', 15:59:57 INFO - 'tart', 15:59:57 INFO - 'tscrollx', 15:59:57 INFO - 'cart']}, 15:59:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:57 INFO - 'talos_options': ['--disable-e10s'], 15:59:57 INFO - 'tests': ['tp5o']}, 15:59:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:57 INFO - 'tests': ['tp5o']}, 15:59:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:57 INFO - 'talos_options': ['--disable-e10s', 15:59:57 INFO - '--xperf_path', 15:59:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:59:57 INFO - 'tests': ['tp5n']}, 15:59:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:57 INFO - 'talos_options': ['--xperf_path', 15:59:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:59:57 INFO - 'tests': ['tp5n']}}, 15:59:57 INFO - 'talos.zip': {'path': '', 15:59:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:59:57 INFO - Running post-action listener: _resource_record_post_action 15:59:57 INFO - [mozharness: 2016-09-08 22:59:57.729161Z] Finished populate-webroot step (success) 15:59:57 INFO - [mozharness: 2016-09-08 22:59:57.729293Z] Running create-virtualenv step. 15:59:57 INFO - Running pre-action listener: _resource_record_pre_action 15:59:57 INFO - Running main action method: create_virtualenv 15:59:57 INFO - Creating virtualenv /builds/slave/test/build/venv 15:59:57 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:59:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:59:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:59:57 INFO - Using real prefix '/tools/python27' 15:59:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:59:58 INFO - Installing distribute.............................................................................................................................................................................................done. 16:00:01 INFO - Installing pip.................done. 16:00:01 INFO - Return code: 0 16:00:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:00:01 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')]} 16:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:01 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')]} 16:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:01 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 0x1010c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101157618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a885af4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101026d30>, '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 0x100d8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10101eb20>, '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.KSflfz5VIR/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.0Uy9sxkGEF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 16:00:01 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 16:00:01 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 16:00:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KSflfz5VIR/Render', 16:00:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:01 INFO - 'HOME': '/Users/cltbld', 16:00:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:01 INFO - 'LOGNAME': 'cltbld', 16:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:01 INFO - 'MOZ_NO_REMOTE': '1', 16:00:01 INFO - 'NO_EM_RESTART': '1', 16:00:01 INFO - 'PAGER': '/bin/cat', 16:00:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:01 INFO - 'PWD': '/builds/slave/test', 16:00:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:01 INFO - 'SHELL': '/bin/bash', 16:00:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Uy9sxkGEF/Listeners', 16:00:01 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:00:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:01 INFO - 'USER': 'cltbld', 16:00:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:01 INFO - 'XPC_FLAGS': '0x0', 16:00:01 INFO - 'XPC_SERVICE_NAME': '0', 16:00:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:01 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 16:00:01 INFO - Cleaning up... 16:00:01 INFO - Return code: 0 16:00:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:00:01 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')]} 16:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:01 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')]} 16:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:01 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 0x1010c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101157618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a885af4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101026d30>, '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 0x100d8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10101eb20>, '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.KSflfz5VIR/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.0Uy9sxkGEF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 16:00:01 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 16:00:01 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 16:00:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KSflfz5VIR/Render', 16:00:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:01 INFO - 'HOME': '/Users/cltbld', 16:00:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:01 INFO - 'LOGNAME': 'cltbld', 16:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:01 INFO - 'MOZ_NO_REMOTE': '1', 16:00:01 INFO - 'NO_EM_RESTART': '1', 16:00:01 INFO - 'PAGER': '/bin/cat', 16:00:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:01 INFO - 'PWD': '/builds/slave/test', 16:00:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:01 INFO - 'SHELL': '/bin/bash', 16:00:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Uy9sxkGEF/Listeners', 16:00:01 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:00:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:01 INFO - 'USER': 'cltbld', 16:00:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:01 INFO - 'XPC_FLAGS': '0x0', 16:00:01 INFO - 'XPC_SERVICE_NAME': '0', 16:00:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:01 INFO - Downloading/unpacking psutil>=3.1.1 16:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:00:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:00:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:00:05 INFO - Installing collected packages: psutil 16:00:05 INFO - Running setup.py install for psutil 16:00:05 INFO - building 'psutil._psutil_osx' extension 16:00:05 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 16:00:05 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 16:00:05 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 16:00:06 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 16:00:06 INFO - building 'psutil._psutil_posix' extension 16:00:06 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 16:00:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:00:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:00:06 INFO - ^ 16:00:06 INFO - 1 warning generated. 16:00:06 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 16:00:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:00:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:00:06 INFO - Successfully installed psutil 16:00:06 INFO - Cleaning up... 16:00:06 INFO - Return code: 0 16:00:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:00:06 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')]} 16:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:06 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')]} 16:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1010c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101157618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a885af4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101026d30>, '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 0x100d8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10101eb20>, '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.KSflfz5VIR/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.0Uy9sxkGEF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 16:00:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:00:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:00:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KSflfz5VIR/Render', 16:00:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:06 INFO - 'HOME': '/Users/cltbld', 16:00:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:06 INFO - 'LOGNAME': 'cltbld', 16:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:06 INFO - 'MOZ_NO_REMOTE': '1', 16:00:06 INFO - 'NO_EM_RESTART': '1', 16:00:06 INFO - 'PAGER': '/bin/cat', 16:00:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:06 INFO - 'PWD': '/builds/slave/test', 16:00:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:06 INFO - 'SHELL': '/bin/bash', 16:00:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Uy9sxkGEF/Listeners', 16:00:06 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:00:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:06 INFO - 'USER': 'cltbld', 16:00:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:06 INFO - 'XPC_FLAGS': '0x0', 16:00:06 INFO - 'XPC_SERVICE_NAME': '0', 16:00:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:00:06 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 16:00:06 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 16:00:06 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 16:00:06 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 16:00:06 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 16:00:06 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 16:00:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:00:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:00:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:00:09 INFO - Installing collected packages: mozsystemmonitor 16:00:09 INFO - Running setup.py install for mozsystemmonitor 16:00:09 INFO - Successfully installed mozsystemmonitor 16:00:09 INFO - Cleaning up... 16:00:09 INFO - Return code: 0 16:00:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:00: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')]} 16:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00: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')]} 16:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:09 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 0x1010c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101157618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a885af4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101026d30>, '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 0x100d8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10101eb20>, '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.KSflfz5VIR/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.0Uy9sxkGEF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 16:00:09 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 16:00:09 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 16:00:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KSflfz5VIR/Render', 16:00:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:09 INFO - 'HOME': '/Users/cltbld', 16:00:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:09 INFO - 'LOGNAME': 'cltbld', 16:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:09 INFO - 'MOZ_NO_REMOTE': '1', 16:00:09 INFO - 'NO_EM_RESTART': '1', 16:00:09 INFO - 'PAGER': '/bin/cat', 16:00:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:09 INFO - 'PWD': '/builds/slave/test', 16:00:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:09 INFO - 'SHELL': '/bin/bash', 16:00:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Uy9sxkGEF/Listeners', 16:00:09 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:00:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:09 INFO - 'USER': 'cltbld', 16:00:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:09 INFO - 'XPC_FLAGS': '0x0', 16:00:09 INFO - 'XPC_SERVICE_NAME': '0', 16:00:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:09 INFO - Downloading/unpacking jsonschema==2.5.1 16:00:09 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 16:00:09 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 16:00:09 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 16:00:09 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 16:00:09 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 16:00:09 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 16:00:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:00:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:00:12 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 16:00:12 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 16:00:12 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 16:00:12 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 16:00:12 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 16:00:12 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 16:00:12 INFO - Downloading functools32-3.2.3-2.tar.gz 16:00:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:00:12 INFO - warning: no files found matching '*.txt' 16:00:12 INFO - no previously-included directories found matching 'build' 16:00:12 INFO - no previously-included directories found matching 'dist' 16:00:12 INFO - no previously-included directories found matching '.git*' 16:00:12 INFO - Installing collected packages: jsonschema, functools32 16:00:12 INFO - Running setup.py install for functools32 16:00:13 INFO - warning: no files found matching '*.txt' 16:00:13 INFO - no previously-included directories found matching 'build' 16:00:13 INFO - no previously-included directories found matching 'dist' 16:00:13 INFO - no previously-included directories found matching '.git*' 16:00:13 INFO - Successfully installed jsonschema functools32 16:00:13 INFO - Cleaning up... 16:00:13 INFO - Return code: 0 16:00:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:00: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')]} 16:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00: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')]} 16:00:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:13 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 0x1010c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101157618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a885af4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101026d30>, '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 0x100d8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10101eb20>, '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.KSflfz5VIR/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.0Uy9sxkGEF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 16:00:13 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 16:00:13 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 16:00:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KSflfz5VIR/Render', 16:00:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:13 INFO - 'HOME': '/Users/cltbld', 16:00:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:13 INFO - 'LOGNAME': 'cltbld', 16:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:13 INFO - 'MOZ_NO_REMOTE': '1', 16:00:13 INFO - 'NO_EM_RESTART': '1', 16:00:13 INFO - 'PAGER': '/bin/cat', 16:00:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:13 INFO - 'PWD': '/builds/slave/test', 16:00:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:13 INFO - 'SHELL': '/bin/bash', 16:00:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Uy9sxkGEF/Listeners', 16:00:13 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:00:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:13 INFO - 'USER': 'cltbld', 16:00:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:13 INFO - 'XPC_FLAGS': '0x0', 16:00:13 INFO - 'XPC_SERVICE_NAME': '0', 16:00:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:00:13 INFO - Cleaning up... 16:00:13 INFO - Return code: 0 16:00:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:00: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')]} 16:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00: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')]} 16:00:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:13 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 0x1010c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101157618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a885af4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101026d30>, '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 0x100d8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10101eb20>, '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.KSflfz5VIR/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.0Uy9sxkGEF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 16:00:13 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 16:00:13 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 16:00:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KSflfz5VIR/Render', 16:00:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:13 INFO - 'HOME': '/Users/cltbld', 16:00:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:13 INFO - 'LOGNAME': 'cltbld', 16:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:13 INFO - 'MOZ_NO_REMOTE': '1', 16:00:13 INFO - 'NO_EM_RESTART': '1', 16:00:13 INFO - 'PAGER': '/bin/cat', 16:00:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:13 INFO - 'PWD': '/builds/slave/test', 16:00:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:13 INFO - 'SHELL': '/bin/bash', 16:00:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Uy9sxkGEF/Listeners', 16:00:13 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:00:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:13 INFO - 'USER': 'cltbld', 16:00:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:13 INFO - 'XPC_FLAGS': '0x0', 16:00:13 INFO - 'XPC_SERVICE_NAME': '0', 16:00:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:13 INFO - Downloading/unpacking blobuploader==1.2.4 16:00:13 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 16:00:13 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 16:00:13 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 16:00:13 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 16:00:13 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 16:00:13 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 16:00:16 INFO - Downloading blobuploader-1.2.4.tar.gz 16:00:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:00:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:00:16 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 16:00:16 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 16:00:16 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 16:00:16 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 16:00:16 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 16:00:16 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 16:00:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:00:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:00:16 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 16:00:16 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 16:00:16 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 16:00:16 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 16:00:16 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 16:00:16 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 16:00:17 INFO - Downloading docopt-0.6.1.tar.gz 16:00:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:00:17 INFO - Installing collected packages: blobuploader, requests, docopt 16:00:17 INFO - Running setup.py install for blobuploader 16:00:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:00:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:00:17 INFO - Running setup.py install for requests 16:00:18 INFO - Running setup.py install for docopt 16:00:18 INFO - Successfully installed blobuploader requests docopt 16:00:18 INFO - Cleaning up... 16:00:18 INFO - Return code: 0 16:00:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:00: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')]} 16:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00: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')]} 16:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:18 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 0x1010c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101157618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a885af4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101026d30>, '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 0x100d8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10101eb20>, '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.KSflfz5VIR/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.0Uy9sxkGEF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 16:00:18 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 16:00:18 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 16:00:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KSflfz5VIR/Render', 16:00:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:18 INFO - 'HOME': '/Users/cltbld', 16:00:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:18 INFO - 'LOGNAME': 'cltbld', 16:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:18 INFO - 'MOZ_NO_REMOTE': '1', 16:00:18 INFO - 'NO_EM_RESTART': '1', 16:00:18 INFO - 'PAGER': '/bin/cat', 16:00:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:18 INFO - 'PWD': '/builds/slave/test', 16:00:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:18 INFO - 'SHELL': '/bin/bash', 16:00:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Uy9sxkGEF/Listeners', 16:00:18 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:00:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:18 INFO - 'USER': 'cltbld', 16:00:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:18 INFO - 'XPC_FLAGS': '0x0', 16:00:18 INFO - 'XPC_SERVICE_NAME': '0', 16:00:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:00:18 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-QcSL0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:00:18 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-bQuOnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:00:18 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-uOv373-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:00:18 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-5O7iAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:00:19 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-6uHN64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:00:19 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-mo6FQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:00:19 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-d52aUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:00:19 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-UOcXMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:00:19 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-8fPB5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:00:19 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-CBgRs0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:00:19 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-IOc4yx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:00:19 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-XgXpgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:00:19 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-2niC7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:00:20 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-6KFriT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:20 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-dikec1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:00:20 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-SlI8l8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:00:20 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-cMSb9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:00:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:00:20 INFO - Running setup.py install for manifestparser 16:00:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:00:20 INFO - Running setup.py install for mozcrash 16:00:20 INFO - Running setup.py install for mozdebug 16:00:20 INFO - Running setup.py install for mozdevice 16:00:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:00:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:00:21 INFO - Running setup.py install for mozfile 16:00:21 INFO - Running setup.py install for mozhttpd 16:00:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:00:21 INFO - Running setup.py install for mozinfo 16:00:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:00:21 INFO - Running setup.py install for mozInstall 16:00:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:00:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:00:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:00:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:00:21 INFO - Running setup.py install for mozleak 16:00:21 INFO - Running setup.py install for mozlog 16:00:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:00:21 INFO - Running setup.py install for moznetwork 16:00:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:00:22 INFO - Running setup.py install for mozprocess 16:00:22 INFO - Running setup.py install for mozprofile 16:00:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:00:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:00:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:00:22 INFO - Running setup.py install for mozrunner 16:00:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:00:22 INFO - Running setup.py install for mozscreenshot 16:00:22 INFO - Running setup.py install for moztest 16:00:22 INFO - Running setup.py install for mozversion 16:00:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:00:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:00:23 INFO - Cleaning up... 16:00:23 INFO - Return code: 0 16:00:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:00:23 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')]} 16:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:23 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')]} 16:00:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:23 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 0x1010c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101157618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a885af4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101026d30>, '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 0x100d8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10101eb20>, '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.KSflfz5VIR/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.0Uy9sxkGEF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 16:00:23 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 16:00:23 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 16:00:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KSflfz5VIR/Render', 16:00:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:23 INFO - 'HOME': '/Users/cltbld', 16:00:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:23 INFO - 'LOGNAME': 'cltbld', 16:00:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:23 INFO - 'MOZ_NO_REMOTE': '1', 16:00:23 INFO - 'NO_EM_RESTART': '1', 16:00:23 INFO - 'PAGER': '/bin/cat', 16:00:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:23 INFO - 'PWD': '/builds/slave/test', 16:00:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:23 INFO - 'SHELL': '/bin/bash', 16:00:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Uy9sxkGEF/Listeners', 16:00:23 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:00:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:23 INFO - 'USER': 'cltbld', 16:00:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:23 INFO - 'XPC_FLAGS': '0x0', 16:00:23 INFO - 'XPC_SERVICE_NAME': '0', 16:00:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:00:23 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-cpEmOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:00:23 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)) 16:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:00:23 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-tVIHAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:00:23 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)) 16:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:00:23 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-REqBjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:00:23 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)) 16:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:00:23 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-rRBHKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:00:23 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)) 16:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:00:23 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-Pd98sM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:00:23 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)) 16:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:00:23 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-uzQEOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:00:23 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)) 16:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:00:23 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-37d2ej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:00:24 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)) 16:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:00:24 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-cjFryl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:00:24 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)) 16:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:00:24 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-Cb59LK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:00:24 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)) 16:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:00:24 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-7R4O9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 16:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:00:24 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-EBcVkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:00:24 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)) 16:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:00:24 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-m5qEIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:00:24 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)) 16:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:00:24 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-VkMfeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:00:24 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)) 16:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:00:24 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-30nz4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:00:24 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)) 16:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:24 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-r5Scio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:24 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)) 16:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:00:24 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-FJPIoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:00:25 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)) 16:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:00:25 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-l9BY3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:00:25 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)) 16:00:25 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)) 16:00:25 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)) 16:00:25 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)) 16:00:25 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)) 16:00:25 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)) 16:00:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:27 INFO - Downloading blessings-1.6.tar.gz 16:00:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:00:27 INFO - Installing collected packages: blessings 16:00:27 INFO - Running setup.py install for blessings 16:00:27 INFO - Successfully installed blessings 16:00:27 INFO - Cleaning up... 16:00:27 INFO - Return code: 0 16:00:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:00:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:00:28 INFO - Reading from file tmpfile_stdout 16:00:28 INFO - Current package versions: 16:00:28 INFO - blessings == 1.6 16:00:28 INFO - blobuploader == 1.2.4 16:00:28 INFO - docopt == 0.6.1 16:00:28 INFO - functools32 == 3.2.3-2 16:00:28 INFO - jsonschema == 2.5.1 16:00:28 INFO - manifestparser == 1.1 16:00:28 INFO - mozInstall == 1.12 16:00:28 INFO - mozcrash == 0.17 16:00:28 INFO - mozdebug == 0.1 16:00:28 INFO - mozdevice == 0.48 16:00:28 INFO - mozfile == 1.2 16:00:28 INFO - mozhttpd == 0.7 16:00:28 INFO - mozinfo == 0.9 16:00:28 INFO - mozleak == 0.1 16:00:28 INFO - mozlog == 3.3 16:00:28 INFO - moznetwork == 0.27 16:00:28 INFO - mozprocess == 0.23 16:00:28 INFO - mozprofile == 0.28 16:00:28 INFO - mozrunner == 6.12 16:00:28 INFO - mozscreenshot == 0.1 16:00:28 INFO - mozsystemmonitor == 0.3 16:00:28 INFO - moztest == 0.7 16:00:28 INFO - mozversion == 1.4 16:00:28 INFO - psutil == 3.1.1 16:00:28 INFO - requests == 1.2.3 16:00:28 INFO - wsgiref == 0.1.2 16:00:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:00: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')]} 16:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00: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')]} 16:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:28 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 0x1010c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101157618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a885af4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101026d30>, '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 0x100d8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10101eb20>, '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.KSflfz5VIR/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.0Uy9sxkGEF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 16:00:28 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 16:00:28 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 16:00:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KSflfz5VIR/Render', 16:00:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:28 INFO - 'HOME': '/Users/cltbld', 16:00:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:28 INFO - 'LOGNAME': 'cltbld', 16:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:28 INFO - 'MOZ_NO_REMOTE': '1', 16:00:28 INFO - 'NO_EM_RESTART': '1', 16:00:28 INFO - 'PAGER': '/bin/cat', 16:00:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:28 INFO - 'PWD': '/builds/slave/test', 16:00:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:28 INFO - 'SHELL': '/bin/bash', 16:00:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Uy9sxkGEF/Listeners', 16:00:28 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:00:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:28 INFO - 'USER': 'cltbld', 16:00:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:28 INFO - 'XPC_FLAGS': '0x0', 16:00:28 INFO - 'XPC_SERVICE_NAME': '0', 16:00:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:28 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)) 16:00:28 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)) 16:00:28 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)) 16:00:28 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)) 16:00:28 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)) 16:00:28 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)) 16:00:28 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)) 16:00:28 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)) 16:00:28 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)) 16:00:28 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)) 16:00:28 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)) 16:00:28 INFO - Cleaning up... 16:00:28 INFO - Return code: 0 16:00:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:00: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')]} 16:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00: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')]} 16:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:28 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 0x1010c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101157618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a885af4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101026d30>, '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 0x100d8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10101eb20>, '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.KSflfz5VIR/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.0Uy9sxkGEF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 16:00:28 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 16:00:28 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 16:00:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KSflfz5VIR/Render', 16:00:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:28 INFO - 'HOME': '/Users/cltbld', 16:00:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:28 INFO - 'LOGNAME': 'cltbld', 16:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:28 INFO - 'MOZ_NO_REMOTE': '1', 16:00:28 INFO - 'NO_EM_RESTART': '1', 16:00:28 INFO - 'PAGER': '/bin/cat', 16:00:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:28 INFO - 'PWD': '/builds/slave/test', 16:00:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:28 INFO - 'SHELL': '/bin/bash', 16:00:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Uy9sxkGEF/Listeners', 16:00:28 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:00:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:28 INFO - 'USER': 'cltbld', 16:00:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:28 INFO - 'XPC_FLAGS': '0x0', 16:00:28 INFO - 'XPC_SERVICE_NAME': '0', 16:00:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:00:28 INFO - Cleaning up... 16:00:28 INFO - Return code: 0 16:00:28 INFO - Running post-action listener: _resource_record_post_action 16:00:28 INFO - Running post-action listener: _start_resource_monitoring 16:00:28 INFO - Starting resource monitoring. 16:00:28 INFO - [mozharness: 2016-09-08 23:00:28.875975Z] Finished create-virtualenv step (success) 16:00:28 INFO - [mozharness: 2016-09-08 23:00:28.876957Z] Running install step. 16:00:28 INFO - Running pre-action listener: _resource_record_pre_action 16:00:28 INFO - Running main action method: install 16:00:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:00:29 INFO - Reading from file tmpfile_stdout 16:00:29 INFO - Detecting whether we're running mozinstall >=1.0... 16:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:00:29 INFO - Reading from file tmpfile_stdout 16:00:29 INFO - Output received: 16:00:29 INFO - Usage: mozinstall [options] installer 16:00:29 INFO - Options: 16:00:29 INFO - -h, --help show this help message and exit 16:00:29 INFO - -d DEST, --destination=DEST 16:00:29 INFO - Directory to install application into. [default: 16:00:29 INFO - "/builds/slave/test"] 16:00:29 INFO - --app=APP Application being installed. [default: firefox] 16:00:29 INFO - mkdir: /builds/slave/test/build/application 16:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:01:03 INFO - Reading from file tmpfile_stdout 16:01:03 INFO - Output received: 16:01:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:01:03 INFO - Running post-action listener: _resource_record_post_action 16:01:03 INFO - [mozharness: 2016-09-08 23:01:03.063884Z] Finished install step (success) 16:01:03 INFO - [mozharness: 2016-09-08 23:01:03.064017Z] Running run-tests step. 16:01:03 INFO - Running pre-action listener: _resource_record_pre_action 16:01:03 INFO - Running main action method: run_tests 16:01:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:01:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:01:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:01:03 INFO - 2016-09-08 16:01:03.078 screenresolution[1640:7539] starting screenresolution argv=screenresolution get 16:01:03 INFO - 2016-09-08 16:01:03.081 screenresolution[1640:7539] Display 0: 1600x1200x32@60 16:01:03 INFO - 2016-09-08 16:01:03.087 screenresolution[1641:7541] starting screenresolution argv=screenresolution list 16:01:03 INFO - Available Modes on Display 0 16:01:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:01:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:01:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:01:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:01:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:01:03 INFO - Intel Iris: 16:01:03 INFO - Chipset Model: Intel Iris 16:01:03 INFO - Type: GPU 16:01:03 INFO - Bus: Built-In 16:01:03 INFO - VRAM (Dynamic, Max): 1536 MB 16:01:03 INFO - Vendor: Intel (0x8086) 16:01:03 INFO - Device ID: 0x0a2e 16:01:03 INFO - Revision ID: 0x0009 16:01:03 INFO - Displays: 16:01:03 INFO - Display: 16:01:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:01:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:01:03 INFO - Display Serial Number: 1600x1200 60 16:01:03 INFO - Main Display: Yes 16:01:03 INFO - Mirror: Off 16:01:03 INFO - Online: Yes 16:01:03 INFO - Rotation: Supported 16:01:03 INFO - Return code: 0 16:01:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:01:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:01:03 INFO - Python 2.7.3 16:01:03 INFO - Return code: 0 16:01:03 INFO - grabbing minidump binary from tooltool 16:01: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')]} 16:01:03 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 0x101026d30>, '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 0x100d8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10101eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:01:03 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 16:01:03 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 16:01:03 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 16:01:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:01:03 INFO - Return code: 0 16:01:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:01:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:01:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:01:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:01:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:01:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VkPgQpKjR-672wOStJoDfw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--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 16:01:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VkPgQpKjR-672wOStJoDfw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0200 --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 16:01:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KSflfz5VIR/Render', 16:01:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:03 INFO - 'HOME': '/Users/cltbld', 16:01:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:03 INFO - 'LOGNAME': 'cltbld', 16:01:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:01:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:03 INFO - 'MOZ_NO_REMOTE': '1', 16:01:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:01:03 INFO - 'NO_EM_RESTART': '1', 16:01:03 INFO - 'PAGER': '/bin/cat', 16:01:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:03 INFO - 'PWD': '/builds/slave/test', 16:01:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:01:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:03 INFO - 'SHELL': '/bin/bash', 16:01:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Uy9sxkGEF/Listeners', 16:01:03 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:01:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:03 INFO - 'USER': 'cltbld', 16:01:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:03 INFO - 'XPC_FLAGS': '0x0', 16:01:03 INFO - 'XPC_SERVICE_NAME': '0', 16:01:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VkPgQpKjR-672wOStJoDfw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--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 16:01:03 INFO - mozversion application_buildid: 20160908150426 16:01:03 INFO - mozversion application_changeset: 5d854c8d076533b9426bc3040979ccf8e0b2404b 16:01:03 INFO - mozversion application_display_name: Nightly 16:01:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:01:03 INFO - mozversion application_name: Firefox 16:01:03 INFO - mozversion application_remotingname: firefox 16:01:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:01:03 INFO - mozversion application_vendor: Mozilla 16:01:03 INFO - mozversion application_version: 51.0a1 16:01:03 INFO - mozversion platform_buildid: 20160908150426 16:01:03 INFO - mozversion platform_changeset: 5d854c8d076533b9426bc3040979ccf8e0b2404b 16:01:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:01:03 INFO - mozversion platform_version: 51.0a1 16:01:03 INFO - using testdate: 1473375663 16:01:03 INFO - actual date: 1473375663 16:01:03 INFO - starting webserver on 'localhost:49266' 16:01:03 INFO - SUITE-START | Running 2 tests 16:01:03 INFO - TEST-START | dromaeo_css 16:01:03 INFO - operating with platform_type : mac_ 16:01:03 INFO - Initialising browser for dromaeo_css test... 16:01:03 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpcLLjR9/profile http://localhost:49266/getInfo.html) 16:01:06 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 16:01:06 INFO - PROCESS | 1651 | colorDepth:24 16:01:06 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 16:01:06 INFO - PROCESS | 1651 | __metrics 16:01:06 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 16:01:07 INFO - TEST-INFO | 1651: exit 0 16:01:07 INFO - Browser initialized. 16:01:07 INFO - Running cycle 1/1 for dromaeo_css test... 16:01:07 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpcLLjR9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:01:19 INFO - PROCESS | 1654 | RSS: Main: 244031488 16:01:19 INFO - PROCESS | 1654 | 16:02:08 INFO - PROCESS | 1654 | 1473375728819 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] 16:04:20 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 16:04:22 INFO - PROCESS | 1654 | RSS: Main: 264540160 16:04:22 INFO - PROCESS | 1654 | 16:07:24 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 16:07:25 INFO - PROCESS | 1654 | RSS: Main: 273330176 16:07:25 INFO - PROCESS | 1654 | 16:10:27 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 16:10:27 INFO - PROCESS | 1654 | RSS: Main: 273858560 16:10:27 INFO - PROCESS | 1654 | 16:13:29 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 16:13:30 INFO - PROCESS | 1654 | RSS: Main: 270295040 16:13:30 INFO - PROCESS | 1654 | 16:16:33 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 16:16:33 INFO - PROCESS | 1654 | RSS: Main: 276656128 16:16:33 INFO - PROCESS | 1654 | 16:19:36 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 16:19:36 INFO - PROCESS | 1654 | RSS: Main: 279560192 16:19:36 INFO - PROCESS | 1654 | 16:19:36 INFO - PROCESS | 1654 | __start_tp_report 16:19:36 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 16:19:36 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 16:19:36 INFO - PROCESS | 1654 | |i|pagename|runs| 16:19:36 INFO - PROCESS | 1654 | |0;dojo.html;3360.9663903360965;3371.2246183377806;3377.969719235632;3388.3392738416005;3401.217719924419;5751.223584816049;5758.280214973129;5781.248437703098;5789.39211382805;5811.709414529278;5567;5708.857278568038;5721.6853073080965;5755.4244575542425;5799.507041901433;3947.934057804391;4249.532551419341;5184.300119483869;5342.358916929974;5343.545798607103;3964.762114273138;5234.476552344773;5346.091164502044;5395.919061214085;5473.8357849264585;3818.717569672496;5164.483551644843;5215.921761173586;5224.92162617561;5261.76322065508;5264.631475796696;5273.86815329616;5287.762050707727;5290.470952904717;5337.066013447631;5216.321878155815;5227.947720522822;5235.397929238148;5239.5546378557865;5336.43967383423;5526.115821468546;5682.835018821132;5729.312482502085;5753.568482363839;5798.362180160179;6261.092141639482;6295.496360291166;6328.525360597973;6343.651099189543;6348.968255158741;6284.968575157141;6307.454673604951;6316.526260530479;6338.144350512668;6340.048992651093;5717.428257174291;5828.504577110908;5880.088586269135;5893.58744887858;5897.734601942837;6332.145160403286;6340.556161068681;6358.205224346957;6363.809085727436;6430.77492287777;6204.255489341218;6226.315105338409;6249.75000999964;6276.278228003838;6282.528810339243;6144.880016394337;6197.690115494207;6217.937820621826;6283.434490895841;6324.873502529924;6103.633781973096;6124.472479235491;6143.354947730513;6169.96915015422;6175.011998080286;6193.938060619336;6267.811965641038;6277.78027769035;6281.654509002049;6289.8742025159245;4535.138323718483;4836.637252206098;4952.678075925053;4955.851324460272;4963.205887058053;4229.577042295746;4446.733196008186;4461.353103799931;4516.029053753458;4538.72767633943;5701.486866182064;5839.386864379264;5946.702664866826;5957.791477298274;6013.909791353133;6220.937790622126;6241.472203757486;6248.4688801451475;6294.150289710922;6303.747850086037;5853.326867410217;5932.228810254639;5943.227380440601;5986.2517185351835;6028.758849645965;5495.120780675073;5510.6969116698165;5515.393306736256;5527.088030475007;5530.668159910419;6217.316095229431;6305.8108256752375;6426.228852537725;6446.6132032077285;6462.870742585122;6229.750809967551;6245.437910588069;6284.24589049317;6294.15028971083;6302.747890084345;6391.680415979089;6421.422072013541;6430.935690643034;6438.3883531064475;6439.678016099083;6136.447719705248;6179.258488981351;6307.24313082433;6309.7160627770945;6331.461825744863;5410;5532.75102620375;6264;6321.241451025906;6323.146375239284;4676.508966558463;5362.731863406892;6032.853757786007;6205.2553693557065;6259.12372267892;6062.060380641008;6090.908636370369;6277.654728989949;6287.434130928238;6295.401936815995;6007.54943379257;6083.482903953213;6100;6141.946315431794;6144.170536977451;4894.65737398393;6133.294671112879;6216.098665693358;6250.624962502265;6260.966940454774;5418.458154184707;5770.249867517364;6081.361457046946;6107.389261073857;6167.599106058092 16:19:36 INFO - PROCESS | 1654 | |1;ext.html;451.8192722910835;469.57972614510015;481.7904211667925;535.5501378841772;545.4218528359937;28031.299217519572;40952.590474095254;40991.180176396454;41088.58911410889;41105.97235069125;41400;41903;41926.58073419265;41940.58059419405;41987.37018944718;9630.470324132188;9833.213342932548;9836.754081147958;9840.360376575536;9859.950700246489;9742.561584728668;9755.365901216399;9790.167835733935;9857.901420985805;9882.752931176743;9836;9842.557084931195;9876;9908.653197138101;9931.751706207331;9807.901920980807;9826.361286516354;9833.01669833011;9836.557354919047;9839.606415743361;9702.805943881154;9713.90286097137;9781.46201958892;9793.853092203623;9807.362521436155;39247;39616.80191599052;39628;39655.60344396548;39740.40389394161;3260.510923361491;3307.818070006149;3368.949465758016;3675.797831119314;3934.8622798201927;30597.38805223883;31193.688063119305;31253.843730781202;31603.683963160303;31622.367552649048;2892.770572506676;3280.78674886134;3303.933921321584;3322.2026713588807;3454.2055327274866;445738;459887;463921;469438;470914.6454267741;61541;62362.68818655878;62816.37183628196;62976.68511657504;63154.368456315766;34789;35132;35350.46974295388;35496;68204;14342.139471631735;16319.83680163207;16895.662086758195;17092.91453542749;17112.315507379815;12945.482180712854;13055.673608159872;13059.804102938464;13094;13115;19728.013599319976;20091;20138.89236092029;20185.394438166877;20265.49336266605;7315.561066336037;8647.221750042527;8908.599113039929;8963.820723585492;9041.457512549268;843.304273973974;895.5477483870734;956.6606750549148;974.65886939572;1012.9290949633455;26927.192184234504;29322.973695920966;29988.55017174744;30304.393912121635;30502.389952200832;995.348047029197;1032.396048311746;1053.9776416875618;1057.830747080479;1121.859767529059;14602.63493412652;14778.556643300719;15399;15411.922940385226;15532.534023979073;550973;628808;630652;705586;706933;57356.42643573594;57409.13886291709;57716.711416442646;57840.71079644575;57945;1293.9935300323439;1329.7473480038761;1337.3781191745884;1348.480834878559;1353.9729205416033;26578.734212657626;28618.284542886595;29550.704492954796;29768.10695679133;30104.69895301019;39668.801655991534;49899.75050124726;50947.745261273456;51025.74487127689;51543.48456515387;604377;604576.9771151292;605560.8053718099;605895;606003;26742.465150697266;27027.86486067557;27044.594331084656;27127.321816954733;27154.864225678746;46034;46386.53613463822;46517.76741116273;47606.761966189944;47819;34893.6042558305;36698.63301367059;38227.235455291295;38981.80509097436;39669.404958926214;38564.6143538561;38929.41605875971;39583.60416395914;39781.40327895142;40137;32159.839200803846;37786.24427511379;38229.044273892265;38401.07994600315;39247.018824529616;28222.85888570626;32612;32812;34400.82799585986;34506.82746586251;22148.51262582137;22378.66432003554;22625.981830817323;22683.773162268168;22776.20283290077 16:19:36 INFO - PROCESS | 1654 | |2;jquery.html;372.8508596561375;382.30802247931246;382.66707964071253;384.5865694378544;405.23612989514777;15544.766828497568;19078.90460547697;20817;20864.47838804031;20882.582348353044;19892.70160947587;20404.795952040477;20483.283085092025;20773.376798696063;20796.272130475438;5107.7701503432245;5892.086726557391;5966.970165149168;6096.7561297548045;6137.601055931373;5970.641761494303;6048.727807248662;6078.300995385543;6089.330173680892;6094.60385074971;5768.855778605547;5983.760649574012;6038.909416358758;6040.758369665208;6064.72708728106;5858.853528661775;5926.644401335933;5963;6012.8496787580225;6029.909551356711;4349.978250108761;4419.779011049434;4434.556544345572;4510.300903359985;5228.111221092386;25087.749122508907;25461.61807572888;25764.355891102685;25934.74065259361;25955.480890382274;2169;2459.717132529764;3014.427258820817;3077.9076627701206;3128.937421251585;13055.477780888754;13083.411246493835;14958.551243462714;15298.082115073132;15589.298481568356;1618.6034421566792;1809.4933418642802;1894.9715754263698;1900.6388786130592;1943.0867492278605;92937.14125717516;106154.46922765336;111905;116372.41813790877;118728;9437.056294370508;10360.792784144274;10619.309744866714;10854.728631784268;11376.601818936297;13340.73318533624;13354.866451335556;13562.033505141699;13605.727885442237;13619.659508512168;8357.456765310357;8622.353323500636;8634.611442485104;8733.825323493495;8746.169113934166;8668.219860212612;8754.168354006359;8787.384883058125;8909.910900890907;9008.873890763654;6535.104819602262;6611.834704132435;6649.135612370361;6663.233728121328;6684.933150668431;1817.972730409045;1850.3616252392903;1852.110986726352;1852.1850385830191;1856.4059500959563;862.1120246146479;879.7844528090659;955.0067929353395;977.855908586955;981.842905135175;20594.35245180382;21799.91000449971;21861.890690546446;21885.562288754136;22155.224567140074;871.5990644303564;908.232543500754;955.8852937647526;961.6778379242892;1010.3836659637615;10641.308314959502;10930.672079837459;10959.232853700165;10974;10982.560697572168;601302;611073;621245;621984;625095;110900;113016.30475542873;115164.84835151541;128395;137812;436.96941214115344;454.06462686864506;455.58996902787754;455.9270516717309;457.36883101319967;9404.96545379994;10362.119219866396;11224.94387528057;16574.005559666457;16753.581160471087;50396.748016261154;55415.44584554264;57169.85660286688;57796.55508612318;58911;78319;78720.21279787358;78877.4224515518;80818.9181081887;81648.77526836983;6627.801165965029;7824.530528168328;7964.663396969709;8031.116577176623;8124.878126828221;8966.955165224132;11463.484143213742;12313.32276724753;12605.369731513205;12635.368231588567;10904.509297081491;11012.449377531251;11083.612073577386;11099.72250693739;11127.218195451138;8764.737057888273;9304;9656.662016829378;10100.939401362752;11014.889851101707;11071.778564428827;11131;11190.552377904793;11261.605843795427;11414.08687304997;7606.239376062349;7947.5231486108705;8034.477758945884;8037.31682806968;8268.875966860382;10905.83641245366;11023.779524409627;11071;11115.666530004113;11340.09279257641 16:19:36 INFO - PROCESS | 1654 | |3;mootools.html;1492.5447738439775;1526.832048474668;1699.728043513038;1739.3564381178965;1843.6220574782171;7518.360939320164;8428.831423371528;8620.439671421353;8980.77548061298;8988.01131875494;8609.612567434464;8662.436941598791;8811.647534098629;8820.20618144367;8854.86717699235;2180.640194367931;2406.1698713943747;2407.9277621671376;2410.276916924924;2418.7702168293986;2361.8346715729904;2390.5457962987066;2405.2062819269686;2408.7470815564375;2411.867347295898;2378.2389635316704;2389.2593296078185;2394.6886904702365;2400.423898264413;2412.662227288181;2377.500724847784;2389.5220955808823;2391.928242152738;2397.2209032064648;2399.6400539918977;2336.97663023371;2378.500514891875;2379.214859096494;2385.010220758383;2398.0527691561733;8756.168164024408;8803.031666516677;8934.017258101603;8934.86597701035;8954.462732236087;4400.4939431965095;5294.708791016493;5349.197620356938;5420.376656684492;5444.5916556258435;3515.490253913168;3529.170644898447;3581.391163502185;3596.29872174926;3612.422012478017;1059.6873922192972;1062.760878802271;1068.647346375702;1069.0592278794688;1073.3720773347602;11409.37248451335;11472.71318217043;11517.13621478384;11595.072394208442;11601.187916845824;4583.083383323348;4629.675922685447;4819.566239038503;4822.638302127354;4837.298591704183;9812.264080194014;9835.311528192959;9907.058829411195;9938.403695778277;9975.551100200508;5278.366596008504;5323.520883120537;5341.81303654378;5344.759485823147;5366.543843773323;3747.6814470769746;4163.396307535451;4378.365137055172;4735;4814.15752243356;3409.62494125646;3893.4549163117185;3987.342088555356;4009.659178969762;4084.448599439097;1128.210252823027;1245;1316.8156458095875;1322.8412590489204;1328.913620614657;819.8524265632243;891.7636826241052;908.922741566961;922.1838672774643;932.1750251027889;5760.855978600484;5873.471387575139;6036.667983260875;6058.242719660043;6117.204763380821;444.36234004203425;445.243086752522;448.19101521752896;452.4231604703963;456.955855869337;3883.76697398157;3939.625735555118;4054.2094291612852;4063.8577649782114;4072.3280658690983;10189.439580823126;10328.277020608486;10551.736206594896;10661.200409969284;10723.088537474247;10447.164226861833;10495.527701253463;10625.681229563126;10642.148628109733;10662.946685266525;7994.40041996841;8105.257369665433;8116;8201.671933122727;8217.465864718773;5791.276090488689;5809.564282678901;5902.915706681544;6033.396660333931;6066.636001839904;8136;9552.092551207625;9724.201022810803;9806.705798826046;9915.355501892354;10025.75295065157;10045.296829221885;10105.797884042426;10112.949435253071;10172.949135254275;6272.435480806749;7299.379552737864;7410.888836667562;7465;7505.324520792937;8227.506349619041;8307.83384332322;8403.579821009047;8507;8531.146885311418;7432.739854105081;7627.122880868549;7641.159472458136;7737.729179478692;7750.7674769756995;7072.186698529735;7366.337029667356;7445.7766267012075;7544.6227688616445;7583.924160758322;7095.82260443493;7134.536255143399;7148.928510714826;7154.892676609751;7165.032720582654;4874.780634871368;4986.301917731448;5026.271190677405;5147.845564633067;5161.948380516147;4977.452480227244;4986.152354099884;5085.0338435697095;5129.79480820763;5144.819931302386 16:19:36 INFO - PROCESS | 1654 | |4;prototype.html;296.5018768468973;304.76076280120105;309.75065072616565;311.39123014506634;313.68944744702753;5413.105413105421;5486;5612.158176273561;5684.715764211794;5735.569832262587;4267.381229721696;5354.785808567663;5366.007288651593;5592.832215033545;5607.607467477268;3553.3781588222087;4248.320268756999;4297.591728785776;4301.978490107545;4318.287482565373;3442;3601.8114022372556;3681.8159092045425;3755.4554589584495;4321.2653848845775;3418.9485459730176;3665.5601327840563;3758.4926034985338;4170.8540201092965;4200.117975225191;3555.520004799345;4130.896727581804;4159.542450330477;4166.1251137261215;4204.789760511962;3435.4331535296647;3557.092941299952;3593.5508061492314;3598.6221446748236;3611.223586928823;4824.614030877521;5133.665247035798;5415.48552887475;5418.133098704188;5480.479354461319;4791.856244312677;4807.4952130026195;4833.637477189225;4847.878803029917;4857.368542089541;5587.525060369873;5807.912881306784;5865.41633758887;5870.911936320958;5914.865121485576;3917.6484112980975;3935.0359162005207;3937.0551067743536;3955.2287303975736;3973.324534829086;5436.809711660112;5964.582479226456;6158.907616385758;6209.813705588839;6267.2479302484;3767.342369357474;3977.2244412339896;4002.07900207896;4008.518977722692;4009.91980160401;3899.7855117968784;3939.0152461884527;4008.617027125637;4085.0400155963325;4104.261232978092;4562.357551281536;4789.30555069513;4806.903861922742;4846.321514987904;4853.733044682505;4716.099598746746;5662.943370566324;5963.642181469126;6025.849353766103;6056.848578785565;1053.489057806963;1238.9318587477594;1297.2151848131932;1365.7473367427058;1382.9696875827574;899.7930475990559;907.1744712311764;929.1173385283872;930.6184464369576;932.1750251027889;4782.689125206851;5510.641808282448;5520.834374968757;5542.806001789999;5592.524635406036;5641.915371269434;5786.450287222707;6020.4280593343565;6037.705671844758;6089.8477538061015;4416.911661766747;5139.434662187156;5207.270982062514;5467.398586155519;5500.917486237709;2130.8067482210013;2431.939201519976;2496.4258220609;2562.2071941583763;2768.5985532097734;4639.953600463952;5220.686758794484;5263.894722105613;5308.946910530845;5380.192971054373;4751.95248047522;5206.55744261742;5762.596618236683;6258.123862659231;6300.172949844494;6005.468605505568;6377.74489020434;6389.265234498;6401.583897046677;6419.935800641933;5030.622703297194;5047.823326183566;5359;6100.755969761338;6174.6912654368;5217.452167522356;5495.368032676293;5541.362743284412;6141.570090093651;6146.692665366803;5173.922391164211;5302.734863256899;5569.164625306236;5585.436077898195;6356.014817703263;3289.9342013159126;3899.824507897208;3982.9203415932097;4015.4177644242004;4029.657479114308;4872.9512704872495;5168.9741551292;5846.356900741001;6067.120267561268;6191.442770150528;5452.154915988028;5725.799597014085;5900.852478687895;5908.375196820737;5909.497692696169;5897.496138484659;5913.615614985012;5929.644221346733;5937.376575459687;5960;5557.749901254534;5807.651540907559;5814.273215848019;5840.474357307863;5857.941420585739;5925;5930.822075337746;5939.7229595637145;5942;5961.52307815365;5075.974620126875;5100.648328192958;5607.971960140173;5844.966972788762;5900.43945825146 16:19:36 INFO - PROCESS | 1654 | |5;yui.html;637.6513673580378;691.315597558417;736.7863319637305;750.9899185595376;806.3911746631294;15040.849591504082;15625.687486250283;15711.685766284667;15734.606634834136;16485.67028659429;15910.284037218324;15912.443064492745;15950.202489875488;15975.680486390265;16040.679186416264;849.9702510412152;871.7515508080198;880.9515476648781;883.0507204754882;883.765802062454;687.1547995964966;846.8729690546405;876.8509353409936;878.7056336127373;885.6501681835671;870.7344260000697;911.9407238529508;912.6805618033668;913.5660561233427;913.949732764701;881.1981097201516;904.5025236120108;907.5825120444604;908.5729707037715;909.708893154191;674.7975607317834;680.3264767879813;681.9897701534481;682.1405029662611;682.1643486728767;15234.390624375012;15234.695306093927;15280.159591222478;15327.693446131016;15348.693026139526;11531.827022594667;13904.721905561832;14041.368138433794;14068.366923488467;14124.011319207659;11357.716057098556;11655.533778648844;11764.411779410995;11802.049487628094;12286.938565307117;3759.2481503699096;3763.4542991266385;3795.994061573685;3801.505804245458;3930.724849260553;113880.43059784731;116553.41723291414;117662.41168794101;117694;118404.40797796042;10713;10750.838737418944;10853.837192442119;10912.181586381053;10942.319017057767;21765.23821666234;21860.562788744137;21894.671579926315;21905.671414928787;21922.780772192073;7894.328982036591;8001.079875814239;8080.818144231292;8120.309773669304;8175;3687.3362794697214;4309.870703878889;4350.630196433275;4387.736735795798;4425.955740442619;3410.3179364127272;3490.528778614905;3522.947155792665;3530.293941211768;3534.32847758925;624.3756243756244;629.9779507717208;632.6046221111806;640.942315191635;647.1554621219354;609.9542534309855;638.7154502894496;670.6780745242311;684.165318311659;695.6765104226457;9296.44489133572;9297.442153470678;9317.627294908263;9439.150476457151;9471.147596716328;561.2395204497847;580.2080160580776;624.406813527151;662.8243515468405;695.2595485807599;3958.802059896994;4362.302031674917;4466.704655649825;4555.772211389417;4673.322368256584;91217;119291;165319;169180;183887;100941;102250.48874755579;104432.91134177273;106152;109081;103223.9999999985;106168;107545.88783085726;107970;109841.45079274553;9244.352895297265;9464.716058518256;9486.620535178516;9581;9599.376040557341;58554.828903422545;63501.364986349545;63544;64802.67598661977;65242.02136967854;88462;92682.07317926735;93792;94086;94326.52836735772;10657.946710266398;11302.265352752045;11405.486753096297;13147.14543554666;13385.598432047054;11919.821202681793;12943.741125177632;14171;14848.7772683412;15227.23863806783;11192.88807111951;11347.148963827582;11420.885791141982;13602.523911663044;13711;13282.80075798883;13596.98022648325;13732.794008090086;14078.929605351908;14198.29008549589;13206.867931320563;13542.892474619668;13681.589552313446;13915.512957046454;14181.574552763434;9485.33602647808;11235.88764112381;11357;11441.461136395294;11526.94236528812;9968.692293525797;11478.196526243417;11493.023093037185;11573.421328933688;11770.470328835052 16:19:36 INFO - PROCESS | 1654 | __end_tp_report 16:19:36 INFO - PROCESS | 1654 | __start_cc_report 16:19:36 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,383 16:19:36 INFO - PROCESS | 1654 | __end_cc_report 16:19:36 INFO - PROCESS | 1654 | __startTimestamp1473376776812__endTimestamp 16:19:36 INFO - PROCESS | 1654 | 16:19:36 INFO - PROCESS | 1654 | ------- Summary: start ------- 16:19:36 INFO - PROCESS | 1654 | Number of tests: 6 16:19:36 INFO - PROCESS | 1654 | 16:19:36 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5749.47 Median:6028.76 stddev:713.68 (11.8%) stddev-sans-first:690.84 16:19:36 INFO - PROCESS | 1654 | Values: 3361.0 3371.2 3378.0 3388.3 3401.2 5751.2 5758.3 5781.2 5789.4 5811.7 5567.0 5708.9 5721.7 5755.4 5799.5 3947.9 4249.5 5184.3 5342.4 5343.5 3964.8 5234.5 5346.1 5395.9 5473.8 3818.7 5164.5 5215.9 5224.9 5261.8 5264.6 5273.9 5287.8 5290.5 5337.1 5216.3 5227.9 5235.4 5239.6 5336.4 5526.1 5682.8 5729.3 5753.6 5798.4 6261.1 6295.5 6328.5 6343.7 6349.0 6285.0 6307.5 6316.5 6338.1 6340.0 5717.4 5828.5 5880.1 5893.6 5897.7 6332.1 6340.6 6358.2 6363.8 6430.8 6204.3 6226.3 6249.8 6276.3 6282.5 6144.9 6197.7 6217.9 6283.4 6324.9 6103.6 6124.5 6143.4 6170.0 6175.0 6193.9 6267.8 6277.8 6281.7 6289.9 4535.1 4836.6 4952.7 4955.9 4963.2 4229.6 4446.7 4461.4 4516.0 4538.7 5701.5 5839.4 5946.7 5957.8 6013.9 6220.9 6241.5 6248.5 6294.2 6303.7 5853.3 5932.2 5943.2 5986.3 6028.8 5495.1 5510.7 5515.4 5527.1 5530.7 6217.3 6305.8 6426.2 6446.6 6462.9 6229.8 6245.4 6284.2 6294.2 6302.7 6391.7 6421.4 6430.9 6438.4 6439.7 6136.4 6179.3 6307.2 6309.7 6331.5 5410.0 5532.8 6264.0 6321.2 6323.1 4676.5 5362.7 6032.9 6205.3 6259.1 6062.1 6090.9 6277.7 6287.4 6295.4 6007.5 6083.5 6100.0 6141.9 6144.2 4894.7 6133.3 6216.1 6250.6 6261.0 5418.5 5770.2 6081.4 6107.4 6167.6 16:19:36 INFO - PROCESS | 1654 | 16:19:36 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:69665.64 Median:39662.20 stddev:154411.61 (389.3%) stddev-sans-first:154757.33 16:19:36 INFO - PROCESS | 1654 | Values: 451.8 469.6 481.8 535.6 545.4 28031.3 40952.6 40991.2 41088.6 41106.0 41400.0 41903.0 41926.6 41940.6 41987.4 9630.5 9833.2 9836.8 9840.4 9860.0 9742.6 9755.4 9790.2 9857.9 9882.8 9836.0 9842.6 9876.0 9908.7 9931.8 9807.9 9826.4 9833.0 9836.6 9839.6 9702.8 9713.9 9781.5 9793.9 9807.4 39247.0 39616.8 39628.0 39655.6 39740.4 3260.5 3307.8 3368.9 3675.8 3934.9 30597.4 31193.7 31253.8 31603.7 31622.4 2892.8 3280.8 3303.9 3322.2 3454.2 445738.0 459887.0 463921.0 469438.0 470914.6 61541.0 62362.7 62816.4 62976.7 63154.4 34789.0 35132.0 35350.5 35496.0 68204.0 14342.1 16319.8 16895.7 17092.9 17112.3 12945.5 13055.7 13059.8 13094.0 13115.0 19728.0 20091.0 20138.9 20185.4 20265.5 7315.6 8647.2 8908.6 8963.8 9041.5 843.3 895.5 956.7 974.7 1012.9 26927.2 29323.0 29988.6 30304.4 30502.4 995.3 1032.4 1054.0 1057.8 1121.9 14602.6 14778.6 15399.0 15411.9 15532.5 550973.0 628808.0 630652.0 705586.0 706933.0 57356.4 57409.1 57716.7 57840.7 57945.0 1294.0 1329.7 1337.4 1348.5 1354.0 26578.7 28618.3 29550.7 29768.1 30104.7 39668.8 49899.8 50947.7 51025.7 51543.5 604377.0 604577.0 605560.8 605895.0 606003.0 26742.5 27027.9 27044.6 27127.3 27154.9 46034.0 46386.5 46517.8 47606.8 47819.0 34893.6 36698.6 38227.2 38981.8 39669.4 38564.6 38929.4 39583.6 39781.4 40137.0 32159.8 37786.2 38229.0 38401.1 39247.0 28222.9 32612.0 32812.0 34400.8 34506.8 22148.5 22378.7 22626.0 22683.8 22776.2 16:19:36 INFO - PROCESS | 1654 | 16:19:36 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:35096.92 Median:382.49 stddev:102408.47 (26774.3%) stddev-sans-first:102662.56 16:19:36 INFO - PROCESS | 1654 | Values: 372.9 382.3 382.7 384.6 405.2 15544.8 19078.9 20817.0 20864.5 20882.6 19892.7 20404.8 20483.3 20773.4 20796.3 5107.8 5892.1 5967.0 6096.8 6137.6 5970.6 6048.7 6078.3 6089.3 6094.6 5768.9 5983.8 6038.9 6040.8 6064.7 5858.9 5926.6 5963.0 6012.8 6029.9 4350.0 4419.8 4434.6 4510.3 5228.1 25087.7 25461.6 25764.4 25934.7 25955.5 2169.0 2459.7 3014.4 3077.9 3128.9 13055.5 13083.4 14958.6 15298.1 15589.3 1618.6 1809.5 1895.0 1900.6 1943.1 92937.1 106154.5 111905.0 116372.4 118728.0 9437.1 10360.8 10619.3 10854.7 11376.6 13340.7 13354.9 13562.0 13605.7 13619.7 8357.5 8622.4 8634.6 8733.8 8746.2 8668.2 8754.2 8787.4 8909.9 9008.9 6535.1 6611.8 6649.1 6663.2 6684.9 1818.0 1850.4 1852.1 1852.2 1856.4 862.1 879.8 955.0 977.9 981.8 20594.4 21799.9 21861.9 21885.6 22155.2 871.6 908.2 955.9 961.7 1010.4 10641.3 10930.7 10959.2 10974.0 10982.6 601302.0 611073.0 621245.0 621984.0 625095.0 110900.0 113016.3 115164.8 128395.0 137812.0 437.0 454.1 455.6 455.9 457.4 9405.0 10362.1 11224.9 16574.0 16753.6 50396.7 55415.4 57169.9 57796.6 58911.0 78319.0 78720.2 78877.4 80818.9 81648.8 6627.8 7824.5 7964.7 8031.1 8124.9 8967.0 11463.5 12313.3 12605.4 12635.4 10904.5 11012.4 11083.6 11099.7 11127.2 8764.7 9304.0 9656.7 10100.9 11014.9 11071.8 11131.0 11190.6 11261.6 11414.1 7606.2 7947.5 8034.5 8037.3 8268.9 10905.8 11023.8 11071.0 11115.7 11340.1 16:19:36 INFO - PROCESS | 1654 | 16:19:36 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5676.34 Median:4856.04 stddev:3180.46 (65.5%) stddev-sans-first:3173.84 16:19:36 INFO - PROCESS | 1654 | Values: 1492.5 1526.8 1699.7 1739.4 1843.6 7518.4 8428.8 8620.4 8980.8 8988.0 8609.6 8662.4 8811.6 8820.2 8854.9 2180.6 2406.2 2407.9 2410.3 2418.8 2361.8 2390.5 2405.2 2408.7 2411.9 2378.2 2389.3 2394.7 2400.4 2412.7 2377.5 2389.5 2391.9 2397.2 2399.6 2337.0 2378.5 2379.2 2385.0 2398.1 8756.2 8803.0 8934.0 8934.9 8954.5 4400.5 5294.7 5349.2 5420.4 5444.6 3515.5 3529.2 3581.4 3596.3 3612.4 1059.7 1062.8 1068.6 1069.1 1073.4 11409.4 11472.7 11517.1 11595.1 11601.2 4583.1 4629.7 4819.6 4822.6 4837.3 9812.3 9835.3 9907.1 9938.4 9975.6 5278.4 5323.5 5341.8 5344.8 5366.5 3747.7 4163.4 4378.4 4735.0 4814.2 3409.6 3893.5 3987.3 4009.7 4084.4 1128.2 1245.0 1316.8 1322.8 1328.9 819.9 891.8 908.9 922.2 932.2 5760.9 5873.5 6036.7 6058.2 6117.2 444.4 445.2 448.2 452.4 457.0 3883.8 3939.6 4054.2 4063.9 4072.3 10189.4 10328.3 10551.7 10661.2 10723.1 10447.2 10495.5 10625.7 10642.1 10662.9 7994.4 8105.3 8116.0 8201.7 8217.5 5791.3 5809.6 5902.9 6033.4 6066.6 8136.0 9552.1 9724.2 9806.7 9915.4 10025.8 10045.3 10105.8 10112.9 10172.9 6272.4 7299.4 7410.9 7465.0 7505.3 8227.5 8307.8 8403.6 8507.0 8531.1 7432.7 7627.1 7641.2 7737.7 7750.8 7072.2 7366.3 7445.8 7544.6 7583.9 7095.8 7134.5 7148.9 7154.9 7165.0 4874.8 4986.3 5026.3 5147.8 5161.9 4977.5 4986.2 5085.0 5129.8 5144.8 16:19:36 INFO - PROCESS | 1654 | 16:19:36 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4673.47 Median:5283.31 stddev:1493.14 (28.3%) stddev-sans-first:1460.74 16:19:36 INFO - PROCESS | 1654 | Values: 296.5 304.8 309.8 311.4 313.7 5413.1 5486.0 5612.2 5684.7 5735.6 4267.4 5354.8 5366.0 5592.8 5607.6 3553.4 4248.3 4297.6 4302.0 4318.3 3442.0 3601.8 3681.8 3755.5 4321.3 3418.9 3665.6 3758.5 4170.9 4200.1 3555.5 4130.9 4159.5 4166.1 4204.8 3435.4 3557.1 3593.6 3598.6 3611.2 4824.6 5133.7 5415.5 5418.1 5480.5 4791.9 4807.5 4833.6 4847.9 4857.4 5587.5 5807.9 5865.4 5870.9 5914.9 3917.6 3935.0 3937.1 3955.2 3973.3 5436.8 5964.6 6158.9 6209.8 6267.2 3767.3 3977.2 4002.1 4008.5 4009.9 3899.8 3939.0 4008.6 4085.0 4104.3 4562.4 4789.3 4806.9 4846.3 4853.7 4716.1 5662.9 5963.6 6025.8 6056.8 1053.5 1238.9 1297.2 1365.7 1383.0 899.8 907.2 929.1 930.6 932.2 4782.7 5510.6 5520.8 5542.8 5592.5 5641.9 5786.5 6020.4 6037.7 6089.8 4416.9 5139.4 5207.3 5467.4 5500.9 2130.8 2431.9 2496.4 2562.2 2768.6 4640.0 5220.7 5263.9 5308.9 5380.2 4752.0 5206.6 5762.6 6258.1 6300.2 6005.5 6377.7 6389.3 6401.6 6419.9 5030.6 5047.8 5359.0 6100.8 6174.7 5217.5 5495.4 5541.4 6141.6 6146.7 5173.9 5302.7 5569.2 5585.4 6356.0 3289.9 3899.8 3982.9 4015.4 4029.7 4873.0 5169.0 5846.4 6067.1 6191.4 5452.2 5725.8 5900.9 5908.4 5909.5 5897.5 5913.6 5929.6 5937.4 5960.0 5557.7 5807.7 5814.3 5840.5 5857.9 5925.0 5930.8 5939.7 5942.0 5961.5 5076.0 5100.6 5608.0 5845.0 5900.4 16:19:36 INFO - PROCESS | 1654 | 16:19:36 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:24233.23 Median:3532.31 stddev:38486.75 (1089.6%) stddev-sans-first:38553.94 16:19:36 INFO - PROCESS | 1654 | Values: 637.7 691.3 736.8 751.0 806.4 15040.8 15625.7 15711.7 15734.6 16485.7 15910.3 15912.4 15950.2 15975.7 16040.7 850.0 871.8 881.0 883.1 883.8 687.2 846.9 876.9 878.7 885.7 870.7 911.9 912.7 913.6 913.9 881.2 904.5 907.6 908.6 909.7 674.8 680.3 682.0 682.1 682.2 15234.4 15234.7 15280.2 15327.7 15348.7 11531.8 13904.7 14041.4 14068.4 14124.0 11357.7 11655.5 11764.4 11802.0 12286.9 3759.2 3763.5 3796.0 3801.5 3930.7 113880.4 116553.4 117662.4 117694.0 118404.4 10713.0 10750.8 10853.8 10912.2 10942.3 21765.2 21860.6 21894.7 21905.7 21922.8 7894.3 8001.1 8080.8 8120.3 8175.0 3687.3 4309.9 4350.6 4387.7 4426.0 3410.3 3490.5 3522.9 3530.3 3534.3 624.4 630.0 632.6 640.9 647.2 610.0 638.7 670.7 684.2 695.7 9296.4 9297.4 9317.6 9439.2 9471.1 561.2 580.2 624.4 662.8 695.3 3958.8 4362.3 4466.7 4555.8 4673.3 91217.0 119291.0 165319.0 169180.0 183887.0 100941.0 102250.5 104432.9 106152.0 109081.0 103224.0 106168.0 107545.9 107970.0 109841.5 9244.4 9464.7 9486.6 9581.0 9599.4 58554.8 63501.4 63544.0 64802.7 65242.0 88462.0 92682.1 93792.0 94086.0 94326.5 10657.9 11302.3 11405.5 13147.1 13385.6 11919.8 12943.7 14171.0 14848.8 15227.2 11192.9 11347.1 11420.9 13602.5 13711.0 13282.8 13597.0 13732.8 14078.9 14198.3 13206.9 13542.9 13681.6 13915.5 14181.6 9485.3 11235.9 11357.0 11441.5 11526.9 9968.7 11478.2 11493.0 11573.4 11770.5 16:19:36 INFO - PROCESS | 1654 | -------- Summary: end -------- 16:19:36 INFO - PROCESS | 1654 | 16:19:37 INFO - TEST-INFO | 1654: exit 0 16:19:37 INFO - TEST-OK | dromaeo_css | took 1113770ms 16:19:37 INFO - TEST-START | kraken 16:19:37 INFO - operating with platform_type : mac_ 16:19:37 INFO - Initialising browser for kraken test... 16:19:37 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpzRyxFk/profile http://localhost:49266/getInfo.html) 16:19:40 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 16:19:40 INFO - PROCESS | 1666 | colorDepth:24 16:19:40 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 16:19:40 INFO - PROCESS | 1666 | __metrics 16:19:40 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 16:19:40 INFO - TEST-INFO | 1666: exit 0 16:19:40 INFO - Browser initialized. 16:19:40 INFO - Running cycle 1/1 for kraken test... 16:19:40 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpzRyxFk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:19:52 INFO - PROCESS | 1668 | RSS: Main: 244101120 16:19:52 INFO - PROCESS | 1668 | 16:19:55 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 16:19:56 INFO - PROCESS | 1668 | RSS: Main: 256905216 16:19:56 INFO - PROCESS | 1668 | 16:19:59 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 16:19:59 INFO - PROCESS | 1668 | RSS: Main: 265596928 16:19:59 INFO - PROCESS | 1668 | 16:20:03 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 16:20:03 INFO - PROCESS | 1668 | RSS: Main: 263290880 16:20:03 INFO - PROCESS | 1668 | 16:20:05 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 16:20:06 INFO - PROCESS | 1668 | RSS: Main: 265945088 16:20:06 INFO - PROCESS | 1668 | 16:20:08 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:20:09 INFO - PROCESS | 1668 | RSS: Main: 266264576 16:20:09 INFO - PROCESS | 1668 | 16:20:12 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 16:20:12 INFO - PROCESS | 1668 | RSS: Main: 266403840 16:20:12 INFO - PROCESS | 1668 | 16:20:15 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 16:20:16 INFO - PROCESS | 1668 | RSS: Main: 266522624 16:20:16 INFO - PROCESS | 1668 | 16:20:19 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 16:20:19 INFO - PROCESS | 1668 | RSS: Main: 266911744 16:20:19 INFO - PROCESS | 1668 | 16:20:21 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:20:21 INFO - PROCESS | 1668 | RSS: Main: 266399744 16:20:21 INFO - PROCESS | 1668 | 16:20:23 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:20:24 INFO - PROCESS | 1668 | RSS: Main: 266518528 16:20:24 INFO - PROCESS | 1668 | 16:20:26 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:20:27 INFO - PROCESS | 1668 | RSS: Main: 266883072 16:20:27 INFO - PROCESS | 1668 | 16:20:30 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:20:31 INFO - PROCESS | 1668 | RSS: Main: 266924032 16:20:31 INFO - PROCESS | 1668 | 16:20:34 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:20:34 INFO - PROCESS | 1668 | RSS: Main: 267259904 16:20:34 INFO - PROCESS | 1668 | 16:20:36 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 16:20:36 INFO - PROCESS | 1668 | RSS: Main: 269348864 16:20:36 INFO - PROCESS | 1668 | 16:20:36 INFO - PROCESS | 1668 | __start_tp_report 16:20:36 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 16:20:36 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 16:20:36 INFO - PROCESS | 1668 | |i|pagename|runs| 16:20:36 INFO - PROCESS | 1668 | |0;ai-astar;117;111;118;116;118;102;118;118;116;114 16:20:36 INFO - PROCESS | 1668 | |1;audio-beat-detection;147;109;134;157;137;164;165;122;159;96 16:20:36 INFO - PROCESS | 1668 | |2;audio-dft;193;201;210;210;269;273;195;198;199;196 16:20:36 INFO - PROCESS | 1668 | |3;audio-fft;70;87;93;177;84;84;67;59;141;61 16:20:36 INFO - PROCESS | 1668 | |4;audio-oscillator;69;72;115;72;71;83;110;68;72;110 16:20:36 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;89;87;88;85;122;76;134;131;88;87 16:20:36 INFO - PROCESS | 1668 | |6;imaging-darkroom;130;100;98;99;99;113;136;99;99;100 16:20:36 INFO - PROCESS | 1668 | |7;imaging-desaturate;90;93;89;114;94;90;90;92;91;91 16:20:36 INFO - PROCESS | 1668 | |8;json-parse-financial;56;57;57;59;58;57;60;58;60;57 16:20:36 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;65;71;75;69;70;76;104;101;97;77 16:20:36 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;87;86;86;87;88;88;89;104;89;88 16:20:36 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;160;135;148;156;132;156;155;157;221;151 16:20:36 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;171;170;153;207;232;198;170;170;170;173 16:20:36 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;68;70;70;66;81;70;68;70;67;68 16:20:36 INFO - PROCESS | 1668 | __end_tp_report 16:20:36 INFO - PROCESS | 1668 | __start_cc_report 16:20:36 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,952 16:20:36 INFO - PROCESS | 1668 | __end_cc_report 16:20:36 INFO - PROCESS | 1668 | __startTimestamp1473376836703__endTimestamp 16:20:36 INFO - PROCESS | 1668 | 16:20:36 INFO - PROCESS | 1668 | ------- Summary: start ------- 16:20:36 INFO - PROCESS | 1668 | Number of tests: 14 16:20:36 INFO - PROCESS | 1668 | 16:20:36 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:114.80 Median:117.50 stddev:5.03 (4.3%) stddev-sans-first:5.27 16:20:36 INFO - PROCESS | 1668 | Values: 117.0 111.0 118.0 116.0 118.0 102.0 118.0 118.0 116.0 114.0 16:20:36 INFO - PROCESS | 1668 | 16:20:36 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:139.00 Median:158.00 stddev:23.94 (15.1%) stddev-sans-first:25.21 16:20:36 INFO - PROCESS | 1668 | Values: 147.0 109.0 134.0 157.0 137.0 164.0 165.0 122.0 159.0 96.0 16:20:36 INFO - PROCESS | 1668 | 16:20:36 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:214.40 Median:205.50 stddev:30.39 (14.8%) stddev-sans-first:31.23 16:20:36 INFO - PROCESS | 1668 | Values: 193.0 201.0 210.0 210.0 269.0 273.0 195.0 198.0 199.0 196.0 16:20:36 INFO - PROCESS | 1668 | 16:20:36 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:92.30 Median:77.00 stddev:37.92 (49.2%) stddev-sans-first:39.35 16:20:36 INFO - PROCESS | 1668 | Values: 70.0 87.0 93.0 177.0 84.0 84.0 67.0 59.0 141.0 61.0 16:20:36 INFO - PROCESS | 1668 | 16:20:36 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:84.20 Median:71.50 stddev:19.42 (27.2%) stddev-sans-first:19.81 16:20:36 INFO - PROCESS | 1668 | Values: 69.0 72.0 115.0 72.0 71.0 83.0 110.0 68.0 72.0 110.0 16:20:36 INFO - PROCESS | 1668 | 16:20:36 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:98.70 Median:87.00 stddev:21.43 (24.6%) stddev-sans-first:22.44 16:20:36 INFO - PROCESS | 1668 | Values: 89.0 87.0 88.0 85.0 122.0 76.0 134.0 131.0 88.0 87.0 16:20:36 INFO - PROCESS | 1668 | 16:20:36 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:107.30 Median:98.50 stddev:14.30 (14.5%) stddev-sans-first:12.59 16:20:36 INFO - PROCESS | 1668 | Values: 130.0 100.0 98.0 99.0 99.0 113.0 136.0 99.0 99.0 100.0 16:20:36 INFO - PROCESS | 1668 | 16:20:36 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:93.40 Median:91.00 stddev:7.40 (8.1%) stddev-sans-first:7.74 16:20:36 INFO - PROCESS | 1668 | Values: 90.0 93.0 89.0 114.0 94.0 90.0 90.0 92.0 91.0 91.0 16:20:36 INFO - PROCESS | 1668 | 16:20:36 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:57.90 Median:58.00 stddev:1.37 (2.4%) stddev-sans-first:1.27 16:20:36 INFO - PROCESS | 1668 | Values: 56.0 57.0 57.0 59.0 58.0 57.0 60.0 58.0 60.0 57.0 16:20:36 INFO - PROCESS | 1668 | 16:20:36 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:80.50 Median:73.00 stddev:14.45 (19.8%) stddev-sans-first:14.20 16:20:36 INFO - PROCESS | 1668 | Values: 65.0 71.0 75.0 69.0 70.0 76.0 104.0 101.0 97.0 77.0 16:20:36 INFO - PROCESS | 1668 | 16:20:36 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:88.00 stddev:5.31 (6.0%) stddev-sans-first:5.57 16:20:36 INFO - PROCESS | 1668 | Values: 87.0 86.0 86.0 87.0 88.0 88.0 89.0 104.0 89.0 88.0 16:20:36 INFO - PROCESS | 1668 | 16:20:36 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:157.10 Median:156.00 stddev:24.35 (15.6%) stddev-sans-first:25.81 16:20:36 INFO - PROCESS | 1668 | Values: 160.0 135.0 148.0 156.0 132.0 156.0 155.0 157.0 221.0 151.0 16:20:36 INFO - PROCESS | 1668 | 16:20:36 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.40 Median:172.00 stddev:23.56 (13.7%) stddev-sans-first:24.69 16:20:36 INFO - PROCESS | 1668 | Values: 171.0 170.0 153.0 207.0 232.0 198.0 170.0 170.0 170.0 173.0 16:20:36 INFO - PROCESS | 1668 | 16:20:36 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:70.00 stddev:4.18 (6.0%) stddev-sans-first:4.39 16:20:36 INFO - PROCESS | 1668 | Values: 68.0 70.0 70.0 66.0 81.0 70.0 68.0 70.0 67.0 68.0 16:20:36 INFO - PROCESS | 1668 | -------- Summary: end -------- 16:20:36 INFO - PROCESS | 1668 | 16:20:37 INFO - TEST-INFO | 1668: exit 0 16:20:37 INFO - TEST-OK | kraken | took 60061ms 16:20:37 INFO - SUITE-END | took 1173s 16:20:37 INFO - Completed test suite (00:19:34) 16:20:37 INFO - javascript benchmark 16:20:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7321.372317718415, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3360.9663903360965, 3371.2246183377806, 3377.969719235632, 3388.3392738416005, 3401.217719924419, 5751.223584816049, 5758.280214973129, 5781.248437703098, 5789.39211382805, 5811.709414529278, 5567.0, 5708.857278568038, 5721.6853073080965, 5755.4244575542425, 5799.507041901433, 3947.934057804391, 4249.532551419341, 5184.300119483869, 5342.358916929974, 5343.545798607103, 3964.762114273138, 5234.476552344773, 5346.091164502044, 5395.919061214085, 5473.8357849264585, 3818.717569672496, 5164.483551644843, 5215.921761173586, 5224.92162617561, 5261.76322065508, 5264.631475796696, 5273.86815329616, 5287.762050707727, 5290.470952904717, 5337.066013447631, 5216.321878155815, 5227.947720522822, 5235.397929238148, 5239.5546378557865, 5336.43967383423, 5526.115821468546, 5682.835018821132, 5729.312482502085, 5753.568482363839, 5798.362180160179, 6261.092141639482, 6295.496360291166, 6328.525360597973, 6343.651099189543, 6348.968255158741, 6284.968575157141, 6307.454673604951, 6316.526260530479, 6338.144350512668, 6340.048992651093, 5717.428257174291, 5828.504577110908, 5880.088586269135, 5893.58744887858, 5897.734601942837, 6332.145160403286, 6340.556161068681, 6358.205224346957, 6363.809085727436, 6430.77492287777, 6204.255489341218, 6226.315105338409, 6249.75000999964, 6276.278228003838, 6282.528810339243, 6144.880016394337, 6197.690115494207, 6217.937820621826, 6283.434490895841, 6324.873502529924, 6103.633781973096, 6124.472479235491, 6143.354947730513, 6169.96915015422, 6175.011998080286, 6193.938060619336, 6267.811965641038, 6277.78027769035, 6281.654509002049, 6289.8742025159245, 4535.138323718483, 4836.637252206098, 4952.678075925053, 4955.851324460272, 4963.205887058053, 4229.577042295746, 4446.733196008186, 4461.353103799931, 4516.029053753458, 4538.72767633943, 5701.486866182064, 5839.386864379264, 5946.702664866826, 5957.791477298274, 6013.909791353133, 6220.937790622126, 6241.472203757486, 6248.4688801451475, 6294.150289710922, 6303.747850086037, 5853.326867410217, 5932.228810254639, 5943.227380440601, 5986.2517185351835, 6028.758849645965, 5495.120780675073, 5510.6969116698165, 5515.393306736256, 5527.088030475007, 5530.668159910419, 6217.316095229431, 6305.8108256752375, 6426.228852537725, 6446.6132032077285, 6462.870742585122, 6229.750809967551, 6245.437910588069, 6284.24589049317, 6294.15028971083, 6302.747890084345, 6391.680415979089, 6421.422072013541, 6430.935690643034, 6438.3883531064475, 6439.678016099083, 6136.447719705248, 6179.258488981351, 6307.24313082433, 6309.7160627770945, 6331.461825744863, 5410.0, 5532.75102620375, 6264.0, 6321.241451025906, 6323.146375239284, 4676.508966558463, 5362.731863406892, 6032.853757786007, 6205.2553693557065, 6259.12372267892, 6062.060380641008, 6090.908636370369, 6277.654728989949, 6287.434130928238, 6295.401936815995, 6007.54943379257, 6083.482903953213, 6100.0, 6141.946315431794, 6144.170536977451, 4894.65737398393, 6133.294671112879, 6216.098665693358, 6250.624962502265, 6260.966940454774, 5418.458154184707, 5770.249867517364, 6081.361457046946, 6107.389261073857, 6167.599106058092], "value": 5703.8293589928635, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.8192722910835, 469.57972614510015, 481.7904211667925, 535.5501378841772, 545.4218528359937, 28031.299217519572, 40952.590474095254, 40991.180176396454, 41088.58911410889, 41105.97235069125, 41400.0, 41903.0, 41926.58073419265, 41940.58059419405, 41987.37018944718, 9630.470324132188, 9833.213342932548, 9836.754081147958, 9840.360376575536, 9859.950700246489, 9742.561584728668, 9755.365901216399, 9790.167835733935, 9857.901420985805, 9882.752931176743, 9836.0, 9842.557084931195, 9876.0, 9908.653197138101, 9931.751706207331, 9807.901920980807, 9826.361286516354, 9833.01669833011, 9836.557354919047, 9839.606415743361, 9702.805943881154, 9713.90286097137, 9781.46201958892, 9793.853092203623, 9807.362521436155, 39247.0, 39616.80191599052, 39628.0, 39655.60344396548, 39740.40389394161, 3260.510923361491, 3307.818070006149, 3368.949465758016, 3675.797831119314, 3934.8622798201927, 30597.38805223883, 31193.688063119305, 31253.843730781202, 31603.683963160303, 31622.367552649048, 2892.770572506676, 3280.78674886134, 3303.933921321584, 3322.2026713588807, 3454.2055327274866, 445738.0, 459887.0, 463921.0, 469438.0, 470914.6454267741, 61541.0, 62362.68818655878, 62816.37183628196, 62976.68511657504, 63154.368456315766, 34789.0, 35132.0, 35350.46974295388, 35496.0, 68204.0, 14342.139471631735, 16319.83680163207, 16895.662086758195, 17092.91453542749, 17112.315507379815, 12945.482180712854, 13055.673608159872, 13059.804102938464, 13094.0, 13115.0, 19728.013599319976, 20091.0, 20138.89236092029, 20185.394438166877, 20265.49336266605, 7315.561066336037, 8647.221750042527, 8908.599113039929, 8963.820723585492, 9041.457512549268, 843.304273973974, 895.5477483870734, 956.6606750549148, 974.65886939572, 1012.9290949633455, 26927.192184234504, 29322.973695920966, 29988.55017174744, 30304.393912121635, 30502.389952200832, 995.348047029197, 1032.396048311746, 1053.9776416875618, 1057.830747080479, 1121.859767529059, 14602.63493412652, 14778.556643300719, 15399.0, 15411.922940385226, 15532.534023979073, 550973.0, 628808.0, 630652.0, 705586.0, 706933.0, 57356.42643573594, 57409.13886291709, 57716.711416442646, 57840.71079644575, 57945.0, 1293.9935300323439, 1329.7473480038761, 1337.3781191745884, 1348.480834878559, 1353.9729205416033, 26578.734212657626, 28618.284542886595, 29550.704492954796, 29768.10695679133, 30104.69895301019, 39668.801655991534, 49899.75050124726, 50947.745261273456, 51025.74487127689, 51543.48456515387, 604377.0, 604576.9771151292, 605560.8053718099, 605895.0, 606003.0, 26742.465150697266, 27027.86486067557, 27044.594331084656, 27127.321816954733, 27154.864225678746, 46034.0, 46386.53613463822, 46517.76741116273, 47606.761966189944, 47819.0, 34893.6042558305, 36698.63301367059, 38227.235455291295, 38981.80509097436, 39669.404958926214, 38564.6143538561, 38929.41605875971, 39583.60416395914, 39781.40327895142, 40137.0, 32159.839200803846, 37786.24427511379, 38229.044273892265, 38401.07994600315, 39247.018824529616, 28222.85888570626, 32612.0, 32812.0, 34400.82799585986, 34506.82746586251, 22148.51262582137, 22378.66432003554, 22625.981830817323, 22683.773162268168, 22776.20283290077], "value": 19746.251549224628, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.8508596561375, 382.30802247931246, 382.66707964071253, 384.5865694378544, 405.23612989514777, 15544.766828497568, 19078.90460547697, 20817.0, 20864.47838804031, 20882.582348353044, 19892.70160947587, 20404.795952040477, 20483.283085092025, 20773.376798696063, 20796.272130475438, 5107.7701503432245, 5892.086726557391, 5966.970165149168, 6096.7561297548045, 6137.601055931373, 5970.641761494303, 6048.727807248662, 6078.300995385543, 6089.330173680892, 6094.60385074971, 5768.855778605547, 5983.760649574012, 6038.909416358758, 6040.758369665208, 6064.72708728106, 5858.853528661775, 5926.644401335933, 5963.0, 6012.8496787580225, 6029.909551356711, 4349.978250108761, 4419.779011049434, 4434.556544345572, 4510.300903359985, 5228.111221092386, 25087.749122508907, 25461.61807572888, 25764.355891102685, 25934.74065259361, 25955.480890382274, 2169.0, 2459.717132529764, 3014.427258820817, 3077.9076627701206, 3128.937421251585, 13055.477780888754, 13083.411246493835, 14958.551243462714, 15298.082115073132, 15589.298481568356, 1618.6034421566792, 1809.4933418642802, 1894.9715754263698, 1900.6388786130592, 1943.0867492278605, 92937.14125717516, 106154.46922765336, 111905.0, 116372.41813790877, 118728.0, 9437.056294370508, 10360.792784144274, 10619.309744866714, 10854.728631784268, 11376.601818936297, 13340.73318533624, 13354.866451335556, 13562.033505141699, 13605.727885442237, 13619.659508512168, 8357.456765310357, 8622.353323500636, 8634.611442485104, 8733.825323493495, 8746.169113934166, 8668.219860212612, 8754.168354006359, 8787.384883058125, 8909.910900890907, 9008.873890763654, 6535.104819602262, 6611.834704132435, 6649.135612370361, 6663.233728121328, 6684.933150668431, 1817.972730409045, 1850.3616252392903, 1852.110986726352, 1852.1850385830191, 1856.4059500959563, 862.1120246146479, 879.7844528090659, 955.0067929353395, 977.855908586955, 981.842905135175, 20594.35245180382, 21799.91000449971, 21861.890690546446, 21885.562288754136, 22155.224567140074, 871.5990644303564, 908.232543500754, 955.8852937647526, 961.6778379242892, 1010.3836659637615, 10641.308314959502, 10930.672079837459, 10959.232853700165, 10974.0, 10982.560697572168, 601302.0, 611073.0, 621245.0, 621984.0, 625095.0, 110900.0, 113016.30475542873, 115164.84835151541, 128395.0, 137812.0, 436.96941214115344, 454.06462686864506, 455.58996902787754, 455.9270516717309, 457.36883101319967, 9404.96545379994, 10362.119219866396, 11224.94387528057, 16574.005559666457, 16753.581160471087, 50396.748016261154, 55415.44584554264, 57169.85660286688, 57796.55508612318, 58911.0, 78319.0, 78720.21279787358, 78877.4224515518, 80818.9181081887, 81648.77526836983, 6627.801165965029, 7824.530528168328, 7964.663396969709, 8031.116577176623, 8124.878126828221, 8966.955165224132, 11463.484143213742, 12313.32276724753, 12605.369731513205, 12635.368231588567, 10904.509297081491, 11012.449377531251, 11083.612073577386, 11099.72250693739, 11127.218195451138, 8764.737057888273, 9304.0, 9656.662016829378, 10100.939401362752, 11014.889851101707, 11071.778564428827, 11131.0, 11190.552377904793, 11261.605843795427, 11414.08687304997, 7606.239376062349, 7947.5231486108705, 8034.477758945884, 8037.31682806968, 8268.875966860382, 10905.83641245366, 11023.779524409627, 11071.0, 11115.666530004113, 11340.09279257641], "value": 9360.745834308445, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1492.5447738439775, 1526.832048474668, 1699.728043513038, 1739.3564381178965, 1843.6220574782171, 7518.360939320164, 8428.831423371528, 8620.439671421353, 8980.77548061298, 8988.01131875494, 8609.612567434464, 8662.436941598791, 8811.647534098629, 8820.20618144367, 8854.86717699235, 2180.640194367931, 2406.1698713943747, 2407.9277621671376, 2410.276916924924, 2418.7702168293986, 2361.8346715729904, 2390.5457962987066, 2405.2062819269686, 2408.7470815564375, 2411.867347295898, 2378.2389635316704, 2389.2593296078185, 2394.6886904702365, 2400.423898264413, 2412.662227288181, 2377.500724847784, 2389.5220955808823, 2391.928242152738, 2397.2209032064648, 2399.6400539918977, 2336.97663023371, 2378.500514891875, 2379.214859096494, 2385.010220758383, 2398.0527691561733, 8756.168164024408, 8803.031666516677, 8934.017258101603, 8934.86597701035, 8954.462732236087, 4400.4939431965095, 5294.708791016493, 5349.197620356938, 5420.376656684492, 5444.5916556258435, 3515.490253913168, 3529.170644898447, 3581.391163502185, 3596.29872174926, 3612.422012478017, 1059.6873922192972, 1062.760878802271, 1068.647346375702, 1069.0592278794688, 1073.3720773347602, 11409.37248451335, 11472.71318217043, 11517.13621478384, 11595.072394208442, 11601.187916845824, 4583.083383323348, 4629.675922685447, 4819.566239038503, 4822.638302127354, 4837.298591704183, 9812.264080194014, 9835.311528192959, 9907.058829411195, 9938.403695778277, 9975.551100200508, 5278.366596008504, 5323.520883120537, 5341.81303654378, 5344.759485823147, 5366.543843773323, 3747.6814470769746, 4163.396307535451, 4378.365137055172, 4735.0, 4814.15752243356, 3409.62494125646, 3893.4549163117185, 3987.342088555356, 4009.659178969762, 4084.448599439097, 1128.210252823027, 1245.0, 1316.8156458095875, 1322.8412590489204, 1328.913620614657, 819.8524265632243, 891.7636826241052, 908.922741566961, 922.1838672774643, 932.1750251027889, 5760.855978600484, 5873.471387575139, 6036.667983260875, 6058.242719660043, 6117.204763380821, 444.36234004203425, 445.243086752522, 448.19101521752896, 452.4231604703963, 456.955855869337, 3883.76697398157, 3939.625735555118, 4054.2094291612852, 4063.8577649782114, 4072.3280658690983, 10189.439580823126, 10328.277020608486, 10551.736206594896, 10661.200409969284, 10723.088537474247, 10447.164226861833, 10495.527701253463, 10625.681229563126, 10642.148628109733, 10662.946685266525, 7994.40041996841, 8105.257369665433, 8116.0, 8201.671933122727, 8217.465864718773, 5791.276090488689, 5809.564282678901, 5902.915706681544, 6033.396660333931, 6066.636001839904, 8136.0, 9552.092551207625, 9724.201022810803, 9806.705798826046, 9915.355501892354, 10025.75295065157, 10045.296829221885, 10105.797884042426, 10112.949435253071, 10172.949135254275, 6272.435480806749, 7299.379552737864, 7410.888836667562, 7465.0, 7505.324520792937, 8227.506349619041, 8307.83384332322, 8403.579821009047, 8507.0, 8531.146885311418, 7432.739854105081, 7627.122880868549, 7641.159472458136, 7737.729179478692, 7750.7674769756995, 7072.186698529735, 7366.337029667356, 7445.7766267012075, 7544.6227688616445, 7583.924160758322, 7095.82260443493, 7134.536255143399, 7148.928510714826, 7154.892676609751, 7165.032720582654, 4874.780634871368, 4986.301917731448, 5026.271190677405, 5147.845564633067, 5161.948380516147, 4977.452480227244, 4986.152354099884, 5085.0338435697095, 5129.79480820763, 5144.819931302386], "value": 4494.69955812929, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.5018768468973, 304.76076280120105, 309.75065072616565, 311.39123014506634, 313.68944744702753, 5413.105413105421, 5486.0, 5612.158176273561, 5684.715764211794, 5735.569832262587, 4267.381229721696, 5354.785808567663, 5366.007288651593, 5592.832215033545, 5607.607467477268, 3553.3781588222087, 4248.320268756999, 4297.591728785776, 4301.978490107545, 4318.287482565373, 3442.0, 3601.8114022372556, 3681.8159092045425, 3755.4554589584495, 4321.2653848845775, 3418.9485459730176, 3665.5601327840563, 3758.4926034985338, 4170.8540201092965, 4200.117975225191, 3555.520004799345, 4130.896727581804, 4159.542450330477, 4166.1251137261215, 4204.789760511962, 3435.4331535296647, 3557.092941299952, 3593.5508061492314, 3598.6221446748236, 3611.223586928823, 4824.614030877521, 5133.665247035798, 5415.48552887475, 5418.133098704188, 5480.479354461319, 4791.856244312677, 4807.4952130026195, 4833.637477189225, 4847.878803029917, 4857.368542089541, 5587.525060369873, 5807.912881306784, 5865.41633758887, 5870.911936320958, 5914.865121485576, 3917.6484112980975, 3935.0359162005207, 3937.0551067743536, 3955.2287303975736, 3973.324534829086, 5436.809711660112, 5964.582479226456, 6158.907616385758, 6209.813705588839, 6267.2479302484, 3767.342369357474, 3977.2244412339896, 4002.07900207896, 4008.518977722692, 4009.91980160401, 3899.7855117968784, 3939.0152461884527, 4008.617027125637, 4085.0400155963325, 4104.261232978092, 4562.357551281536, 4789.30555069513, 4806.903861922742, 4846.321514987904, 4853.733044682505, 4716.099598746746, 5662.943370566324, 5963.642181469126, 6025.849353766103, 6056.848578785565, 1053.489057806963, 1238.9318587477594, 1297.2151848131932, 1365.7473367427058, 1382.9696875827574, 899.7930475990559, 907.1744712311764, 929.1173385283872, 930.6184464369576, 932.1750251027889, 4782.689125206851, 5510.641808282448, 5520.834374968757, 5542.806001789999, 5592.524635406036, 5641.915371269434, 5786.450287222707, 6020.4280593343565, 6037.705671844758, 6089.8477538061015, 4416.911661766747, 5139.434662187156, 5207.270982062514, 5467.398586155519, 5500.917486237709, 2130.8067482210013, 2431.939201519976, 2496.4258220609, 2562.2071941583763, 2768.5985532097734, 4639.953600463952, 5220.686758794484, 5263.894722105613, 5308.946910530845, 5380.192971054373, 4751.95248047522, 5206.55744261742, 5762.596618236683, 6258.123862659231, 6300.172949844494, 6005.468605505568, 6377.74489020434, 6389.265234498, 6401.583897046677, 6419.935800641933, 5030.622703297194, 5047.823326183566, 5359.0, 6100.755969761338, 6174.6912654368, 5217.452167522356, 5495.368032676293, 5541.362743284412, 6141.570090093651, 6146.692665366803, 5173.922391164211, 5302.734863256899, 5569.164625306236, 5585.436077898195, 6356.014817703263, 3289.9342013159126, 3899.824507897208, 3982.9203415932097, 4015.4177644242004, 4029.657479114308, 4872.9512704872495, 5168.9741551292, 5846.356900741001, 6067.120267561268, 6191.442770150528, 5452.154915988028, 5725.799597014085, 5900.852478687895, 5908.375196820737, 5909.497692696169, 5897.496138484659, 5913.615614985012, 5929.644221346733, 5937.376575459687, 5960.0, 5557.749901254534, 5807.651540907559, 5814.273215848019, 5840.474357307863, 5857.941420585739, 5925.0, 5930.822075337746, 5939.7229595637145, 5942.0, 5961.52307815365, 5075.974620126875, 5100.648328192958, 5607.971960140173, 5844.966972788762, 5900.43945825146], "value": 4190.88496896738, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [637.6513673580378, 691.315597558417, 736.7863319637305, 750.9899185595376, 806.3911746631294, 15040.849591504082, 15625.687486250283, 15711.685766284667, 15734.606634834136, 16485.67028659429, 15910.284037218324, 15912.443064492745, 15950.202489875488, 15975.680486390265, 16040.679186416264, 849.9702510412152, 871.7515508080198, 880.9515476648781, 883.0507204754882, 883.765802062454, 687.1547995964966, 846.8729690546405, 876.8509353409936, 878.7056336127373, 885.6501681835671, 870.7344260000697, 911.9407238529508, 912.6805618033668, 913.5660561233427, 913.949732764701, 881.1981097201516, 904.5025236120108, 907.5825120444604, 908.5729707037715, 909.708893154191, 674.7975607317834, 680.3264767879813, 681.9897701534481, 682.1405029662611, 682.1643486728767, 15234.390624375012, 15234.695306093927, 15280.159591222478, 15327.693446131016, 15348.693026139526, 11531.827022594667, 13904.721905561832, 14041.368138433794, 14068.366923488467, 14124.011319207659, 11357.716057098556, 11655.533778648844, 11764.411779410995, 11802.049487628094, 12286.938565307117, 3759.2481503699096, 3763.4542991266385, 3795.994061573685, 3801.505804245458, 3930.724849260553, 113880.43059784731, 116553.41723291414, 117662.41168794101, 117694.0, 118404.40797796042, 10713.0, 10750.838737418944, 10853.837192442119, 10912.181586381053, 10942.319017057767, 21765.23821666234, 21860.562788744137, 21894.671579926315, 21905.671414928787, 21922.780772192073, 7894.328982036591, 8001.079875814239, 8080.818144231292, 8120.309773669304, 8175.0, 3687.3362794697214, 4309.870703878889, 4350.630196433275, 4387.736735795798, 4425.955740442619, 3410.3179364127272, 3490.528778614905, 3522.947155792665, 3530.293941211768, 3534.32847758925, 624.3756243756244, 629.9779507717208, 632.6046221111806, 640.942315191635, 647.1554621219354, 609.9542534309855, 638.7154502894496, 670.6780745242311, 684.165318311659, 695.6765104226457, 9296.44489133572, 9297.442153470678, 9317.627294908263, 9439.150476457151, 9471.147596716328, 561.2395204497847, 580.2080160580776, 624.406813527151, 662.8243515468405, 695.2595485807599, 3958.802059896994, 4362.302031674917, 4466.704655649825, 4555.772211389417, 4673.322368256584, 91217.0, 119291.0, 165319.0, 169180.0, 183887.0, 100941.0, 102250.48874755579, 104432.91134177273, 106152.0, 109081.0, 103223.9999999985, 106168.0, 107545.88783085726, 107970.0, 109841.45079274553, 9244.352895297265, 9464.716058518256, 9486.620535178516, 9581.0, 9599.376040557341, 58554.828903422545, 63501.364986349545, 63544.0, 64802.67598661977, 65242.02136967854, 88462.0, 92682.07317926735, 93792.0, 94086.0, 94326.52836735772, 10657.946710266398, 11302.265352752045, 11405.486753096297, 13147.14543554666, 13385.598432047054, 11919.821202681793, 12943.741125177632, 14171.0, 14848.7772683412, 15227.23863806783, 11192.88807111951, 11347.148963827582, 11420.885791141982, 13602.523911663044, 13711.0, 13282.80075798883, 13596.98022648325, 13732.794008090086, 14078.929605351908, 14198.29008549589, 13206.867931320563, 13542.892474619668, 13681.589552313446, 13915.512957046454, 14181.574552763434, 9485.33602647808, 11235.88764112381, 11357.0, 11441.461136395294, 11526.94236528812, 9968.692293525797, 11478.196526243417, 11493.023093037185, 11573.421328933688, 11770.470328835052], "value": 7754.290051954891, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1580.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 111.0, 118.0, 116.0, 118.0, 102.0, 118.0, 118.0, 116.0, 114.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 109.0, 134.0, 157.0, 137.0, 164.0, 165.0, 122.0, 159.0, 96.0], "value": 139.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 201.0, 210.0, 210.0, 269.0, 273.0, 195.0, 198.0, 199.0, 196.0], "value": 214.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 87.0, 93.0, 177.0, 84.0, 84.0, 67.0, 59.0, 141.0, 61.0], "value": 92.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 115.0, 72.0, 71.0, 83.0, 110.0, 68.0, 72.0, 110.0], "value": 84.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 85.0, 122.0, 76.0, 134.0, 131.0, 88.0, 87.0], "value": 98.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 100.0, 98.0, 99.0, 99.0, 113.0, 136.0, 99.0, 99.0, 100.0], "value": 107.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 89.0, 114.0, 94.0, 90.0, 90.0, 92.0, 91.0, 91.0], "value": 93.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 57.0, 59.0, 58.0, 57.0, 60.0, 58.0, 60.0, 57.0], "value": 57.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 75.0, 69.0, 70.0, 76.0, 104.0, 101.0, 97.0, 77.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 87.0, 88.0, 88.0, 89.0, 104.0, 89.0, 88.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 135.0, 148.0, 156.0, 132.0, 156.0, 155.0, 157.0, 221.0, 151.0], "value": 157.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 170.0, 153.0, 207.0, 232.0, 198.0, 170.0, 170.0, 170.0, 173.0], "value": 181.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 70.0, 66.0, 81.0, 70.0, 68.0, 70.0, 67.0, 68.0], "value": 69.8, "unit": "score"}]}]} 16:20:37 INFO - Return code: 0 16:20:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:20:38 ERROR - # TBPL SUCCESS # 16:20:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:20:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:20:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:20:38 INFO - 2016-09-08 16:20:38.024 screenresolution[1673:10557] starting screenresolution argv=screenresolution get 16:20:38 INFO - 2016-09-08 16:20:38.027 screenresolution[1673:10557] Display 0: 1600x1200x32@60 16:20:38 INFO - 2016-09-08 16:20:38.033 screenresolution[1674:10560] starting screenresolution argv=screenresolution list 16:20:38 INFO - Available Modes on Display 0 16:20:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:20:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:20:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:20:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:20:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:20:38 INFO - Intel Iris: 16:20:38 INFO - Chipset Model: Intel Iris 16:20:38 INFO - Type: GPU 16:20:38 INFO - Bus: Built-In 16:20:38 INFO - VRAM (Dynamic, Max): 1536 MB 16:20:38 INFO - Vendor: Intel (0x8086) 16:20:38 INFO - Device ID: 0x0a2e 16:20:38 INFO - Revision ID: 0x0009 16:20:38 INFO - Displays: 16:20:38 INFO - Display: 16:20:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:20:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:20:38 INFO - Display Serial Number: 1600x1200 60 16:20:38 INFO - Main Display: Yes 16:20:38 INFO - Mirror: Off 16:20:38 INFO - Online: Yes 16:20:38 INFO - Rotation: Supported 16:20:38 INFO - Return code: 0 16:20:38 INFO - Running post-action listener: _resource_record_post_action 16:20:38 INFO - [mozharness: 2016-09-08 23:20:38.269648Z] Finished run-tests step (success) 16:20:38 INFO - Running post-run listener: _resource_record_post_run 16:20:38 INFO - Total resource usage - Wall time: 1208s; CPU: 55.0%; Read bytes: 37397504; Write bytes: 703767552; Read time: 394; Write time: 1994 16:20:38 INFO - TinderboxPrint: CPU usage
54.6% 16:20:38 INFO - TinderboxPrint: I/O read bytes / time
37,397,504 / 394 16:20:38 INFO - TinderboxPrint: I/O write bytes / time
703,767,552 / 1,994 16:20:38 INFO - TinderboxPrint: CPU idle
2,193.5 (45.4%) 16:20:38 INFO - TinderboxPrint: CPU system
980.1 (20.3%) 16:20:38 INFO - TinderboxPrint: CPU user
1,660.0 (34.3%) 16:20:38 INFO - TinderboxPrint: Swap in / out
443,138,048 / 0 16:20:38 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404701696; Write bytes: 399083520; Read time: 29899; Write time: 679 16:20:38 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 34534400; Write bytes: 293960704; Read time: 364; Write time: 1282 16:20:38 INFO - Running post-run listener: _upload_blobber_files 16:20:38 INFO - Blob upload gear active. 16:20:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:20:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:20:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:20:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:20:39 INFO - (blobuploader) - INFO - Open directory for files ... 16:20:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:20:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:20:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:20:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:40 INFO - (blobuploader) - INFO - Done attempting. 16:20:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:20:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:20:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:20:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:41 INFO - (blobuploader) - INFO - Done attempting. 16:20:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:20:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:20:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:20:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:43 INFO - (blobuploader) - INFO - Done attempting. 16:20:43 INFO - (blobuploader) - INFO - Iteration through files over. 16:20:43 INFO - Return code: 0 16:20:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:20:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:20:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/626387ab8aaa23bb03d9ecb6211e8992a01d8c590a36cb076debb29b1fdee46208012c65c7b3a2920158f980cdbf0cc3ec727041b91fd947d8fc31a6a395cadd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95fe9dd1af7a51cccc8c19390f1a73d430805a1102a20fad08d07c517d30e2d52bb9ac744e2645deb33a0e105382de05577f1971cfd09bf8ede8b95bd78c4bc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2213ca85111be751b5639190a7b8412c3fb2956deb8e894d11c7109245eedf7ff8c8bc46933f5060c0795275c1ebf80d103e951837cb7ce4c6c8e6821298123b"} 16:20:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:20:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:20:43 INFO - Contents: 16:20:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/626387ab8aaa23bb03d9ecb6211e8992a01d8c590a36cb076debb29b1fdee46208012c65c7b3a2920158f980cdbf0cc3ec727041b91fd947d8fc31a6a395cadd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95fe9dd1af7a51cccc8c19390f1a73d430805a1102a20fad08d07c517d30e2d52bb9ac744e2645deb33a0e105382de05577f1971cfd09bf8ede8b95bd78c4bc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2213ca85111be751b5639190a7b8412c3fb2956deb8e894d11c7109245eedf7ff8c8bc46933f5060c0795275c1ebf80d103e951837cb7ce4c6c8e6821298123b"} 16:20:43 INFO - Running post-run listener: copy_logs_to_upload_dir 16:20:43 INFO - Copying logs to upload dir... 16:20:43 INFO - mkdir: /builds/slave/test/build/upload/logs 16:20:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.106033 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-09-08 16:20:43.993552) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 16:20:43.997579) ========= 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.KSflfz5VIR/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.0Uy9sxkGEF/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/626387ab8aaa23bb03d9ecb6211e8992a01d8c590a36cb076debb29b1fdee46208012c65c7b3a2920158f980cdbf0cc3ec727041b91fd947d8fc31a6a395cadd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95fe9dd1af7a51cccc8c19390f1a73d430805a1102a20fad08d07c517d30e2d52bb9ac744e2645deb33a0e105382de05577f1971cfd09bf8ede8b95bd78c4bc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2213ca85111be751b5639190a7b8412c3fb2956deb8e894d11c7109245eedf7ff8c8bc46933f5060c0795275c1ebf80d103e951837cb7ce4c6c8e6821298123b"} build_url:https://queue.taskcluster.net/v1/task/VkPgQpKjR-672wOStJoDfw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011211 build_url: 'https://queue.taskcluster.net/v1/task/VkPgQpKjR-672wOStJoDfw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/626387ab8aaa23bb03d9ecb6211e8992a01d8c590a36cb076debb29b1fdee46208012c65c7b3a2920158f980cdbf0cc3ec727041b91fd947d8fc31a6a395cadd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95fe9dd1af7a51cccc8c19390f1a73d430805a1102a20fad08d07c517d30e2d52bb9ac744e2645deb33a0e105382de05577f1971cfd09bf8ede8b95bd78c4bc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2213ca85111be751b5639190a7b8412c3fb2956deb8e894d11c7109245eedf7ff8c8bc46933f5060c0795275c1ebf80d103e951837cb7ce4c6c8e6821298123b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 16:20:44.030597) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 16:20:44.030943) ========= 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.KSflfz5VIR/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.0Uy9sxkGEF/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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.005010 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 16:20:44.082215) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 16:20:44.082555) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 16:20:44.082925) ========= ========= Total master_lag: 0.18 =========