builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0090 starttime: 1484202061.82 results: success (0) buildid: 20170111193617 builduid: 1af6877df77a401a82975b00b25e7179 revision: 74b16b5e1e8f3ff2467f488a24332ba819dd3201 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 22:21:01.818831) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 22:21:01.819416) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 22:21:01.819690) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 22:21:01.934679) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 22:21:01.935008) ========= 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.Kpi1eMKemA/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.jx77udWlSL/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-11 22:21:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.61M=0.001s 2017-01-11 22:21:02 (9.61 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.464718 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 22:21:02.436391) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 22:21:02.436869) ========= 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.Kpi1eMKemA/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.jx77udWlSL/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.026316 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 22:21:02.487177) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-11 22:21:02.487582) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 74b16b5e1e8f3ff2467f488a24332ba819dd3201 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 74b16b5e1e8f3ff2467f488a24332ba819dd3201 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Kpi1eMKemA/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.jx77udWlSL/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-11 22:21:02,585 truncating revision to first 12 chars 2017-01-11 22:21:02,585 Setting DEBUG logging. 2017-01-11 22:21:02,585 attempt 1/10 2017-01-11 22:21:02,585 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/74b16b5e1e8f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-11 22:21:03,480 unpacking tar archive at: mozilla-beta-74b16b5e1e8f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.171104 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-11 22:21:03.682612) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 22:21:03.682889) ========= script_repo_revision: 74b16b5e1e8f3ff2467f488a24332ba819dd3201 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 22:21:03.683245) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 22:21:03.683534) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 22:21:03.703631) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-01-11 22:21:03.703904) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Kpi1eMKemA/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.jx77udWlSL/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 22:21:03 INFO - MultiFileLogger online at 20170111 22:21:03 in /builds/slave/test 22:21:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 22:21:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:21:03 INFO - {'append_to_log': False, 22:21:03 INFO - 'base_work_dir': '/builds/slave/test', 22:21:03 INFO - 'blob_upload_branch': 'Mozilla-Beta', 22:21:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:21:03 INFO - 'branch': 'Mozilla-Beta', 22:21:03 INFO - 'buildbot_json_path': 'buildprops.json', 22:21:03 INFO - 'config_files': ('talos/mac_config.py',), 22:21:03 INFO - 'default_actions': ('clobber', 22:21:03 INFO - 'read-buildbot-config', 22:21:03 INFO - 'download-and-extract', 22:21:03 INFO - 'populate-webroot', 22:21:03 INFO - 'create-virtualenv', 22:21:03 INFO - 'install', 22:21:03 INFO - 'run-tests'), 22:21:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:21:03 INFO - 'download_minidump_stackwalk': True, 22:21:03 INFO - 'download_symbols': 'ondemand', 22:21:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:21:03 INFO - 'tooltool.py': '/tools/tooltool.py', 22:21:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:21:03 INFO - '/tools/misc-python/virtualenv.py')}, 22:21:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:21:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:21:03 INFO - 'installer_path': 'installer.exe', 22:21:03 INFO - 'log_level': 'info', 22:21:03 INFO - 'log_name': 'talos', 22:21:03 INFO - 'log_to_console': True, 22:21:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:21:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:21:03 INFO - 'opt_config_files': (), 22:21:03 INFO - 'pip_index': False, 22:21:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:21:03 INFO - 'cmd': ('bash', 22:21:03 INFO - '-c', 22:21:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:21:03 INFO - 'enabled': True, 22:21:03 INFO - 'halt_on_failure': False, 22:21:03 INFO - 'name': 'check_screen_resolution'},), 22:21:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:21:03 INFO - 'cmd': ('bash', 22:21:03 INFO - '-c', 22:21:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:21:03 INFO - 'enabled': True, 22:21:03 INFO - 'halt_on_failure': False, 22:21:03 INFO - 'name': 'check_screen_resolution'},), 22:21:03 INFO - 'run_cmd_checks_enabled': True, 22:21:03 INFO - 'sps_profile': False, 22:21:03 INFO - 'sps_profile_interval': 0, 22:21:03 INFO - 'suite': 'dromaeojs-e10s', 22:21:03 INFO - 'system_bits': '32', 22:21:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:21:03 INFO - 'title': 't-yosemite-r7-0090', 22:21:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:21:03 INFO - 'use_talos_json': True, 22:21:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:21:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:21:03 INFO - 'work_dir': 'build'} 22:21:03 INFO - [mozharness: 2017-01-12 06:21:03.906905Z] Running clobber step. 22:21:03 INFO - Running pre-action listener: _resource_record_pre_action 22:21:03 INFO - Running main action method: clobber 22:21:03 INFO - rmtree: /builds/slave/test/build 22:21:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:21:06 INFO - Running post-action listener: _resource_record_post_action 22:21:06 INFO - [mozharness: 2017-01-12 06:21:06.335650Z] Finished clobber step (success) 22:21:06 INFO - [mozharness: 2017-01-12 06:21:06.335818Z] Running read-buildbot-config step. 22:21:06 INFO - Running pre-action listener: _resource_record_pre_action 22:21:06 INFO - Running main action method: read_buildbot_config 22:21:06 INFO - Using buildbot properties: 22:21:06 INFO - { 22:21:06 INFO - "project": "", 22:21:06 INFO - "product": "firefox", 22:21:06 INFO - "script_repo_revision": "production", 22:21:06 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 22:21:06 INFO - "repository": "", 22:21:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 22:21:06 INFO - "buildid": "20170111193617", 22:21:06 INFO - "pgo_build": "False", 22:21:06 INFO - "basedir": "/builds/slave/test", 22:21:06 INFO - "buildnumber": 10, 22:21:06 INFO - "slavename": "t-yosemite-r7-0090", 22:21:06 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 22:21:06 INFO - "platform": "yosemite_r7", 22:21:06 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 22:21:06 INFO - "branch": "mozilla-beta", 22:21:06 INFO - "repo_path": "releases/mozilla-beta", 22:21:06 INFO - "revision": "74b16b5e1e8f3ff2467f488a24332ba819dd3201", 22:21:06 INFO - "stage_platform": "macosx64", 22:21:06 INFO - "builduid": "1af6877df77a401a82975b00b25e7179", 22:21:06 INFO - "slavebuilddir": "test" 22:21:06 INFO - } 22:21:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.dmg. 22:21:06 INFO - Running post-action listener: _resource_record_post_action 22:21:06 INFO - [mozharness: 2017-01-12 06:21:06.339475Z] Finished read-buildbot-config step (success) 22:21:06 INFO - [mozharness: 2017-01-12 06:21:06.339618Z] Running download-and-extract step. 22:21:06 INFO - Running pre-action listener: _resource_record_pre_action 22:21:06 INFO - Running main action method: download_and_extract 22:21:06 INFO - mkdir: /builds/slave/test/build/tests 22:21: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')]} 22:21:06 INFO - trying https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json 22:21:06 INFO - Downloading https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 22:21:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json'}, attempt #1 22:21:07 INFO - Downloaded 1165 bytes. 22:21:07 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 22:21:07 INFO - Using the following test package requirements: 22:21:07 INFO - {u'common': [u'firefox-51.0.en-US.mac.common.tests.zip'], 22:21:07 INFO - u'cppunittest': [u'firefox-51.0.en-US.mac.common.tests.zip', 22:21:07 INFO - u'firefox-51.0.en-US.mac.cppunittest.tests.zip'], 22:21:07 INFO - u'gtest': [u'firefox-51.0.en-US.mac.common.tests.zip', 22:21:07 INFO - u'firefox-51.0.en-US.mac.gtest.tests.zip'], 22:21:07 INFO - u'jittest': [u'firefox-51.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 22:21:07 INFO - u'mochitest': [u'firefox-51.0.en-US.mac.common.tests.zip', 22:21:07 INFO - u'firefox-51.0.en-US.mac.mochitest.tests.zip'], 22:21:07 INFO - u'mozbase': [u'firefox-51.0.en-US.mac.common.tests.zip'], 22:21:07 INFO - u'reftest': [u'firefox-51.0.en-US.mac.common.tests.zip', 22:21:07 INFO - u'firefox-51.0.en-US.mac.reftest.tests.zip'], 22:21:07 INFO - u'talos': [u'firefox-51.0.en-US.mac.common.tests.zip', 22:21:07 INFO - u'firefox-51.0.en-US.mac.talos.tests.zip'], 22:21:07 INFO - u'web-platform': [u'firefox-51.0.en-US.mac.common.tests.zip', 22:21:07 INFO - u'firefox-51.0.en-US.mac.web-platform.tests.zip'], 22:21:07 INFO - u'xpcshell': [u'firefox-51.0.en-US.mac.common.tests.zip', 22:21:07 INFO - u'firefox-51.0.en-US.mac.xpcshell.tests.zip']} 22:21:07 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip'] for test suite category: common 22:21:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 22:21:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 22:21:07 INFO - Fetch https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 22:21:07 INFO - Expected file size: 16235423 22:21:15 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip', u'firefox-51.0.en-US.mac.talos.tests.zip'] for test suite category: talos 22:21:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 22:21:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 22:21:15 INFO - Fetch https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 22:21:15 INFO - Expected file size: 16235423 22:21:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip 22:21:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip'}, attempt #1 22:21:22 INFO - Fetch https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip into memory 22:21:22 INFO - Expected file size: 14414089 22:21: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')]} 22:21:23 INFO - trying https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.dmg 22:21:23 INFO - Downloading https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0.en-US.mac.dmg 22:21:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg'}, attempt #1 22:21:26 INFO - Downloaded 86140400 bytes. 22:21:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.dmg 22:21:26 INFO - mkdir: /builds/slave/test/properties 22:21:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:21:26 INFO - Writing to file /builds/slave/test/properties/build_url 22:21:26 INFO - Contents: 22:21:26 INFO - build_url:https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.dmg 22:21:26 INFO - Running post-action listener: _resource_record_post_action 22:21:26 INFO - Running post-action listener: set_extra_try_arguments 22:21:26 INFO - [mozharness: 2017-01-12 06:21:26.757518Z] Finished download-and-extract step (success) 22:21:26 INFO - [mozharness: 2017-01-12 06:21:26.757614Z] Running populate-webroot step. 22:21:26 INFO - Running pre-action listener: _resource_record_pre_action 22:21:26 INFO - Running main action method: populate_webroot 22:21:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:21:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:21:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:21:26 INFO - '--tppagecycles', 22:21:26 INFO - '7'], 22:21:26 INFO - 'tests': ['tsvgm']}}, 22:21:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:21:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:21:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:21:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:21:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:26 INFO - 'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:21:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:21:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:26 INFO - 'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['damp', 'tps']}, 22:21:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:26 INFO - 'tests': ['damp', 'tps']}, 22:21:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['dromaeo_dom']}, 22:21:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:21:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['basic_compositor_video']}, 22:21:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:21:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['a11yr', 22:21:26 INFO - 'ts_paint', 22:21:26 INFO - 'tpaint', 22:21:26 INFO - 'sessionrestore', 22:21:26 INFO - 'sessionrestore_no_auto_restore', 22:21:26 INFO - 'tabpaint']}, 22:21:26 INFO - 'other-e10s': {'tests': ['a11yr', 22:21:26 INFO - 'ts_paint', 22:21:26 INFO - 'tpaint', 22:21:26 INFO - 'sessionrestore', 22:21:26 INFO - 'sessionrestore_no_auto_restore', 22:21:26 INFO - 'tabpaint']}, 22:21:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:21:26 INFO - 'ts_paint', 22:21:26 INFO - 'tpaint', 22:21:26 INFO - 'sessionrestore', 22:21:26 INFO - 'sessionrestore_no_auto_restore', 22:21:26 INFO - 'tabpaint']}, 22:21:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:21:26 INFO - 'ts_paint', 22:21:26 INFO - 'tpaint', 22:21:26 INFO - 'sessionrestore', 22:21:26 INFO - 'sessionrestore_no_auto_restore', 22:21:26 INFO - 'tabpaint']}, 22:21:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['a11yr', 22:21:26 INFO - 'ts_paint', 22:21:26 INFO - 'tpaint', 22:21:26 INFO - 'sessionrestore', 22:21:26 INFO - 'sessionrestore_no_auto_restore', 22:21:26 INFO - 'tabpaint']}, 22:21:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['a11yr', 22:21:26 INFO - 'ts_paint', 22:21:26 INFO - 'tpaint', 22:21:26 INFO - 'sessionrestore', 22:21:26 INFO - 'sessionrestore_no_auto_restore', 22:21:26 INFO - 'tabpaint']}, 22:21:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['tsvgx', 22:21:26 INFO - 'tsvgr_opacity', 22:21:26 INFO - 'tart', 22:21:26 INFO - 'tscrollx', 22:21:26 INFO - 'cart']}, 22:21:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:21:26 INFO - 'tsvgr_opacity', 22:21:26 INFO - 'tart', 22:21:26 INFO - 'tscrollx', 22:21:26 INFO - 'cart']}, 22:21:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:26 INFO - 'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['tp5o']}, 22:21:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:26 INFO - 'tests': ['tp5o']}, 22:21:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:26 INFO - 'talos_options': ['--disable-e10s', 22:21:26 INFO - '--xperf_path', 22:21:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:21:26 INFO - 'tests': ['tp5n']}, 22:21:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:26 INFO - 'talos_options': ['--xperf_path', 22:21:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:21:26 INFO - 'tests': ['tp5n']}}, 22:21:26 INFO - 'talos.zip': {'path': '', 22:21:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:21:26 INFO - Running post-action listener: _resource_record_post_action 22:21:26 INFO - [mozharness: 2017-01-12 06:21:26.776300Z] Finished populate-webroot step (success) 22:21:26 INFO - [mozharness: 2017-01-12 06:21:26.776411Z] Running create-virtualenv step. 22:21:26 INFO - Running pre-action listener: _resource_record_pre_action 22:21:26 INFO - Running main action method: create_virtualenv 22:21:26 INFO - Creating virtualenv /builds/slave/test/build/venv 22:21:26 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 22:21:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:21:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:21:26 INFO - Using real prefix '/tools/python27' 22:21:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:21:27 INFO - Installing distribute.............................................................................................................................................................................................done. 22:21:30 INFO - Installing pip.................done. 22:21:30 INFO - Return code: 0 22:21:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:21:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:30 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 0x103def490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d6157be60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c03d30>, '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 0x103a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfbb20>, '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.Kpi1eMKemA/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.jx77udWlSL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 22:21:30 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 22:21:30 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 22:21:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kpi1eMKemA/Render', 22:21:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:30 INFO - 'HOME': '/Users/cltbld', 22:21:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:30 INFO - 'LOGNAME': 'cltbld', 22:21:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:30 INFO - 'MOZ_NO_REMOTE': '1', 22:21:30 INFO - 'NO_EM_RESTART': '1', 22:21:30 INFO - 'PAGER': '/bin/cat', 22:21:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:30 INFO - 'PWD': '/builds/slave/test', 22:21:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:30 INFO - 'SHELL': '/bin/bash', 22:21:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jx77udWlSL/Listeners', 22:21:30 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 22:21:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:30 INFO - 'USER': 'cltbld', 22:21:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:30 INFO - 'XPC_FLAGS': '0x0', 22:21:30 INFO - 'XPC_SERVICE_NAME': '0', 22:21:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:30 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 22:21:30 INFO - Cleaning up... 22:21:30 INFO - Return code: 0 22:21:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:21:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:30 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 0x103def490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d6157be60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c03d30>, '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 0x103a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfbb20>, '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.Kpi1eMKemA/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.jx77udWlSL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 22:21:30 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 22:21:30 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 22:21:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kpi1eMKemA/Render', 22:21:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:30 INFO - 'HOME': '/Users/cltbld', 22:21:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:30 INFO - 'LOGNAME': 'cltbld', 22:21:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:30 INFO - 'MOZ_NO_REMOTE': '1', 22:21:30 INFO - 'NO_EM_RESTART': '1', 22:21:30 INFO - 'PAGER': '/bin/cat', 22:21:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:30 INFO - 'PWD': '/builds/slave/test', 22:21:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:30 INFO - 'SHELL': '/bin/bash', 22:21:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jx77udWlSL/Listeners', 22:21:30 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 22:21:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:30 INFO - 'USER': 'cltbld', 22:21:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:30 INFO - 'XPC_FLAGS': '0x0', 22:21:30 INFO - 'XPC_SERVICE_NAME': '0', 22:21:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:31 INFO - Downloading/unpacking psutil>=3.1.1 22:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:21:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:21:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:21:34 INFO - Installing collected packages: psutil 22:21:34 INFO - Running setup.py install for psutil 22:21:34 INFO - building 'psutil._psutil_osx' extension 22:21:34 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 22:21:35 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 22:21:35 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 22:21:35 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 22:21:35 INFO - building 'psutil._psutil_posix' extension 22:21:35 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 22:21:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:21:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:21:35 INFO - ^ 22:21:35 INFO - 1 warning generated. 22:21:35 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 22:21:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:21:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:21:35 INFO - Successfully installed psutil 22:21:35 INFO - Cleaning up... 22:21:35 INFO - Return code: 0 22:21:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:21:35 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')]} 22:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:35 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')]} 22:21:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:35 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 0x103def490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d6157be60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c03d30>, '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 0x103a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfbb20>, '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.Kpi1eMKemA/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.jx77udWlSL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 22:21:35 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 22:21:35 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 22:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kpi1eMKemA/Render', 22:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:35 INFO - 'HOME': '/Users/cltbld', 22:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:35 INFO - 'LOGNAME': 'cltbld', 22:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:35 INFO - 'MOZ_NO_REMOTE': '1', 22:21:35 INFO - 'NO_EM_RESTART': '1', 22:21:35 INFO - 'PAGER': '/bin/cat', 22:21:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:35 INFO - 'PWD': '/builds/slave/test', 22:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:35 INFO - 'SHELL': '/bin/bash', 22:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jx77udWlSL/Listeners', 22:21:35 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 22:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:35 INFO - 'USER': 'cltbld', 22:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:35 INFO - 'XPC_FLAGS': '0x0', 22:21:35 INFO - 'XPC_SERVICE_NAME': '0', 22:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:21:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:21:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:21:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:21:38 INFO - Installing collected packages: mozsystemmonitor 22:21:38 INFO - Running setup.py install for mozsystemmonitor 22:21:38 INFO - Successfully installed mozsystemmonitor 22:21:38 INFO - Cleaning up... 22:21:38 INFO - Return code: 0 22:21:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:21:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:38 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 0x103def490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d6157be60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c03d30>, '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 0x103a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfbb20>, '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.Kpi1eMKemA/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.jx77udWlSL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 22:21:38 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 22:21:38 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 22:21:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kpi1eMKemA/Render', 22:21:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:38 INFO - 'HOME': '/Users/cltbld', 22:21:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:38 INFO - 'LOGNAME': 'cltbld', 22:21:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:38 INFO - 'MOZ_NO_REMOTE': '1', 22:21:38 INFO - 'NO_EM_RESTART': '1', 22:21:38 INFO - 'PAGER': '/bin/cat', 22:21:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:38 INFO - 'PWD': '/builds/slave/test', 22:21:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:38 INFO - 'SHELL': '/bin/bash', 22:21:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jx77udWlSL/Listeners', 22:21:38 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 22:21:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:38 INFO - 'USER': 'cltbld', 22:21:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:38 INFO - 'XPC_FLAGS': '0x0', 22:21:38 INFO - 'XPC_SERVICE_NAME': '0', 22:21:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:39 INFO - Downloading/unpacking jsonschema==2.5.1 22:21:39 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 22:21:39 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 22:21:39 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 22:21:39 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 22:21:39 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 22:21:39 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 22:21:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:21:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:42 INFO - Downloading functools32-3.2.3-2.tar.gz 22:21:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:21:42 INFO - warning: no files found matching '*.txt' 22:21:42 INFO - no previously-included directories found matching 'build' 22:21:42 INFO - no previously-included directories found matching 'dist' 22:21:42 INFO - no previously-included directories found matching '.git*' 22:21:42 INFO - Installing collected packages: jsonschema, functools32 22:21:42 INFO - Running setup.py install for functools32 22:21:42 INFO - warning: no files found matching '*.txt' 22:21:42 INFO - no previously-included directories found matching 'build' 22:21:42 INFO - no previously-included directories found matching 'dist' 22:21:42 INFO - no previously-included directories found matching '.git*' 22:21:42 INFO - Successfully installed jsonschema functools32 22:21:42 INFO - Cleaning up... 22:21:42 INFO - Return code: 0 22:21:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103def490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d6157be60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c03d30>, '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 0x103a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfbb20>, '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.Kpi1eMKemA/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.jx77udWlSL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 22:21:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:21:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:21:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kpi1eMKemA/Render', 22:21:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:42 INFO - 'HOME': '/Users/cltbld', 22:21:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:42 INFO - 'LOGNAME': 'cltbld', 22:21:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:42 INFO - 'MOZ_NO_REMOTE': '1', 22:21:42 INFO - 'NO_EM_RESTART': '1', 22:21:42 INFO - 'PAGER': '/bin/cat', 22:21:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:42 INFO - 'PWD': '/builds/slave/test', 22:21:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:42 INFO - 'SHELL': '/bin/bash', 22:21:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jx77udWlSL/Listeners', 22:21:42 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 22:21:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:42 INFO - 'USER': 'cltbld', 22:21:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:42 INFO - 'XPC_FLAGS': '0x0', 22:21:42 INFO - 'XPC_SERVICE_NAME': '0', 22:21:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:21:42 INFO - Cleaning up... 22:21:42 INFO - Return code: 0 22:21:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103def490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d6157be60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c03d30>, '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 0x103a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfbb20>, '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.Kpi1eMKemA/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.jx77udWlSL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 22:21:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:21:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:21:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kpi1eMKemA/Render', 22:21:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:42 INFO - 'HOME': '/Users/cltbld', 22:21:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:42 INFO - 'LOGNAME': 'cltbld', 22:21:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:42 INFO - 'MOZ_NO_REMOTE': '1', 22:21:42 INFO - 'NO_EM_RESTART': '1', 22:21:42 INFO - 'PAGER': '/bin/cat', 22:21:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:42 INFO - 'PWD': '/builds/slave/test', 22:21:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:42 INFO - 'SHELL': '/bin/bash', 22:21:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jx77udWlSL/Listeners', 22:21:42 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 22:21:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:42 INFO - 'USER': 'cltbld', 22:21:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:42 INFO - 'XPC_FLAGS': '0x0', 22:21:42 INFO - 'XPC_SERVICE_NAME': '0', 22:21:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:43 INFO - Downloading/unpacking blobuploader==1.2.4 22:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:45 INFO - Downloading blobuploader-1.2.4.tar.gz 22:21:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:21:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:21:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:21:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:47 INFO - Downloading docopt-0.6.1.tar.gz 22:21:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:21:47 INFO - Installing collected packages: blobuploader, requests, docopt 22:21:47 INFO - Running setup.py install for blobuploader 22:21:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:21:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:21:47 INFO - Running setup.py install for requests 22:21:47 INFO - Running setup.py install for docopt 22:21:47 INFO - Successfully installed blobuploader requests docopt 22:21:47 INFO - Cleaning up... 22:21:47 INFO - Return code: 0 22:21:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21:47 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')]} 22:21:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:47 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')]} 22:21:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:47 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 0x103def490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d6157be60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c03d30>, '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 0x103a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfbb20>, '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.Kpi1eMKemA/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.jx77udWlSL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 22:21:47 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 22:21:47 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 22:21:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kpi1eMKemA/Render', 22:21:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:47 INFO - 'HOME': '/Users/cltbld', 22:21:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:47 INFO - 'LOGNAME': 'cltbld', 22:21:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:47 INFO - 'MOZ_NO_REMOTE': '1', 22:21:47 INFO - 'NO_EM_RESTART': '1', 22:21:47 INFO - 'PAGER': '/bin/cat', 22:21:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:47 INFO - 'PWD': '/builds/slave/test', 22:21:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:47 INFO - 'SHELL': '/bin/bash', 22:21:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jx77udWlSL/Listeners', 22:21:47 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 22:21:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:47 INFO - 'USER': 'cltbld', 22:21:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:47 INFO - 'XPC_FLAGS': '0x0', 22:21:47 INFO - 'XPC_SERVICE_NAME': '0', 22:21:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:21:48 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-GLSV0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:21:48 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-UkKAcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:21:48 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-hfp7ct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:21:48 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-6S90ZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:21:48 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-_MM1aW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:21:48 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-nkk8F5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:21:48 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-Nn2q7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:21:48 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-KcYtCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:21:49 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-JDuZ7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:21:49 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-4K9r4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:21:49 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-gGBQBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:21:49 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-lrQ_Db-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:21:49 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-Se6Ppj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:21:49 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-HDuzeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:49 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-Td8VTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:21:49 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-K9m2GP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:21:49 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-7IE4Bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:21:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:21:50 INFO - Running setup.py install for manifestparser 22:21:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:21:50 INFO - Running setup.py install for mozcrash 22:21:50 INFO - Running setup.py install for mozdebug 22:21:50 INFO - Running setup.py install for mozdevice 22:21:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:21:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:21:50 INFO - Running setup.py install for mozfile 22:21:50 INFO - Running setup.py install for mozhttpd 22:21:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:21:50 INFO - Running setup.py install for mozinfo 22:21:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:21:51 INFO - Running setup.py install for mozInstall 22:21:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:21:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:21:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:21:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:21:51 INFO - Running setup.py install for mozleak 22:21:51 INFO - Running setup.py install for mozlog 22:21:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:21:51 INFO - Running setup.py install for moznetwork 22:21:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:21:51 INFO - Running setup.py install for mozprocess 22:21:51 INFO - Running setup.py install for mozprofile 22:21:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:21:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:21:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:21:51 INFO - Running setup.py install for mozrunner 22:21:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:21:52 INFO - Running setup.py install for mozscreenshot 22:21:52 INFO - Running setup.py install for moztest 22:21:52 INFO - Running setup.py install for mozversion 22:21:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:21:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:21:52 INFO - Cleaning up... 22:21:52 INFO - Return code: 0 22:21:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:52 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 0x103def490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d6157be60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c03d30>, '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 0x103a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfbb20>, '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.Kpi1eMKemA/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.jx77udWlSL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 22:21:52 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 22:21:52 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 22:21:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kpi1eMKemA/Render', 22:21:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:52 INFO - 'HOME': '/Users/cltbld', 22:21:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:52 INFO - 'LOGNAME': 'cltbld', 22:21:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:52 INFO - 'MOZ_NO_REMOTE': '1', 22:21:52 INFO - 'NO_EM_RESTART': '1', 22:21:52 INFO - 'PAGER': '/bin/cat', 22:21:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:52 INFO - 'PWD': '/builds/slave/test', 22:21:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:52 INFO - 'SHELL': '/bin/bash', 22:21:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jx77udWlSL/Listeners', 22:21:52 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 22:21:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:52 INFO - 'USER': 'cltbld', 22:21:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:52 INFO - 'XPC_FLAGS': '0x0', 22:21:52 INFO - 'XPC_SERVICE_NAME': '0', 22:21:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:21:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-HkDxUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:21:52 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)) 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:21:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-LVDOck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:21:53 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)) 22:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:21:53 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-wY0zin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:21:53 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)) 22:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:21:53 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-qzDIsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:21:53 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)) 22:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:21:53 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-6ijI8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:21:53 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)) 22:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:21:53 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-VX6kNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:21:53 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)) 22:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:21:53 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-ONy4Gy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:21:53 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)) 22:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:21:53 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-GHZXIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:21:53 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)) 22:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:21:53 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-7W4IoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:21:53 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)) 22:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:21:53 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-1WyEJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:21:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:21:53 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-7O81RZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:21:54 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)) 22:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:21:54 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-CGPNRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:21:54 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)) 22:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:21:54 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-nY2LTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:21:54 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)) 22:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:21:54 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-1ZdERX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:21:54 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)) 22:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:54 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-etLIEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:54 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)) 22:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:21:54 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-5rW4_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:21:54 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)) 22:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:21:54 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-8vSXuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:21:54 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)) 22:21:54 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)) 22:21:54 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)) 22:21:54 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)) 22:21:54 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)) 22:21:54 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)) 22:21:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:21:54 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 22:21:54 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 22:21:54 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 22:21:54 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 22:21:54 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 22:21:54 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 22:21:57 INFO - Downloading blessings-1.6.tar.gz 22:21:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:21:57 INFO - Installing collected packages: blessings 22:21:57 INFO - Running setup.py install for blessings 22:21:57 INFO - Successfully installed blessings 22:21:57 INFO - Cleaning up... 22:21:57 INFO - Return code: 0 22:21:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:21:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:21:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:21:57 INFO - Reading from file tmpfile_stdout 22:21:57 INFO - Current package versions: 22:21:57 INFO - blessings == 1.6 22:21:57 INFO - blobuploader == 1.2.4 22:21:57 INFO - docopt == 0.6.1 22:21:57 INFO - functools32 == 3.2.3-2 22:21:57 INFO - jsonschema == 2.5.1 22:21:57 INFO - manifestparser == 1.1 22:21:57 INFO - mozInstall == 1.12 22:21:57 INFO - mozcrash == 0.17 22:21:57 INFO - mozdebug == 0.1 22:21:57 INFO - mozdevice == 0.48 22:21:57 INFO - mozfile == 1.2 22:21:57 INFO - mozhttpd == 0.7 22:21:57 INFO - mozinfo == 0.9 22:21:57 INFO - mozleak == 0.1 22:21:57 INFO - mozlog == 3.4 22:21:57 INFO - moznetwork == 0.27 22:21:57 INFO - mozprocess == 0.23 22:21:57 INFO - mozprofile == 0.28 22:21:57 INFO - mozrunner == 6.12 22:21:57 INFO - mozscreenshot == 0.1 22:21:57 INFO - mozsystemmonitor == 0.3 22:21:57 INFO - moztest == 0.7 22:21:57 INFO - mozversion == 1.4 22:21:57 INFO - psutil == 3.1.1 22:21:57 INFO - requests == 1.2.3 22:21:57 INFO - wsgiref == 0.1.2 22:21:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:57 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 0x103def490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d6157be60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c03d30>, '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 0x103a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfbb20>, '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.Kpi1eMKemA/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.jx77udWlSL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 22:21:57 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 22:21:57 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 22:21:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kpi1eMKemA/Render', 22:21:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:57 INFO - 'HOME': '/Users/cltbld', 22:21:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:57 INFO - 'LOGNAME': 'cltbld', 22:21:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:57 INFO - 'MOZ_NO_REMOTE': '1', 22:21:57 INFO - 'NO_EM_RESTART': '1', 22:21:57 INFO - 'PAGER': '/bin/cat', 22:21:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:57 INFO - 'PWD': '/builds/slave/test', 22:21:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:57 INFO - 'SHELL': '/bin/bash', 22:21:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jx77udWlSL/Listeners', 22:21:57 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 22:21:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:57 INFO - 'USER': 'cltbld', 22:21:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:57 INFO - 'XPC_FLAGS': '0x0', 22:21:57 INFO - 'XPC_SERVICE_NAME': '0', 22:21:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:58 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)) 22:21:58 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)) 22:21:58 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)) 22:21:58 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)) 22:21:58 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)) 22:21:58 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)) 22:21:58 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)) 22:21:58 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)) 22:21:58 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)) 22:21:58 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)) 22:21:58 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)) 22:21:58 INFO - Cleaning up... 22:21:58 INFO - Return code: 0 22:21:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:21:58 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')]} 22:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:58 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')]} 22:21:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:58 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 0x103def490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d65570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d6157be60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c03d30>, '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 0x103a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfbb20>, '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.Kpi1eMKemA/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.jx77udWlSL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 22:21:58 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 22:21:58 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 22:21:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kpi1eMKemA/Render', 22:21:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:58 INFO - 'HOME': '/Users/cltbld', 22:21:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:58 INFO - 'LOGNAME': 'cltbld', 22:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:58 INFO - 'MOZ_NO_REMOTE': '1', 22:21:58 INFO - 'NO_EM_RESTART': '1', 22:21:58 INFO - 'PAGER': '/bin/cat', 22:21:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:58 INFO - 'PWD': '/builds/slave/test', 22:21:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:58 INFO - 'SHELL': '/bin/bash', 22:21:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jx77udWlSL/Listeners', 22:21:58 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 22:21:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:58 INFO - 'USER': 'cltbld', 22:21:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:58 INFO - 'XPC_FLAGS': '0x0', 22:21:58 INFO - 'XPC_SERVICE_NAME': '0', 22:21:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:21:58 INFO - Cleaning up... 22:21:58 INFO - Return code: 0 22:21:58 INFO - Running post-action listener: _resource_record_post_action 22:21:58 INFO - Running post-action listener: _start_resource_monitoring 22:21:58 INFO - Starting resource monitoring. 22:21:58 INFO - [mozharness: 2017-01-12 06:21:58.510012Z] Finished create-virtualenv step (success) 22:21:58 INFO - [mozharness: 2017-01-12 06:21:58.510952Z] Running install step. 22:21:58 INFO - Running pre-action listener: _resource_record_pre_action 22:21:58 INFO - Running main action method: install 22:21:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:21:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:21:58 INFO - Reading from file tmpfile_stdout 22:21:58 INFO - Detecting whether we're running mozinstall >=1.0... 22:21:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:21:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:21:58 INFO - Reading from file tmpfile_stdout 22:21:58 INFO - Output received: 22:21:58 INFO - Usage: mozinstall [options] installer 22:21:58 INFO - Options: 22:21:58 INFO - -h, --help show this help message and exit 22:21:58 INFO - -d DEST, --destination=DEST 22:21:58 INFO - Directory to install application into. [default: 22:21:58 INFO - "/builds/slave/test"] 22:21:58 INFO - --app=APP Application being installed. [default: firefox] 22:21:58 INFO - mkdir: /builds/slave/test/build/application 22:21:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:21:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.mac.dmg --destination /builds/slave/test/build/application 22:22:20 INFO - Reading from file tmpfile_stdout 22:22:20 INFO - Output received: 22:22:20 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 22:22:20 INFO - Running post-action listener: _resource_record_post_action 22:22:20 INFO - [mozharness: 2017-01-12 06:22:20.770644Z] Finished install step (success) 22:22:20 INFO - [mozharness: 2017-01-12 06:22:20.770806Z] Running run-tests step. 22:22:20 INFO - Running pre-action listener: _resource_record_pre_action 22:22:20 INFO - Running main action method: run_tests 22:22:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:22:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:22:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:22:20 INFO - 2017-01-11 22:22:20.785 screenresolution[1634:8842] starting screenresolution argv=screenresolution get 22:22:20 INFO - 2017-01-11 22:22:20.787 screenresolution[1634:8842] Display 0: 1600x1200x32@60 22:22:20 INFO - 2017-01-11 22:22:20.793 screenresolution[1635:8845] starting screenresolution argv=screenresolution list 22:22:20 INFO - Available Modes on Display 0 22:22:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:22:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:22:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:22:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:22:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:22:21 INFO - Intel Iris: 22:22:21 INFO - Chipset Model: Intel Iris 22:22:21 INFO - Type: GPU 22:22:21 INFO - Bus: Built-In 22:22:21 INFO - VRAM (Dynamic, Max): 1536 MB 22:22:21 INFO - Vendor: Intel (0x8086) 22:22:21 INFO - Device ID: 0x0a2e 22:22:21 INFO - Revision ID: 0x0009 22:22:21 INFO - Displays: 22:22:21 INFO - Display: 22:22:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:22:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:22:21 INFO - Display Serial Number: 1600x1200 60 22:22:21 INFO - Main Display: Yes 22:22:21 INFO - Mirror: Off 22:22:21 INFO - Online: Yes 22:22:21 INFO - Rotation: Supported 22:22:21 INFO - Return code: 0 22:22:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:22:21 INFO - Python 2.7.3 22:22:21 INFO - Return code: 0 22:22:21 INFO - grabbing minidump binary from tooltool 22:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:22:21 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c03d30>, '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 0x103a69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:22:21 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:22:21 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 22:22:21 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:22:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:22:21 INFO - Return code: 0 22:22:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:22:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:22:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:22:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:22:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:22:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--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 22:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0090 --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 22:22:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kpi1eMKemA/Render', 22:22:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:22:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:22:21 INFO - 'HOME': '/Users/cltbld', 22:22:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:22:21 INFO - 'LOGNAME': 'cltbld', 22:22:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:22:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:21 INFO - 'MOZ_NO_REMOTE': '1', 22:22:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:22:21 INFO - 'NO_EM_RESTART': '1', 22:22:21 INFO - 'PAGER': '/bin/cat', 22:22:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:21 INFO - 'PWD': '/builds/slave/test', 22:22:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:22:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:22:21 INFO - 'SHELL': '/bin/bash', 22:22:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jx77udWlSL/Listeners', 22:22:21 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 22:22:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:22:21 INFO - 'USER': 'cltbld', 22:22:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:22:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:21 INFO - 'XPC_FLAGS': '0x0', 22:22:21 INFO - 'XPC_SERVICE_NAME': '0', 22:22:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:22:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--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 22:22:21 INFO - mozversion application_buildid: 20170111193617 22:22:21 INFO - mozversion application_changeset: 74b16b5e1e8f3ff2467f488a24332ba819dd3201 22:22:21 INFO - mozversion application_display_name: Firefox 22:22:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:22:21 INFO - mozversion application_name: Firefox 22:22:21 INFO - mozversion application_remotingname: firefox 22:22:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 22:22:21 INFO - mozversion application_vendor: Mozilla 22:22:21 INFO - mozversion application_version: 51.0 22:22:21 INFO - mozversion platform_buildid: 20170111193617 22:22:21 INFO - mozversion platform_changeset: 74b16b5e1e8f3ff2467f488a24332ba819dd3201 22:22:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 22:22:21 INFO - mozversion platform_version: 51.0 22:22:21 INFO - using testdate: 1484202141 22:22:21 INFO - actual date: 1484202141 22:22:21 INFO - starting webserver on 'localhost:49240' 22:22:21 INFO - SUITE-START | Running 2 tests 22:22:21 INFO - TEST-START | dromaeo_css 22:22:21 INFO - operating with platform_type : mac_ 22:22:21 INFO - Initialising browser for dromaeo_css test... 22:22:21 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpSV9IIh/profile http://localhost:49240/getInfo.html) 22:22:24 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 22:22:24 INFO - PROCESS | 1645 | colorDepth:24 22:22:24 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 22:22:24 INFO - PROCESS | 1645 | __metrics 22:22:24 ERROR - PROCESS | 1645 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3015: TypeError: this.tabs is undefined 22:22:24 INFO - TEST-INFO | 1645: exit 0 22:22:24 INFO - Browser initialized. 22:22:24 INFO - Running cycle 1/1 for dromaeo_css test... 22:22:24 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpSV9IIh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:22:36 INFO - PROCESS | 1650 | RSS: Main: 174641152 22:22:36 INFO - PROCESS | 1650 | 22:23:26 INFO - PROCESS | 1650 | 1484202206299 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] 22:25:37 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 22:25:38 INFO - PROCESS | 1650 | RSS: Main: 188006400 22:25:38 INFO - PROCESS | 1650 | 22:28:40 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 22:28:41 INFO - PROCESS | 1650 | RSS: Main: 197423104 22:28:41 INFO - PROCESS | 1650 | 22:31:43 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 22:31:43 INFO - PROCESS | 1650 | RSS: Main: 200888320 22:31:43 INFO - PROCESS | 1650 | 22:34:45 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 22:34:46 INFO - PROCESS | 1650 | RSS: Main: 205279232 22:34:46 INFO - PROCESS | 1650 | 22:37:49 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 22:37:49 INFO - PROCESS | 1650 | RSS: Main: 207392768 22:37:49 INFO - PROCESS | 1650 | 22:40:52 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 22:40:52 INFO - PROCESS | 1650 | RSS: Main: 208064512 22:40:52 INFO - PROCESS | 1650 | 22:40:52 INFO - PROCESS | 1650 | __start_tp_report 22:40:52 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 22:40:52 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 22:40:52 INFO - PROCESS | 1650 | |i|pagename|runs| 22:40:52 INFO - PROCESS | 1650 | |0;dojo.html;3529.3117842020806;3625.093726568358;3627;3630.2739452109568;3678.3195108904856;5874.853128671785;5956.1065840124;5964.9999999999945;5978.910316345247;5992;5900.675462849542;5906.704664766766;5968.820935371935;5973.074173503103;5985.6109352892145;5323.414424413311;5334.226537152111;5340.092184328654;5393.379761327458;5510.338759348863;5402.37872644645;5404.135338345865;5425.755840987165;5435.130379139338;5438.456154384549;5272.6836389816735;5278.894422111555;5372.274742909716;5404.351477822667;5456.154296084113;5333.2000199969925;5339.813106541291;5343.118385466393;5348.866278343034;5369.328833895763;5274.525292723673;5298.231756395301;5306.495882891119;5391.48780865821;5394.91907621386;4840.733759643218;5133.794648214067;5725.255716756795;6002.969985150046;6050.69746512677;6153.569250152491;6236.688165591702;6248.1564988726395;6273.215848018997;6282;6364.490840732777;6383;6385.808425747188;6396.712147953307;6412.102305677209;5919.704014799199;5996.100584912211;6004.6097003695495;6103.938960610381;6116.663583502906;5233.319668443078;6474.514411419163;6480.935190648128;6485.513586481033;6487;6254.874902501924;6285.937140628535;6288.717007734663;6289.276733175743;6298.653574053471;5168.3539557555305;6145.600535965148;6345.714442850082;6347.619142851351;6433;6176.7838125665385;6223.0354295084335;6244.594101383486;6280.497560195174;6286.528510361742;6271.62370257786;6276.340984196685;6279.183706118267;6285.2457705075685;6287.245530536274;4931.7040977541465;5061.063703214917;5103;5116.923246151311;5119.820806271762;4432.889177770581;4482.5069242383315;4518.841840535565;4582.564656357641;4594;5227.581793456515;6022.849428764316;6107.511399088063;6107.786227482017;6129.172561704202;6179.62922224659;6222.0355844844125;6331.746730130835;6345.428911397997;6349.492040636738;5998.1002849572915;6016.939830601726;6026.54800889935;6088.695565221721;6126.908096378558;4059.5534491205945;5482.205080263419;5524.696141712164;5535.892821435729;5556.083246264402;6277.3408792078;6292.590981586182;6530.640814755139;6540.37866402691;6565.080888675589;6199.380061993765;6236.563440559117;6239.469645080219;6328.14570033058;6332.968335158479;6527.51043671717;6547.672616369257;6551;6559.409653131241;6575;5220.825314304403;5284.735763211901;6264.9976003839165;6303;6317.589356691984;5209.973950130225;6169.722362493789;6194.473469755121;6195.597286176384;6273.843153921189;6119.877602448015;6222.160008398808;6275.309715931336;6293.307736148929;6304.211973503312;6157.168782214344;6203.2245969253845;6225.533085018551;6239.688015599111;6278.215223097113;4745;5893.9115913260475;5904.911426328695;6082.817515474543;6116.35778243278;6191.4118158774845;6206.813795586139;6215;6222.81331560054;6248.218972628421;6103;6185.443310102112;6186.536009799193;6190.7523699051535;6191.597546159485 22:40:52 INFO - PROCESS | 1650 | |1;ext.html;485.3132817063855;497.234259240769;539.0889396919206;560.7728869807729;574.1244601981977;40661.59338406615;41977.79011104944;42135.78932105339;44656.106877862425;44689.55310446903;43644.563554364446;43791.34312985308;44104;44138.337924931155;44207.55792442075;10014.098731114198;10192.592296308176;10216.591336346537;10219.182465402788;10226.590936362536;10034.749131271703;10131.088202061814;10136.645217417392;10137.645182418617;10166.898331016706;9871.03868839349;9921.75195620108;9983.101520863122;10092.89907100927;10129.746756331077;10049.296549241555;10103.444310562916;10157.593696252216;10158.28891977562;10213.897861021444;10058.295919285652;10139;10154.238432117621;10155.238357123246;10172;42227.788861055495;42268.365974510096;42288.1542369154;42575.1484970302;42671.35992960077;3514.8242587870504;3634.745567810254;3701.7408781385316;3714.4242642390473;4362.694611377205;31854.681453185403;32615.836920815244;32617;32619.6738032619;32637.836810815796;3379.7972121672533;3421.7775844570024;3441.5009823575692;3475.878344257939;3488.8255587220538;502626;503285;521255;522885;523835;61644.383556164765;62184;64254;64504;64698;35538;35548.111297217256;35801.64198357983;37251.25497490035;37406.43890341647;17702.380416685355;17778.111094445485;18045.00752458601;18046.187921543562;18158.364457243933;12328.445219965122;13558.254296013616;13813.378397972116;13949.302534873419;13979.860201397856;17423;19024.048797560066;19835.900820495805;19882.60234795325;19908.70136947947;8323.750287491264;8360.372972027122;8425.873611895826;8456.619452124669;8500.53247071417;819.3076850061689;900.7387857521377;1019.9235057370727;1024.8155332040305;1038.361407734238;25646.871765641426;31357;31568.684313157035;31620.367592648017;32111.35777284441;964.7925695975399;1051.7580956380075;1120.294214644785;1124.7244425115734;1129.1418521923233;13361.93319033418;16248.431304904509;16359.83640163607;16394.918025410036;16683.33266669344;638905;650776;656879;657463;660007;59409.405905941254;59444.70277648584;59537.70231148817;60110;60178.69910650506;1429.7212043651596;1446.168453139428;1460.4304321314696;1465.4724299252262;1468.185157237722;29573.408531829675;30663.926762563202;31007.36710419212;31210.531842022843;31449.685503144676;42770.35844462398;49392.01215975732;50315.496845031084;50542.7364315895;50672;576264;576998;581115;581627;581682;26977.595336070368;27151.321216969733;27528.174154775388;27586;27619.86190069037;48268.517314827805;48859.511404885496;49209.769755756395;49342.75328623334;50335.74832125816;39445.80277098596;41181.588184117776;41416;41693.58306417018;41875.7906210467;40084.79957600193;40308.39537406882;40876.38685419781;41229.1754164909;41654.37518437165;34094.147646309044;34884;37622.62377376191;39558.604413955494;40357.39463907985;34459.655403446646;35849.46225806667;36000.81999589985;36608.633913661586;36691.63308366882;26770.732292677603;26956.786944587147;26975;27043.323916902234;27487.76305066237 22:40:52 INFO - PROCESS | 1650 | |2;jquery.html;387.6859743607676;388.71817931999306;391.4617401073524;396.8908550148708;415.70093457943926;17443.21505532251;17607.735883961752;20736.585268294624;22157.778422215793;22174.223902163427;21008.159673613074;21101.15595376187;21105.26131585395;21247.36257912265;21407;5741.99999999999;6208.348123447041;6242.7502899884175;6252.906206406908;6265.812025639215;6064.272287325527;6090.624656384014;6091.939080609203;6111.724972376253;6164.506839452855;5259.3951695555115;6022.518198544106;6126.663033533154;6139.969300153493;6156.015037593964;5221.347331583552;5250.976059668366;6095.695215239242;6143.354947730491;6196.194494715658;5413.783448662049;5418.4039755626845;5420.620556561043;5422.8915421691345;5435.021696094701;25888.964441422322;26023;26496.867515662492;26648;26780.59829102565;2139.3474990128007;2322.930312090623;2519.596864501689;2565.7819085377764;2704.3779930615874;15529.301181446863;16148.75776863348;16230.10734409607;16354.754300609953;16607.50177494677;1907.523119220195;2021.5148364392587;2022.8482863785275;2025.5543780368444;2025.929092481756;115698;118219.40890295494;118629;119318.99999999913;119411;11222.382768947624;11287.661370158909;11442.199046066695;11475.942620286845;11821.822672659917;14649.560513184622;14663.34014969329;14675.972681912164;14694.55916322512;14708.485203017843;9044.547772611342;9088.000319964798;9145.77135571616;9146.948100968473;9231;9202.401843880127;9313.068693130634;9336.486493242937;9356.298277629205;9513.619455221851;7068.752593659197;7139.286071392923;7143.249958754256;7163.820904477429;7186.56880587156;1885.8019907909747;1886.6981282994655;1901.5531350132708;1934;1942.805719428046;859.6733241368365;944.5371767833713;953.8855337359561;1062.166199533378;1065.2543219746208;21971.34085977423;22126.22558210487;23233.186838460573;23411.7658823413;23519.294421167393;876.7764220123955;1004.9296549241483;1022.2333250062453;1036.9896301036893;1038.870141232346;11775.05799536035;12345.321007344683;12397.876021239852;12481.063920206021;12540.05949553769;593306.4355449276;597657;607135;642753;644882;81654;96071;121772;125559;129290.35354823165;816.546816516827;863.1799790199336;942.6370847223731;963.5182408795603;963.7301555564453;20385.388438346872;21608.703477791383;21673.458163545405;21703;21722.782772172075;36201;45337;45532.31701524414;46002.53997460116;46142.53857461383;61662;64166;64444;64615.353846462815;65773.01340479801;5796.449337312949;8197.631106600098;8235.176482351717;8474.788130296793;8632.525211113449;12357.32034738098;13452.93273533626;13811.654708632364;13915.930420347833;14016.579502614937;9390.248780097712;10257.897421025695;11793.52825886955;11897.643070707893;11951.342676152895;11123.221374503708;11518.94240528792;11575;11808.553931056496;11964.9829764471;11630.138218451155;11647.41762911868;12046.156769026435;12112.818307725567;12133.453994570089;7092.929070709433;8274.834503310021;8302.418830681794;8627;8638.395312328328;9714.854277185988;11137.774844766907;11438.714032149262;11532.769344613229;11780.352080635304 22:40:52 INFO - PROCESS | 1650 | |3;mootools.html;1503.8872084593654;1550.5580909440807;1612.4437069211126;1935.5838494723632;1995.7605087389516;8248.67005319788;8461.746147615566;8899.510526921016;9484.193843523291;9499.192568631666;7552.5846078465665;8312.501249925008;8661.956690216555;9028.458292502455;9101.317401194903;2424.187897054489;2458.4714286428416;2461.2616215135477;2464.494778570389;2470.728219895819;2418.7823095921362;2430.1858877276136;2473.332200305916;2475.777180053795;2486.4032632168332;2419.6491508731224;2439.2804122783823;2446.54738873309;2454.472288457981;2477.095860011092;2379.5454432598644;2440.9389765255833;2447.070113356931;2452.3256104571205;2456.980353153439;2418.6734790803193;2431.902723891042;2432.963505547418;2435.476372579904;2443.841150325223;9145.817083658356;9235.815283694355;9262.814743705156;9344.06559344067;9391.859122113174;5365.409804921455;5400.297961265011;5486.698231597261;5505.559555235612;5538.723063846792;3495.772774769657;3646.5988741238443;3659.2681463707363;3680.3375392429352;3707.054701051242;1088.4394536813547;1093.7156339351745;1117.3686866920164;1119.652907598647;1126.2397881430002;11703.122265830012;11716.179867409284;11737.295762254293;11743.412829358498;11748.823767643493;4728.953272608755;4781.378420805273;4786.473487916326;4885.706857588556;4937.703737775673;9818.950905245429;9959.253056020827;10003.999600039937;10035.899641003496;10050.497475126214;5222.7127507987425;5240.895182096337;5268.9736551317;5274.604404669665;5288.8413347599635;4505.571970662847;4648.349231107648;4750.3824502815105;4956.925646115311;5085.1355269604255;3432.828358582061;4121.62905338521;4178.623923846869;4195.811188496464;4199.202151591188;1329.9002574806927;1331.8468376136868;1342.7314537092425;1342.9059965802496;1346.5017943360824;827.9030284872568;843.7342237195264;922.7831459606987;948.8624149498285;956.1920177450044;5805.245318108618;5862.091375836667;5925.022371308686;6009.308929473166;6133.6319820810895;444.1139925847954;446.98212071516775;455.03078442916376;467.96490263230396;473.2404490792298;3856.9807150964066;3943.645071943539;4019.7588144711412;4033.616806403387;4058.9594104058583;10864.402457864893;10930.781384372269;11183.44082795857;11213.60752373663;11236.66290011301;10649.254552181274;10740.570377184982;10858.457077146111;10884.72788180286;10945.83581246282;8473.83052338962;8518.361122915681;8562.785930351667;8659.047504774344;8728.647059705756;6001.849953751192;6071.876702809995;6113.266408031065;6165.938340616536;6181.103739957771;10030.398176109456;10135.391876487434;10136.18910487174;10213.591456341663;10312.639057633247;8579.699710510102;10040.949795250977;10458.215633827645;10542.15662746963;10574.735631609272;7089.82275443118;7251.383632391306;7592.468527203043;7676.462647614613;7936.84126317482;8457;8603.612837422455;8636.697715580176;8678.869816952878;8685.565721714016;6659;7660.923390766021;7829.412794040583;7834.960825195838;7953.920460795318;7626.9618651906385;7660.233976602518;7708.306252437307;7726.459147859596;7742.264484873928;7172.641367931687;7325;7333.413326933939;7337.889931651136;7341.265873412616;5035.093683137071;5041.747912604282;5093.18509038552;5255.500727430888;5334.866628334323;4876.128987261646;5119.232115182604;5122.206058061007;5129.128048231735;5230.267762513327 22:40:52 INFO - PROCESS | 1650 | |4;prototype.html;292.3670253900306;308.16487319364506;320.52722234703805;321.53056537455313;321.61406312425066;4602.723836569804;5395.649282796616;5515.586331025169;5641.633293835897;5686.744096515657;4452.821887124519;4952.950470495294;5102.081625307444;5470.070088085025;5580.358258800239;4231.661467082641;4265.338872474771;4267.253230684633;4267.423897773791;4283.336082907139;3703.907402314937;3719.6237392164935;4193.6645068394455;4262.722923009995;4267.935980960288;4145.419641250227;4153.9853171211935;4172.97913510432;4180.205760905434;4188.350805625133;3792.393217085281;3944.27844787096;4146.087860670647;4170.624643782075;4174.979125104385;3616.2405894762132;3618.3486972344967;3618.475321078455;3627.437747149196;3648.5770549485455;5327.413984461706;5355.955588660213;5383.757730902119;5426.375966763794;5440.558252063206;3750.906227344311;4254.808533615995;4740.928886066712;4744.240921452585;4766.213574760161;5324.520793128637;5575.414581468968;5694.402087780765;5779.624324418899;5797.913031304492;3175.9682403175902;3492.947605785915;3595.442706380515;3974.920501589952;3978.5424676162324;6058.697065146725;6071.810450788941;6126.203593532857;6139.017757158877;6145.231846019247;3848.3073046851277;4006.7378775685565;4014.5985401460202;4027.496562929634;4029.7783621901076;3498.606351838763;3931.92136157281;3953.426753120839;4024.6981476389387;4042.9892526868284;4301.418988776943;4355.5208927018;4657.301404789308;4771.96346621524;4788;4633.166030114612;5789.565782566325;5830.2686280780335;5924.940750592526;5953.672548009901;1252.599168266146;1274.815151803002;1306.9934650326688;1373.2927542315715;1391.325207274471;709.9138318372898;792.8939129913738;794.9761507154678;813.8413009463097;854.3208149521146;4227.371028723543;4302.935455968163;4424.668149888771;4736.081886835837;4751.95248047522;4415.712978656377;4503;4760.928586071212;4976.850694479172;5143.717095559706;3690.612485689018;4492.528284530143;4607.746573938399;4666.503420669926;4715.7877895494785;2059.7631272403564;2490.5457049062497;2562.3337932137406;2681.6781986161786;2690.6636670416196;4407.625351845129;4680.53194680536;4915.508449155127;4988.455887497403;5288.286081379041;4528.909421811545;4545.749983750926;4737.862912900879;5177.171652535651;5214.634975551675;4147.440095587118;4724.716517008922;4822.324874517571;5015.448300686921;5341.356028765786;4414.955850441583;4838.371011768448;5101.744912754273;5129.717865517432;5176.767045482887;4183.958160418357;5012.343457967283;5055.671381360347;5060;5199.948000520098;4832.163777764567;4919.680220785637;5234.545200167871;5236.826716912438;5315.9468405317;3087.876484940577;3173.5557022016474;3543.4684797280615;3550.7336949729392;3552.200754830246;4708.505606911225;5081.798466965788;5129.666571672829;5145.446280042264;5151.922721159261;5218.791248350175;5404.675719456845;5658.321001479849;5892.764289428546;5897.3807750185615;5863.765449381977;5897.262842144732;5916.763329466773;5931.8432905582995;5934.821955341346;4414.366684326819;4829.6860704054125;5076;5437.864053398696;5796.884062318814;5539.532024013559;5608.298962629671;5989.011813050798;5989.735594101138;5991.812988259167;4834.6374021947795;5793.333766616893;5853.736581853912;5855.423613609998;5856.209411729362 22:40:52 INFO - PROCESS | 1650 | |5;yui.html;728.4463807506297;767.2519293688654;783.3106865957956;855.9058503564611;858.6093327535972;15715.44995925143;15937.681246375065;16685.749713754303;16688.165591720426;16746.83253167468;12160.513579456834;14641.560753177395;14757.18835464049;14971.176585287832;14983.700325993474;675.4562577125412;702.5328156775763;860.9741707748747;877.6840337478519;895.7044175422128;734.51521995483;869.2350731356397;869.6173683579204;870.4646642314963;872.3413822563955;874.8862647855801;918.5866360137932;926.2404828041009;927.4945154890601;928.6517555916531;896.672714459221;912.3841407050214;913.3013244867682;915.587985406563;921.3412410126758;546.3443867359146;677.529117263502;696.2237105627189;697.069412334534;701.828052127227;14097;14324.92837535816;14585.19781412022;14643.267836608127;14675.412983480755;14901.403943842342;14997.25013749319;15115.093094414371;15135.848641513665;15197.392104315924;11505.194636375458;11684.883151168464;11919.642410727693;12130.968867646172;12542;4561.498235194126;4582.908341833178;4623.676342656016;4647.465541462708;4694.906101877943;105095.4745226269;118767;119209;120168;121720.39139804244;10766.78466430667;11291.887081129247;11318.434078296052;11485.655430336936;11538.826917596243;21313.68029479559;21329.933503324773;21397.14411423557;21502;21926.013329400215;7024.648767561602;8061.879071813927;8144.5520496373265;8154.8776768348525;8181.345492360597;3745.962540374616;4327.7186982846015;4425.712328698624;4466.57567531084;4476.2837945928495;3491.6682915123024;3631.927361452756;3636.5454318210222;3639.6906262967946;3641.8361173747244;552.9502344788988;659.1299484680176;662.4634046422414;673.4376795375916;674.5143496682381;542.6011881267266;602.8071017274518;632.6741728010079;633.8700566383881;650.9772157974543;7892.723754668559;8681.913180868236;8929.642814287487;9220.723378298662;9389;530.1332321654123;592.655832745971;633.4503622917792;646.3407324528954;650.7982525417136;4352;4497.145542346943;4503.121891231179;4525.230710779176;4613.838515651936;103165;138495;159568;159700;161516;139300.9104863428;139968;141628.2918585421;144487;145005;72613.54772904512;84495;119953.40023299828;121923.39038304752;123022;8550.401471896908;8731.738047858575;8797.692080777142;8805.251553617758;8941.37410381267;65757.67121164364;65893.34106658872;67547.3245267541;67721;68841;60392.49018774566;70110.94833577398;95811;98260;99932.00067999227;11129.887011298806;11205.327680339205;11391.601293954673;11457.65627031157;11538;12236.571719990114;12254.142210045567;12345.753084938431;12447.190932589352;12493.75012499763;11490;11527.193096483166;11849.052075834084;11849.763004740029;12036.699082522657;9544.618215271312;11574;11786.116041296766;11948.163628545919;11981.101417393904;9780.41317520951;10913.945430272797;11040;11052.944735276273;11444.912733290657;8333.833323333378;9513.571889264862;9706.445055602078;10036.397816131055;10687.679369618923;8063;9898.653547125816;9980;10151.492425378554;10164.136048435967 22:40:52 INFO - PROCESS | 1650 | __end_tp_report 22:40:52 INFO - PROCESS | 1650 | __start_cc_report 22:40:52 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,343 22:40:52 INFO - PROCESS | 1650 | __end_cc_report 22:40:52 INFO - PROCESS | 1650 | __startTimestamp1484203252326__endTimestamp 22:40:52 INFO - PROCESS | 1650 | 22:40:52 INFO - PROCESS | 1650 | ------- Summary: start ------- 22:40:52 INFO - PROCESS | 1650 | Number of tests: 6 22:40:52 INFO - PROCESS | 1650 | 22:40:52 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5833.74 Median:6116.36 stddev:652.17 (10.7%) stddev-sans-first:628.62 22:40:52 INFO - PROCESS | 1650 | Values: 3529.3 3625.1 3627.0 3630.3 3678.3 5874.9 5956.1 5965.0 5978.9 5992.0 5900.7 5906.7 5968.8 5973.1 5985.6 5323.4 5334.2 5340.1 5393.4 5510.3 5402.4 5404.1 5425.8 5435.1 5438.5 5272.7 5278.9 5372.3 5404.4 5456.2 5333.2 5339.8 5343.1 5348.9 5369.3 5274.5 5298.2 5306.5 5391.5 5394.9 4840.7 5133.8 5725.3 6003.0 6050.7 6153.6 6236.7 6248.2 6273.2 6282.0 6364.5 6383.0 6385.8 6396.7 6412.1 5919.7 5996.1 6004.6 6103.9 6116.7 5233.3 6474.5 6480.9 6485.5 6487.0 6254.9 6285.9 6288.7 6289.3 6298.7 5168.4 6145.6 6345.7 6347.6 6433.0 6176.8 6223.0 6244.6 6280.5 6286.5 6271.6 6276.3 6279.2 6285.2 6287.2 4931.7 5061.1 5103.0 5116.9 5119.8 4432.9 4482.5 4518.8 4582.6 4594.0 5227.6 6022.8 6107.5 6107.8 6129.2 6179.6 6222.0 6331.7 6345.4 6349.5 5998.1 6016.9 6026.5 6088.7 6126.9 4059.6 5482.2 5524.7 5535.9 5556.1 6277.3 6292.6 6530.6 6540.4 6565.1 6199.4 6236.6 6239.5 6328.1 6333.0 6527.5 6547.7 6551.0 6559.4 6575.0 5220.8 5284.7 6265.0 6303.0 6317.6 5210.0 6169.7 6194.5 6195.6 6273.8 6119.9 6222.2 6275.3 6293.3 6304.2 6157.2 6203.2 6225.5 6239.7 6278.2 4745.0 5893.9 5904.9 6082.8 6116.4 6191.4 6206.8 6215.0 6222.8 6248.2 6103.0 6185.4 6186.5 6190.8 6191.6 22:40:52 INFO - PROCESS | 1650 | 22:40:52 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:71494.69 Median:35825.55 stddev:156306.32 (436.3%) stddev-sans-first:156653.88 22:40:52 INFO - PROCESS | 1650 | Values: 485.3 497.2 539.1 560.8 574.1 40661.6 41977.8 42135.8 44656.1 44689.6 43644.6 43791.3 44104.0 44138.3 44207.6 10014.1 10192.6 10216.6 10219.2 10226.6 10034.7 10131.1 10136.6 10137.6 10166.9 9871.0 9921.8 9983.1 10092.9 10129.7 10049.3 10103.4 10157.6 10158.3 10213.9 10058.3 10139.0 10154.2 10155.2 10172.0 42227.8 42268.4 42288.2 42575.1 42671.4 3514.8 3634.7 3701.7 3714.4 4362.7 31854.7 32615.8 32617.0 32619.7 32637.8 3379.8 3421.8 3441.5 3475.9 3488.8 502626.0 503285.0 521255.0 522885.0 523835.0 61644.4 62184.0 64254.0 64504.0 64698.0 35538.0 35548.1 35801.6 37251.3 37406.4 17702.4 17778.1 18045.0 18046.2 18158.4 12328.4 13558.3 13813.4 13949.3 13979.9 17423.0 19024.0 19835.9 19882.6 19908.7 8323.8 8360.4 8425.9 8456.6 8500.5 819.3 900.7 1019.9 1024.8 1038.4 25646.9 31357.0 31568.7 31620.4 32111.4 964.8 1051.8 1120.3 1124.7 1129.1 13361.9 16248.4 16359.8 16394.9 16683.3 638905.0 650776.0 656879.0 657463.0 660007.0 59409.4 59444.7 59537.7 60110.0 60178.7 1429.7 1446.2 1460.4 1465.5 1468.2 29573.4 30663.9 31007.4 31210.5 31449.7 42770.4 49392.0 50315.5 50542.7 50672.0 576264.0 576998.0 581115.0 581627.0 581682.0 26977.6 27151.3 27528.2 27586.0 27619.9 48268.5 48859.5 49209.8 49342.8 50335.7 39445.8 41181.6 41416.0 41693.6 41875.8 40084.8 40308.4 40876.4 41229.2 41654.4 34094.1 34884.0 37622.6 39558.6 40357.4 34459.7 35849.5 36000.8 36608.6 36691.6 26770.7 26956.8 26975.0 27043.3 27487.8 22:40:52 INFO - PROCESS | 1650 | 22:40:52 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:35016.59 Median:14227.37 stddev:102270.58 (718.8%) stddev-sans-first:102524.42 22:40:52 INFO - PROCESS | 1650 | Values: 387.7 388.7 391.5 396.9 415.7 17443.2 17607.7 20736.6 22157.8 22174.2 21008.2 21101.2 21105.3 21247.4 21407.0 5742.0 6208.3 6242.8 6252.9 6265.8 6064.3 6090.6 6091.9 6111.7 6164.5 5259.4 6022.5 6126.7 6140.0 6156.0 5221.3 5251.0 6095.7 6143.4 6196.2 5413.8 5418.4 5420.6 5422.9 5435.0 25889.0 26023.0 26496.9 26648.0 26780.6 2139.3 2322.9 2519.6 2565.8 2704.4 15529.3 16148.8 16230.1 16354.8 16607.5 1907.5 2021.5 2022.8 2025.6 2025.9 115698.0 118219.4 118629.0 119319.0 119411.0 11222.4 11287.7 11442.2 11475.9 11821.8 14649.6 14663.3 14676.0 14694.6 14708.5 9044.5 9088.0 9145.8 9146.9 9231.0 9202.4 9313.1 9336.5 9356.3 9513.6 7068.8 7139.3 7143.2 7163.8 7186.6 1885.8 1886.7 1901.6 1934.0 1942.8 859.7 944.5 953.9 1062.2 1065.3 21971.3 22126.2 23233.2 23411.8 23519.3 876.8 1004.9 1022.2 1037.0 1038.9 11775.1 12345.3 12397.9 12481.1 12540.1 593306.4 597657.0 607135.0 642753.0 644882.0 81654.0 96071.0 121772.0 125559.0 129290.4 816.5 863.2 942.6 963.5 963.7 20385.4 21608.7 21673.5 21703.0 21722.8 36201.0 45337.0 45532.3 46002.5 46142.5 61662.0 64166.0 64444.0 64615.4 65773.0 5796.4 8197.6 8235.2 8474.8 8632.5 12357.3 13452.9 13811.7 13915.9 14016.6 9390.2 10257.9 11793.5 11897.6 11951.3 11123.2 11518.9 11575.0 11808.6 11965.0 11630.1 11647.4 12046.2 12112.8 12133.5 7092.9 8274.8 8302.4 8627.0 8638.4 9714.9 11137.8 11438.7 11532.8 11780.4 22:40:52 INFO - PROCESS | 1650 | 22:40:52 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5829.47 Median:4765.88 stddev:3272.09 (68.7%) stddev-sans-first:3265.12 22:40:52 INFO - PROCESS | 1650 | Values: 1503.9 1550.6 1612.4 1935.6 1995.8 8248.7 8461.7 8899.5 9484.2 9499.2 7552.6 8312.5 8662.0 9028.5 9101.3 2424.2 2458.5 2461.3 2464.5 2470.7 2418.8 2430.2 2473.3 2475.8 2486.4 2419.6 2439.3 2446.5 2454.5 2477.1 2379.5 2440.9 2447.1 2452.3 2457.0 2418.7 2431.9 2433.0 2435.5 2443.8 9145.8 9235.8 9262.8 9344.1 9391.9 5365.4 5400.3 5486.7 5505.6 5538.7 3495.8 3646.6 3659.3 3680.3 3707.1 1088.4 1093.7 1117.4 1119.7 1126.2 11703.1 11716.2 11737.3 11743.4 11748.8 4729.0 4781.4 4786.5 4885.7 4937.7 9819.0 9959.3 10004.0 10035.9 10050.5 5222.7 5240.9 5269.0 5274.6 5288.8 4505.6 4648.3 4750.4 4956.9 5085.1 3432.8 4121.6 4178.6 4195.8 4199.2 1329.9 1331.8 1342.7 1342.9 1346.5 827.9 843.7 922.8 948.9 956.2 5805.2 5862.1 5925.0 6009.3 6133.6 444.1 447.0 455.0 468.0 473.2 3857.0 3943.6 4019.8 4033.6 4059.0 10864.4 10930.8 11183.4 11213.6 11236.7 10649.3 10740.6 10858.5 10884.7 10945.8 8473.8 8518.4 8562.8 8659.0 8728.6 6001.8 6071.9 6113.3 6165.9 6181.1 10030.4 10135.4 10136.2 10213.6 10312.6 8579.7 10040.9 10458.2 10542.2 10574.7 7089.8 7251.4 7592.5 7676.5 7936.8 8457.0 8603.6 8636.7 8678.9 8685.6 6659.0 7660.9 7829.4 7835.0 7953.9 7627.0 7660.2 7708.3 7726.5 7742.3 7172.6 7325.0 7333.4 7337.9 7341.3 5035.1 5041.7 5093.2 5255.5 5334.9 4876.1 5119.2 5122.2 5129.1 5230.3 22:40:52 INFO - PROCESS | 1650 | 22:40:52 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4402.53 Median:4742.58 stddev:1364.90 (28.8%) stddev-sans-first:1333.41 22:40:52 INFO - PROCESS | 1650 | Values: 292.4 308.2 320.5 321.5 321.6 4602.7 5395.6 5515.6 5641.6 5686.7 4452.8 4953.0 5102.1 5470.1 5580.4 4231.7 4265.3 4267.3 4267.4 4283.3 3703.9 3719.6 4193.7 4262.7 4267.9 4145.4 4154.0 4173.0 4180.2 4188.4 3792.4 3944.3 4146.1 4170.6 4175.0 3616.2 3618.3 3618.5 3627.4 3648.6 5327.4 5356.0 5383.8 5426.4 5440.6 3750.9 4254.8 4740.9 4744.2 4766.2 5324.5 5575.4 5694.4 5779.6 5797.9 3176.0 3492.9 3595.4 3974.9 3978.5 6058.7 6071.8 6126.2 6139.0 6145.2 3848.3 4006.7 4014.6 4027.5 4029.8 3498.6 3931.9 3953.4 4024.7 4043.0 4301.4 4355.5 4657.3 4772.0 4788.0 4633.2 5789.6 5830.3 5924.9 5953.7 1252.6 1274.8 1307.0 1373.3 1391.3 709.9 792.9 795.0 813.8 854.3 4227.4 4302.9 4424.7 4736.1 4752.0 4415.7 4503.0 4760.9 4976.9 5143.7 3690.6 4492.5 4607.7 4666.5 4715.8 2059.8 2490.5 2562.3 2681.7 2690.7 4407.6 4680.5 4915.5 4988.5 5288.3 4528.9 4545.7 4737.9 5177.2 5214.6 4147.4 4724.7 4822.3 5015.4 5341.4 4415.0 4838.4 5101.7 5129.7 5176.8 4184.0 5012.3 5055.7 5060.0 5199.9 4832.2 4919.7 5234.5 5236.8 5315.9 3087.9 3173.6 3543.5 3550.7 3552.2 4708.5 5081.8 5129.7 5145.4 5151.9 5218.8 5404.7 5658.3 5892.8 5897.4 5863.8 5897.3 5916.8 5931.8 5934.8 4414.4 4829.7 5076.0 5437.9 5796.9 5539.5 5608.3 5989.0 5989.7 5991.8 4834.6 5793.3 5853.7 5855.4 5856.2 22:40:52 INFO - PROCESS | 1650 | 22:40:52 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:24756.35 Median:4486.71 stddev:40722.57 (907.6%) stddev-sans-first:40796.85 22:40:52 INFO - PROCESS | 1650 | Values: 728.4 767.3 783.3 855.9 858.6 15715.4 15937.7 16685.7 16688.2 16746.8 12160.5 14641.6 14757.2 14971.2 14983.7 675.5 702.5 861.0 877.7 895.7 734.5 869.2 869.6 870.5 872.3 874.9 918.6 926.2 927.5 928.7 896.7 912.4 913.3 915.6 921.3 546.3 677.5 696.2 697.1 701.8 14097.0 14324.9 14585.2 14643.3 14675.4 14901.4 14997.3 15115.1 15135.8 15197.4 11505.2 11684.9 11919.6 12131.0 12542.0 4561.5 4582.9 4623.7 4647.5 4694.9 105095.5 118767.0 119209.0 120168.0 121720.4 10766.8 11291.9 11318.4 11485.7 11538.8 21313.7 21329.9 21397.1 21502.0 21926.0 7024.6 8061.9 8144.6 8154.9 8181.3 3746.0 4327.7 4425.7 4466.6 4476.3 3491.7 3631.9 3636.5 3639.7 3641.8 553.0 659.1 662.5 673.4 674.5 542.6 602.8 632.7 633.9 651.0 7892.7 8681.9 8929.6 9220.7 9389.0 530.1 592.7 633.5 646.3 650.8 4352.0 4497.1 4503.1 4525.2 4613.8 103165.0 138495.0 159568.0 159700.0 161516.0 139300.9 139968.0 141628.3 144487.0 145005.0 72613.5 84495.0 119953.4 121923.4 123022.0 8550.4 8731.7 8797.7 8805.3 8941.4 65757.7 65893.3 67547.3 67721.0 68841.0 60392.5 70110.9 95811.0 98260.0 99932.0 11129.9 11205.3 11391.6 11457.7 11538.0 12236.6 12254.1 12345.8 12447.2 12493.8 11490.0 11527.2 11849.1 11849.8 12036.7 9544.6 11574.0 11786.1 11948.2 11981.1 9780.4 10913.9 11040.0 11052.9 11444.9 8333.8 9513.6 9706.4 10036.4 10687.7 8063.0 9898.7 9980.0 10151.5 10164.1 22:40:52 INFO - PROCESS | 1650 | -------- Summary: end -------- 22:40:52 INFO - PROCESS | 1650 | 22:40:52 INFO - TEST-INFO | 1650: exit 0 22:40:52 INFO - TEST-OK | dromaeo_css | took 1111562ms 22:40:52 INFO - TEST-START | kraken 22:40:52 INFO - operating with platform_type : mac_ 22:40:52 INFO - Initialising browser for kraken test... 22:40:52 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpXkY9P_/profile http://localhost:49240/getInfo.html) 22:40:55 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 22:40:55 INFO - PROCESS | 1658 | colorDepth:24 22:40:55 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 22:40:55 INFO - PROCESS | 1658 | __metrics 22:40:55 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3015: TypeError: this.tabs is undefined 22:40:56 INFO - TEST-INFO | 1658: exit 0 22:40:56 INFO - Browser initialized. 22:40:56 INFO - Running cycle 1/1 for kraken test... 22:40:56 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpXkY9P_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:41:08 INFO - PROCESS | 1661 | RSS: Main: 175190016 22:41:08 INFO - PROCESS | 1661 | 22:41:11 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 22:41:11 INFO - PROCESS | 1661 | RSS: Main: 187056128 22:41:11 INFO - PROCESS | 1661 | 22:41:14 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 22:41:15 INFO - PROCESS | 1661 | RSS: Main: 194461696 22:41:15 INFO - PROCESS | 1661 | 22:41:18 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 22:41:19 INFO - PROCESS | 1661 | RSS: Main: 198107136 22:41:19 INFO - PROCESS | 1661 | 22:41:21 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 22:41:21 INFO - PROCESS | 1661 | RSS: Main: 199450624 22:41:21 INFO - PROCESS | 1661 | 22:41:23 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:41:24 INFO - PROCESS | 1661 | RSS: Main: 199860224 22:41:24 INFO - PROCESS | 1661 | 22:41:27 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 22:41:27 INFO - PROCESS | 1661 | RSS: Main: 200425472 22:41:27 INFO - PROCESS | 1661 | 22:41:30 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 22:41:31 INFO - PROCESS | 1661 | RSS: Main: 200396800 22:41:31 INFO - PROCESS | 1661 | 22:41:34 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 22:41:35 INFO - PROCESS | 1661 | RSS: Main: 200683520 22:41:35 INFO - PROCESS | 1661 | 22:41:36 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:41:37 INFO - PROCESS | 1661 | RSS: Main: 200585216 22:41:37 INFO - PROCESS | 1661 | 22:41:39 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:41:39 INFO - PROCESS | 1661 | RSS: Main: 200650752 22:41:39 INFO - PROCESS | 1661 | 22:41:41 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:41:42 INFO - PROCESS | 1661 | RSS: Main: 200482816 22:41:42 INFO - PROCESS | 1661 | 22:41:45 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:41:46 INFO - PROCESS | 1661 | RSS: Main: 200507392 22:41:46 INFO - PROCESS | 1661 | 22:41:49 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:41:49 INFO - PROCESS | 1661 | RSS: Main: 200892416 22:41:49 INFO - PROCESS | 1661 | 22:41:51 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 22:41:51 INFO - PROCESS | 1661 | RSS: Main: 202440704 22:41:51 INFO - PROCESS | 1661 | 22:41:51 INFO - PROCESS | 1661 | __start_tp_report 22:41:51 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 22:41:51 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 22:41:51 INFO - PROCESS | 1661 | |i|pagename|runs| 22:41:51 INFO - PROCESS | 1661 | |0;ai-astar;105;117;117;116;120;129;171;160;116;116 22:41:51 INFO - PROCESS | 1661 | |1;audio-beat-detection;141;109;110;155;111;110;120;115;174;111 22:41:51 INFO - PROCESS | 1661 | |2;audio-dft;196;198;195;204;198;196;198;198;199;266 22:41:51 INFO - PROCESS | 1661 | |3;audio-fft;76;53;83;72;66;82;84;82;102;59 22:41:51 INFO - PROCESS | 1661 | |4;audio-oscillator;86;58;72;60;56;55;71;79;101;117 22:41:51 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;93;88;91;130;123;107;81;133;135;117 22:41:51 INFO - PROCESS | 1661 | |6;imaging-darkroom;153;96;96;95;96;96;96;97;99;102 22:41:51 INFO - PROCESS | 1661 | |7;imaging-desaturate;146;148;91;90;88;139;84;145;146;123 22:41:51 INFO - PROCESS | 1661 | |8;json-parse-financial;54;54;55;54;60;58;58;56;57;58 22:41:51 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;65;72;65;74;71;64;57;77;92;99 22:41:51 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;78;86;82;83;89;83;84;85;86;90 22:41:51 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;124;129;146;149;149;126;144;149;167;145 22:41:51 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;167;166;164;162;223;223;163;163;162;160 22:41:51 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;66;67;65;68;65;67;66;65;66;65 22:41:51 INFO - PROCESS | 1661 | __end_tp_report 22:41:51 INFO - PROCESS | 1661 | __start_cc_report 22:41:51 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,899 22:41:51 INFO - PROCESS | 1661 | __end_cc_report 22:41:51 INFO - PROCESS | 1661 | __startTimestamp1484203311551__endTimestamp 22:41:51 INFO - PROCESS | 1661 | 22:41:51 INFO - PROCESS | 1661 | ------- Summary: start ------- 22:41:51 INFO - PROCESS | 1661 | Number of tests: 14 22:41:51 INFO - PROCESS | 1661 | 22:41:51 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:126.70 Median:118.50 stddev:21.41 (18.1%) stddev-sans-first:21.22 22:41:51 INFO - PROCESS | 1661 | Values: 105.0 117.0 117.0 116.0 120.0 129.0 171.0 160.0 116.0 116.0 22:41:51 INFO - PROCESS | 1661 | 22:41:51 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:125.60 Median:117.50 stddev:23.04 (19.6%) stddev-sans-first:23.75 22:41:51 INFO - PROCESS | 1661 | Values: 141.0 109.0 110.0 155.0 111.0 110.0 120.0 115.0 174.0 111.0 22:41:51 INFO - PROCESS | 1661 | 22:41:51 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:204.80 Median:198.00 stddev:21.64 (10.9%) stddev-sans-first:22.72 22:41:51 INFO - PROCESS | 1661 | Values: 196.0 198.0 195.0 204.0 198.0 196.0 198.0 198.0 199.0 266.0 22:41:51 INFO - PROCESS | 1661 | 22:41:51 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:75.90 Median:79.00 stddev:14.12 (17.9%) stddev-sans-first:14.98 22:41:51 INFO - PROCESS | 1661 | Values: 76.0 53.0 83.0 72.0 66.0 82.0 84.0 82.0 102.0 59.0 22:41:51 INFO - PROCESS | 1661 | 22:41:51 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:65.50 stddev:20.75 (31.7%) stddev-sans-first:21.66 22:41:51 INFO - PROCESS | 1661 | Values: 86.0 58.0 72.0 60.0 56.0 55.0 71.0 79.0 101.0 117.0 22:41:51 INFO - PROCESS | 1661 | 22:41:51 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:109.80 Median:108.00 stddev:20.43 (18.9%) stddev-sans-first:20.74 22:41:51 INFO - PROCESS | 1661 | Values: 93.0 88.0 91.0 130.0 123.0 107.0 81.0 133.0 135.0 117.0 22:41:51 INFO - PROCESS | 1661 | 22:41:51 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:102.60 Median:96.00 stddev:17.83 (18.6%) stddev-sans-first:2.18 22:41:51 INFO - PROCESS | 1661 | Values: 153.0 96.0 96.0 95.0 96.0 96.0 96.0 97.0 99.0 102.0 22:41:51 INFO - PROCESS | 1661 | 22:41:51 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:120.00 Median:116.00 stddev:28.27 (24.4%) stddev-sans-first:28.37 22:41:51 INFO - PROCESS | 1661 | Values: 146.0 148.0 91.0 90.0 88.0 139.0 84.0 145.0 146.0 123.0 22:41:51 INFO - PROCESS | 1661 | 22:41:51 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:57.50 stddev:2.12 (3.7%) stddev-sans-first:2.06 22:41:51 INFO - PROCESS | 1661 | Values: 54.0 54.0 55.0 54.0 60.0 58.0 58.0 56.0 57.0 58.0 22:41:51 INFO - PROCESS | 1661 | 22:41:51 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:73.60 Median:73.00 stddev:13.00 (17.8%) stddev-sans-first:13.41 22:41:51 INFO - PROCESS | 1661 | Values: 65.0 72.0 65.0 74.0 71.0 64.0 57.0 77.0 92.0 99.0 22:41:51 INFO - PROCESS | 1661 | 22:41:51 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:85.50 stddev:3.47 (4.1%) stddev-sans-first:2.74 22:41:51 INFO - PROCESS | 1661 | Values: 78.0 86.0 82.0 83.0 89.0 83.0 84.0 85.0 86.0 90.0 22:41:51 INFO - PROCESS | 1661 | 22:41:51 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:142.80 Median:147.50 stddev:13.10 (8.9%) stddev-sans-first:11.99 22:41:51 INFO - PROCESS | 1661 | Values: 124.0 129.0 146.0 149.0 149.0 126.0 144.0 149.0 167.0 145.0 22:41:51 INFO - PROCESS | 1661 | 22:41:51 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.30 Median:165.00 stddev:25.22 (15.3%) stddev-sans-first:26.57 22:41:51 INFO - PROCESS | 1661 | Values: 167.0 166.0 164.0 162.0 223.0 223.0 163.0 163.0 162.0 160.0 22:41:51 INFO - PROCESS | 1661 | 22:41:51 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:1.05 (1.6%) stddev-sans-first:1.12 22:41:51 INFO - PROCESS | 1661 | Values: 66.0 67.0 65.0 68.0 65.0 67.0 66.0 65.0 66.0 65.0 22:41:51 INFO - PROCESS | 1661 | -------- Summary: end -------- 22:41:51 INFO - PROCESS | 1661 | 22:41:52 INFO - TEST-INFO | 1661: exit 0 22:41:52 INFO - TEST-OK | kraken | took 59380ms 22:41:52 INFO - SUITE-END | took 1170s 22:41:52 INFO - Completed test suite (00:19:31) 22:41:52 INFO - javascript benchmark 22:41:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7410.725989086176, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3529.3117842020806, 3625.093726568358, 3627.0, 3630.2739452109568, 3678.3195108904856, 5874.853128671785, 5956.1065840124, 5964.9999999999945, 5978.910316345247, 5992.0, 5900.675462849542, 5906.704664766766, 5968.820935371935, 5973.074173503103, 5985.6109352892145, 5323.414424413311, 5334.226537152111, 5340.092184328654, 5393.379761327458, 5510.338759348863, 5402.37872644645, 5404.135338345865, 5425.755840987165, 5435.130379139338, 5438.456154384549, 5272.6836389816735, 5278.894422111555, 5372.274742909716, 5404.351477822667, 5456.154296084113, 5333.2000199969925, 5339.813106541291, 5343.118385466393, 5348.866278343034, 5369.328833895763, 5274.525292723673, 5298.231756395301, 5306.495882891119, 5391.48780865821, 5394.91907621386, 4840.733759643218, 5133.794648214067, 5725.255716756795, 6002.969985150046, 6050.69746512677, 6153.569250152491, 6236.688165591702, 6248.1564988726395, 6273.215848018997, 6282.0, 6364.490840732777, 6383.0, 6385.808425747188, 6396.712147953307, 6412.102305677209, 5919.704014799199, 5996.100584912211, 6004.6097003695495, 6103.938960610381, 6116.663583502906, 5233.319668443078, 6474.514411419163, 6480.935190648128, 6485.513586481033, 6487.0, 6254.874902501924, 6285.937140628535, 6288.717007734663, 6289.276733175743, 6298.653574053471, 5168.3539557555305, 6145.600535965148, 6345.714442850082, 6347.619142851351, 6433.0, 6176.7838125665385, 6223.0354295084335, 6244.594101383486, 6280.497560195174, 6286.528510361742, 6271.62370257786, 6276.340984196685, 6279.183706118267, 6285.2457705075685, 6287.245530536274, 4931.7040977541465, 5061.063703214917, 5103.0, 5116.923246151311, 5119.820806271762, 4432.889177770581, 4482.5069242383315, 4518.841840535565, 4582.564656357641, 4594.0, 5227.581793456515, 6022.849428764316, 6107.511399088063, 6107.786227482017, 6129.172561704202, 6179.62922224659, 6222.0355844844125, 6331.746730130835, 6345.428911397997, 6349.492040636738, 5998.1002849572915, 6016.939830601726, 6026.54800889935, 6088.695565221721, 6126.908096378558, 4059.5534491205945, 5482.205080263419, 5524.696141712164, 5535.892821435729, 5556.083246264402, 6277.3408792078, 6292.590981586182, 6530.640814755139, 6540.37866402691, 6565.080888675589, 6199.380061993765, 6236.563440559117, 6239.469645080219, 6328.14570033058, 6332.968335158479, 6527.51043671717, 6547.672616369257, 6551.0, 6559.409653131241, 6575.0, 5220.825314304403, 5284.735763211901, 6264.9976003839165, 6303.0, 6317.589356691984, 5209.973950130225, 6169.722362493789, 6194.473469755121, 6195.597286176384, 6273.843153921189, 6119.877602448015, 6222.160008398808, 6275.309715931336, 6293.307736148929, 6304.211973503312, 6157.168782214344, 6203.2245969253845, 6225.533085018551, 6239.688015599111, 6278.215223097113, 4745.0, 5893.9115913260475, 5904.911426328695, 6082.817515474543, 6116.35778243278, 6191.4118158774845, 6206.813795586139, 6215.0, 6222.81331560054, 6248.218972628421, 6103.0, 6185.443310102112, 6186.536009799193, 6190.7523699051535, 6191.597546159485], "value": 5797.301246008617, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.3132817063855, 497.234259240769, 539.0889396919206, 560.7728869807729, 574.1244601981977, 40661.59338406615, 41977.79011104944, 42135.78932105339, 44656.106877862425, 44689.55310446903, 43644.563554364446, 43791.34312985308, 44104.0, 44138.337924931155, 44207.55792442075, 10014.098731114198, 10192.592296308176, 10216.591336346537, 10219.182465402788, 10226.590936362536, 10034.749131271703, 10131.088202061814, 10136.645217417392, 10137.645182418617, 10166.898331016706, 9871.03868839349, 9921.75195620108, 9983.101520863122, 10092.89907100927, 10129.746756331077, 10049.296549241555, 10103.444310562916, 10157.593696252216, 10158.28891977562, 10213.897861021444, 10058.295919285652, 10139.0, 10154.238432117621, 10155.238357123246, 10172.0, 42227.788861055495, 42268.365974510096, 42288.1542369154, 42575.1484970302, 42671.35992960077, 3514.8242587870504, 3634.745567810254, 3701.7408781385316, 3714.4242642390473, 4362.694611377205, 31854.681453185403, 32615.836920815244, 32617.0, 32619.6738032619, 32637.836810815796, 3379.7972121672533, 3421.7775844570024, 3441.5009823575692, 3475.878344257939, 3488.8255587220538, 502626.0, 503285.0, 521255.0, 522885.0, 523835.0, 61644.383556164765, 62184.0, 64254.0, 64504.0, 64698.0, 35538.0, 35548.111297217256, 35801.64198357983, 37251.25497490035, 37406.43890341647, 17702.380416685355, 17778.111094445485, 18045.00752458601, 18046.187921543562, 18158.364457243933, 12328.445219965122, 13558.254296013616, 13813.378397972116, 13949.302534873419, 13979.860201397856, 17423.0, 19024.048797560066, 19835.900820495805, 19882.60234795325, 19908.70136947947, 8323.750287491264, 8360.372972027122, 8425.873611895826, 8456.619452124669, 8500.53247071417, 819.3076850061689, 900.7387857521377, 1019.9235057370727, 1024.8155332040305, 1038.361407734238, 25646.871765641426, 31357.0, 31568.684313157035, 31620.367592648017, 32111.35777284441, 964.7925695975399, 1051.7580956380075, 1120.294214644785, 1124.7244425115734, 1129.1418521923233, 13361.93319033418, 16248.431304904509, 16359.83640163607, 16394.918025410036, 16683.33266669344, 638905.0, 650776.0, 656879.0, 657463.0, 660007.0, 59409.405905941254, 59444.70277648584, 59537.70231148817, 60110.0, 60178.69910650506, 1429.7212043651596, 1446.168453139428, 1460.4304321314696, 1465.4724299252262, 1468.185157237722, 29573.408531829675, 30663.926762563202, 31007.36710419212, 31210.531842022843, 31449.685503144676, 42770.35844462398, 49392.01215975732, 50315.496845031084, 50542.7364315895, 50672.0, 576264.0, 576998.0, 581115.0, 581627.0, 581682.0, 26977.595336070368, 27151.321216969733, 27528.174154775388, 27586.0, 27619.86190069037, 48268.517314827805, 48859.511404885496, 49209.769755756395, 49342.75328623334, 50335.74832125816, 39445.80277098596, 41181.588184117776, 41416.0, 41693.58306417018, 41875.7906210467, 40084.79957600193, 40308.39537406882, 40876.38685419781, 41229.1754164909, 41654.37518437165, 34094.147646309044, 34884.0, 37622.62377376191, 39558.604413955494, 40357.39463907985, 34459.655403446646, 35849.46225806667, 36000.81999589985, 36608.633913661586, 36691.63308366882, 26770.732292677603, 26956.786944587147, 26975.0, 27043.323916902234, 27487.76305066237], "value": 20538.80323770606, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.6859743607676, 388.71817931999306, 391.4617401073524, 396.8908550148708, 415.70093457943926, 17443.21505532251, 17607.735883961752, 20736.585268294624, 22157.778422215793, 22174.223902163427, 21008.159673613074, 21101.15595376187, 21105.26131585395, 21247.36257912265, 21407.0, 5741.99999999999, 6208.348123447041, 6242.7502899884175, 6252.906206406908, 6265.812025639215, 6064.272287325527, 6090.624656384014, 6091.939080609203, 6111.724972376253, 6164.506839452855, 5259.3951695555115, 6022.518198544106, 6126.663033533154, 6139.969300153493, 6156.015037593964, 5221.347331583552, 5250.976059668366, 6095.695215239242, 6143.354947730491, 6196.194494715658, 5413.783448662049, 5418.4039755626845, 5420.620556561043, 5422.8915421691345, 5435.021696094701, 25888.964441422322, 26023.0, 26496.867515662492, 26648.0, 26780.59829102565, 2139.3474990128007, 2322.930312090623, 2519.596864501689, 2565.7819085377764, 2704.3779930615874, 15529.301181446863, 16148.75776863348, 16230.10734409607, 16354.754300609953, 16607.50177494677, 1907.523119220195, 2021.5148364392587, 2022.8482863785275, 2025.5543780368444, 2025.929092481756, 115698.0, 118219.40890295494, 118629.0, 119318.99999999913, 119411.0, 11222.382768947624, 11287.661370158909, 11442.199046066695, 11475.942620286845, 11821.822672659917, 14649.560513184622, 14663.34014969329, 14675.972681912164, 14694.55916322512, 14708.485203017843, 9044.547772611342, 9088.000319964798, 9145.77135571616, 9146.948100968473, 9231.0, 9202.401843880127, 9313.068693130634, 9336.486493242937, 9356.298277629205, 9513.619455221851, 7068.752593659197, 7139.286071392923, 7143.249958754256, 7163.820904477429, 7186.56880587156, 1885.8019907909747, 1886.6981282994655, 1901.5531350132708, 1934.0, 1942.805719428046, 859.6733241368365, 944.5371767833713, 953.8855337359561, 1062.166199533378, 1065.2543219746208, 21971.34085977423, 22126.22558210487, 23233.186838460573, 23411.7658823413, 23519.294421167393, 876.7764220123955, 1004.9296549241483, 1022.2333250062453, 1036.9896301036893, 1038.870141232346, 11775.05799536035, 12345.321007344683, 12397.876021239852, 12481.063920206021, 12540.05949553769, 593306.4355449276, 597657.0, 607135.0, 642753.0, 644882.0, 81654.0, 96071.0, 121772.0, 125559.0, 129290.35354823165, 816.546816516827, 863.1799790199336, 942.6370847223731, 963.5182408795603, 963.7301555564453, 20385.388438346872, 21608.703477791383, 21673.458163545405, 21703.0, 21722.782772172075, 36201.0, 45337.0, 45532.31701524414, 46002.53997460116, 46142.53857461383, 61662.0, 64166.0, 64444.0, 64615.353846462815, 65773.01340479801, 5796.449337312949, 8197.631106600098, 8235.176482351717, 8474.788130296793, 8632.525211113449, 12357.32034738098, 13452.93273533626, 13811.654708632364, 13915.930420347833, 14016.579502614937, 9390.248780097712, 10257.897421025695, 11793.52825886955, 11897.643070707893, 11951.342676152895, 11123.221374503708, 11518.94240528792, 11575.0, 11808.553931056496, 11964.9829764471, 11630.138218451155, 11647.41762911868, 12046.156769026435, 12112.818307725567, 12133.453994570089, 7092.929070709433, 8274.834503310021, 8302.418830681794, 8627.0, 8638.395312328328, 9714.854277185988, 11137.774844766907, 11438.714032149262, 11532.769344613229, 11780.352080635304], "value": 9960.061841866329, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1503.8872084593654, 1550.5580909440807, 1612.4437069211126, 1935.5838494723632, 1995.7605087389516, 8248.67005319788, 8461.746147615566, 8899.510526921016, 9484.193843523291, 9499.192568631666, 7552.5846078465665, 8312.501249925008, 8661.956690216555, 9028.458292502455, 9101.317401194903, 2424.187897054489, 2458.4714286428416, 2461.2616215135477, 2464.494778570389, 2470.728219895819, 2418.7823095921362, 2430.1858877276136, 2473.332200305916, 2475.777180053795, 2486.4032632168332, 2419.6491508731224, 2439.2804122783823, 2446.54738873309, 2454.472288457981, 2477.095860011092, 2379.5454432598644, 2440.9389765255833, 2447.070113356931, 2452.3256104571205, 2456.980353153439, 2418.6734790803193, 2431.902723891042, 2432.963505547418, 2435.476372579904, 2443.841150325223, 9145.817083658356, 9235.815283694355, 9262.814743705156, 9344.06559344067, 9391.859122113174, 5365.409804921455, 5400.297961265011, 5486.698231597261, 5505.559555235612, 5538.723063846792, 3495.772774769657, 3646.5988741238443, 3659.2681463707363, 3680.3375392429352, 3707.054701051242, 1088.4394536813547, 1093.7156339351745, 1117.3686866920164, 1119.652907598647, 1126.2397881430002, 11703.122265830012, 11716.179867409284, 11737.295762254293, 11743.412829358498, 11748.823767643493, 4728.953272608755, 4781.378420805273, 4786.473487916326, 4885.706857588556, 4937.703737775673, 9818.950905245429, 9959.253056020827, 10003.999600039937, 10035.899641003496, 10050.497475126214, 5222.7127507987425, 5240.895182096337, 5268.9736551317, 5274.604404669665, 5288.8413347599635, 4505.571970662847, 4648.349231107648, 4750.3824502815105, 4956.925646115311, 5085.1355269604255, 3432.828358582061, 4121.62905338521, 4178.623923846869, 4195.811188496464, 4199.202151591188, 1329.9002574806927, 1331.8468376136868, 1342.7314537092425, 1342.9059965802496, 1346.5017943360824, 827.9030284872568, 843.7342237195264, 922.7831459606987, 948.8624149498285, 956.1920177450044, 5805.245318108618, 5862.091375836667, 5925.022371308686, 6009.308929473166, 6133.6319820810895, 444.1139925847954, 446.98212071516775, 455.03078442916376, 467.96490263230396, 473.2404490792298, 3856.9807150964066, 3943.645071943539, 4019.7588144711412, 4033.616806403387, 4058.9594104058583, 10864.402457864893, 10930.781384372269, 11183.44082795857, 11213.60752373663, 11236.66290011301, 10649.254552181274, 10740.570377184982, 10858.457077146111, 10884.72788180286, 10945.83581246282, 8473.83052338962, 8518.361122915681, 8562.785930351667, 8659.047504774344, 8728.647059705756, 6001.849953751192, 6071.876702809995, 6113.266408031065, 6165.938340616536, 6181.103739957771, 10030.398176109456, 10135.391876487434, 10136.18910487174, 10213.591456341663, 10312.639057633247, 8579.699710510102, 10040.949795250977, 10458.215633827645, 10542.15662746963, 10574.735631609272, 7089.82275443118, 7251.383632391306, 7592.468527203043, 7676.462647614613, 7936.84126317482, 8457.0, 8603.612837422455, 8636.697715580176, 8678.869816952878, 8685.565721714016, 6659.0, 7660.923390766021, 7829.412794040583, 7834.960825195838, 7953.920460795318, 7626.9618651906385, 7660.233976602518, 7708.306252437307, 7726.459147859596, 7742.264484873928, 7172.641367931687, 7325.0, 7333.413326933939, 7337.889931651136, 7341.265873412616, 5035.093683137071, 5041.747912604282, 5093.18509038552, 5255.500727430888, 5334.866628334323, 4876.128987261646, 5119.232115182604, 5122.206058061007, 5129.128048231735, 5230.267762513327], "value": 4615.190089394415, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.3670253900306, 308.16487319364506, 320.52722234703805, 321.53056537455313, 321.61406312425066, 4602.723836569804, 5395.649282796616, 5515.586331025169, 5641.633293835897, 5686.744096515657, 4452.821887124519, 4952.950470495294, 5102.081625307444, 5470.070088085025, 5580.358258800239, 4231.661467082641, 4265.338872474771, 4267.253230684633, 4267.423897773791, 4283.336082907139, 3703.907402314937, 3719.6237392164935, 4193.6645068394455, 4262.722923009995, 4267.935980960288, 4145.419641250227, 4153.9853171211935, 4172.97913510432, 4180.205760905434, 4188.350805625133, 3792.393217085281, 3944.27844787096, 4146.087860670647, 4170.624643782075, 4174.979125104385, 3616.2405894762132, 3618.3486972344967, 3618.475321078455, 3627.437747149196, 3648.5770549485455, 5327.413984461706, 5355.955588660213, 5383.757730902119, 5426.375966763794, 5440.558252063206, 3750.906227344311, 4254.808533615995, 4740.928886066712, 4744.240921452585, 4766.213574760161, 5324.520793128637, 5575.414581468968, 5694.402087780765, 5779.624324418899, 5797.913031304492, 3175.9682403175902, 3492.947605785915, 3595.442706380515, 3974.920501589952, 3978.5424676162324, 6058.697065146725, 6071.810450788941, 6126.203593532857, 6139.017757158877, 6145.231846019247, 3848.3073046851277, 4006.7378775685565, 4014.5985401460202, 4027.496562929634, 4029.7783621901076, 3498.606351838763, 3931.92136157281, 3953.426753120839, 4024.6981476389387, 4042.9892526868284, 4301.418988776943, 4355.5208927018, 4657.301404789308, 4771.96346621524, 4788.0, 4633.166030114612, 5789.565782566325, 5830.2686280780335, 5924.940750592526, 5953.672548009901, 1252.599168266146, 1274.815151803002, 1306.9934650326688, 1373.2927542315715, 1391.325207274471, 709.9138318372898, 792.8939129913738, 794.9761507154678, 813.8413009463097, 854.3208149521146, 4227.371028723543, 4302.935455968163, 4424.668149888771, 4736.081886835837, 4751.95248047522, 4415.712978656377, 4503.0, 4760.928586071212, 4976.850694479172, 5143.717095559706, 3690.612485689018, 4492.528284530143, 4607.746573938399, 4666.503420669926, 4715.7877895494785, 2059.7631272403564, 2490.5457049062497, 2562.3337932137406, 2681.6781986161786, 2690.6636670416196, 4407.625351845129, 4680.53194680536, 4915.508449155127, 4988.455887497403, 5288.286081379041, 4528.909421811545, 4545.749983750926, 4737.862912900879, 5177.171652535651, 5214.634975551675, 4147.440095587118, 4724.716517008922, 4822.324874517571, 5015.448300686921, 5341.356028765786, 4414.955850441583, 4838.371011768448, 5101.744912754273, 5129.717865517432, 5176.767045482887, 4183.958160418357, 5012.343457967283, 5055.671381360347, 5060.0, 5199.948000520098, 4832.163777764567, 4919.680220785637, 5234.545200167871, 5236.826716912438, 5315.9468405317, 3087.876484940577, 3173.5557022016474, 3543.4684797280615, 3550.7336949729392, 3552.200754830246, 4708.505606911225, 5081.798466965788, 5129.666571672829, 5145.446280042264, 5151.922721159261, 5218.791248350175, 5404.675719456845, 5658.321001479849, 5892.764289428546, 5897.3807750185615, 5863.765449381977, 5897.262842144732, 5916.763329466773, 5931.8432905582995, 5934.821955341346, 4414.366684326819, 4829.6860704054125, 5076.0, 5437.864053398696, 5796.884062318814, 5539.532024013559, 5608.298962629671, 5989.011813050798, 5989.735594101138, 5991.812988259167, 4834.6374021947795, 5793.333766616893, 5853.736581853912, 5855.423613609998, 5856.209411729362], "value": 3968.912539034034, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.4463807506297, 767.2519293688654, 783.3106865957956, 855.9058503564611, 858.6093327535972, 15715.44995925143, 15937.681246375065, 16685.749713754303, 16688.165591720426, 16746.83253167468, 12160.513579456834, 14641.560753177395, 14757.18835464049, 14971.176585287832, 14983.700325993474, 675.4562577125412, 702.5328156775763, 860.9741707748747, 877.6840337478519, 895.7044175422128, 734.51521995483, 869.2350731356397, 869.6173683579204, 870.4646642314963, 872.3413822563955, 874.8862647855801, 918.5866360137932, 926.2404828041009, 927.4945154890601, 928.6517555916531, 896.672714459221, 912.3841407050214, 913.3013244867682, 915.587985406563, 921.3412410126758, 546.3443867359146, 677.529117263502, 696.2237105627189, 697.069412334534, 701.828052127227, 14097.0, 14324.92837535816, 14585.19781412022, 14643.267836608127, 14675.412983480755, 14901.403943842342, 14997.25013749319, 15115.093094414371, 15135.848641513665, 15197.392104315924, 11505.194636375458, 11684.883151168464, 11919.642410727693, 12130.968867646172, 12542.0, 4561.498235194126, 4582.908341833178, 4623.676342656016, 4647.465541462708, 4694.906101877943, 105095.4745226269, 118767.0, 119209.0, 120168.0, 121720.39139804244, 10766.78466430667, 11291.887081129247, 11318.434078296052, 11485.655430336936, 11538.826917596243, 21313.68029479559, 21329.933503324773, 21397.14411423557, 21502.0, 21926.013329400215, 7024.648767561602, 8061.879071813927, 8144.5520496373265, 8154.8776768348525, 8181.345492360597, 3745.962540374616, 4327.7186982846015, 4425.712328698624, 4466.57567531084, 4476.2837945928495, 3491.6682915123024, 3631.927361452756, 3636.5454318210222, 3639.6906262967946, 3641.8361173747244, 552.9502344788988, 659.1299484680176, 662.4634046422414, 673.4376795375916, 674.5143496682381, 542.6011881267266, 602.8071017274518, 632.6741728010079, 633.8700566383881, 650.9772157974543, 7892.723754668559, 8681.913180868236, 8929.642814287487, 9220.723378298662, 9389.0, 530.1332321654123, 592.655832745971, 633.4503622917792, 646.3407324528954, 650.7982525417136, 4352.0, 4497.145542346943, 4503.121891231179, 4525.230710779176, 4613.838515651936, 103165.0, 138495.0, 159568.0, 159700.0, 161516.0, 139300.9104863428, 139968.0, 141628.2918585421, 144487.0, 145005.0, 72613.54772904512, 84495.0, 119953.40023299828, 121923.39038304752, 123022.0, 8550.401471896908, 8731.738047858575, 8797.692080777142, 8805.251553617758, 8941.37410381267, 65757.67121164364, 65893.34106658872, 67547.3245267541, 67721.0, 68841.0, 60392.49018774566, 70110.94833577398, 95811.0, 98260.0, 99932.00067999227, 11129.887011298806, 11205.327680339205, 11391.601293954673, 11457.65627031157, 11538.0, 12236.571719990114, 12254.142210045567, 12345.753084938431, 12447.190932589352, 12493.75012499763, 11490.0, 11527.193096483166, 11849.052075834084, 11849.763004740029, 12036.699082522657, 9544.618215271312, 11574.0, 11786.116041296766, 11948.163628545919, 11981.101417393904, 9780.41317520951, 10913.945430272797, 11040.0, 11052.944735276273, 11444.912733290657, 8333.833323333378, 9513.571889264862, 9706.445055602078, 10036.397816131055, 10687.679369618923, 8063.0, 9898.653547125816, 9980.0, 10151.492425378554, 10164.136048435967], "value": 7624.157474478032, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1539.5999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 117.0, 116.0, 120.0, 129.0, 171.0, 160.0, 116.0, 116.0], "value": 126.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 109.0, 110.0, 155.0, 111.0, 110.0, 120.0, 115.0, 174.0, 111.0], "value": 125.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 198.0, 195.0, 204.0, 198.0, 196.0, 198.0, 198.0, 199.0, 266.0], "value": 204.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 53.0, 83.0, 72.0, 66.0, 82.0, 84.0, 82.0, 102.0, 59.0], "value": 75.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 58.0, 72.0, 60.0, 56.0, 55.0, 71.0, 79.0, 101.0, 117.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 88.0, 91.0, 130.0, 123.0, 107.0, 81.0, 133.0, 135.0, 117.0], "value": 109.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 97.0, 99.0, 102.0], "value": 102.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 91.0, 90.0, 88.0, 139.0, 84.0, 145.0, 146.0, 123.0], "value": 120.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 54.0, 60.0, 58.0, 58.0, 56.0, 57.0, 58.0], "value": 56.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 65.0, 74.0, 71.0, 64.0, 57.0, 77.0, 92.0, 99.0], "value": 73.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 86.0, 82.0, 83.0, 89.0, 83.0, 84.0, 85.0, 86.0, 90.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 129.0, 146.0, 149.0, 149.0, 126.0, 144.0, 149.0, 167.0, 145.0], "value": 142.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 164.0, 162.0, 223.0, 223.0, 163.0, 163.0, 162.0, 160.0], "value": 175.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 65.0, 68.0, 65.0, 67.0, 66.0, 65.0, 66.0, 65.0], "value": 66.0, "unit": "score"}]}]} 22:41:52 INFO - Return code: 0 22:41:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:41:52 ERROR - # TBPL SUCCESS # 22:41:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:41:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:41:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:41:52 INFO - 2017-01-11 22:41:52.876 screenresolution[1666:11841] starting screenresolution argv=screenresolution get 22:41:52 INFO - 2017-01-11 22:41:52.879 screenresolution[1666:11841] Display 0: 1600x1200x32@60 22:41:52 INFO - 2017-01-11 22:41:52.885 screenresolution[1667:11844] starting screenresolution argv=screenresolution list 22:41:52 INFO - Available Modes on Display 0 22:41:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:41:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:41:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:41:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:41:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:41:53 INFO - Intel Iris: 22:41:53 INFO - Chipset Model: Intel Iris 22:41:53 INFO - Type: GPU 22:41:53 INFO - Bus: Built-In 22:41:53 INFO - VRAM (Dynamic, Max): 1536 MB 22:41:53 INFO - Vendor: Intel (0x8086) 22:41:53 INFO - Device ID: 0x0a2e 22:41:53 INFO - Revision ID: 0x0009 22:41:53 INFO - Displays: 22:41:53 INFO - Display: 22:41:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:41:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:41:53 INFO - Display Serial Number: 1600x1200 60 22:41:53 INFO - Main Display: Yes 22:41:53 INFO - Mirror: Off 22:41:53 INFO - Online: Yes 22:41:53 INFO - Rotation: Supported 22:41:53 INFO - Return code: 0 22:41:53 INFO - Running post-action listener: _resource_record_post_action 22:41:53 INFO - [mozharness: 2017-01-12 06:41:53.120565Z] Finished run-tests step (success) 22:41:53 INFO - Running post-run listener: _resource_record_post_run 22:41:53 INFO - Total resource usage - Wall time: 1194s; CPU: 54.0%; Read bytes: 33446912; Write bytes: 500131840; Read time: 310; Write time: 1867 22:41:53 INFO - TinderboxPrint: CPU usage
54.3% 22:41:53 INFO - TinderboxPrint: I/O read bytes / time
33,446,912 / 310 22:41:53 INFO - TinderboxPrint: I/O write bytes / time
500,131,840 / 1,867 22:41:53 INFO - TinderboxPrint: CPU idle
2,181.9 (45.7%) 22:41:53 INFO - TinderboxPrint: CPU system
968.0 (20.3%) 22:41:53 INFO - TinderboxPrint: CPU user
1,627.9 (34.1%) 22:41:53 INFO - TinderboxPrint: Swap in / out
224,747,520 / 0 22:41:53 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 194810368; Write bytes: 188917760; Read time: 17786; Write time: 436 22:41:53 INFO - run-tests - Wall time: 1172s; CPU: 54.0%; Read bytes: 31382528; Write bytes: 301006848; Read time: 287; Write time: 1396 22:41:53 INFO - Running post-run listener: _upload_blobber_files 22:41:53 INFO - Blob upload gear active. 22:41:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:41:53 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 22:41:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:41:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:41:54 INFO - (blobuploader) - INFO - Open directory for files ... 22:41:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:41:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:41:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:41:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:41:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:41:55 INFO - (blobuploader) - INFO - Done attempting. 22:41:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:41:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:41:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:41:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:41:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:41:56 INFO - (blobuploader) - INFO - Done attempting. 22:41:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:41:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:41:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:41:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:41:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:41:58 INFO - (blobuploader) - INFO - Done attempting. 22:41:58 INFO - (blobuploader) - INFO - Iteration through files over. 22:41:58 INFO - Return code: 0 22:41:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:41:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:41:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3d1920f338093c3ee736bb1301f426c755b46212ef8170a8f7d25f77973c61f535d90ed601a1bc47a3e20e7414c81e6304f68a4b067cc7651f682bc9a2dec0e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9380a84fcb79c50ce92f89e7a62e6047500a23e8f4db89311ce3d97eff0a9488a4e59acb964cdd06c2354c2b0d40292e5174a9c70df84dc9f5b1a59ae33de67c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/82b3d09cf7880b28a823df9eafa52c3e49eed60ba50860494d8f2e7cdbad84f157cab95c68ef0852f28c2c9f03243dfdfd4998557557d754a8e043fb184a2fb2"} 22:41:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:41:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:41:58 INFO - Contents: 22:41:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3d1920f338093c3ee736bb1301f426c755b46212ef8170a8f7d25f77973c61f535d90ed601a1bc47a3e20e7414c81e6304f68a4b067cc7651f682bc9a2dec0e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9380a84fcb79c50ce92f89e7a62e6047500a23e8f4db89311ce3d97eff0a9488a4e59acb964cdd06c2354c2b0d40292e5174a9c70df84dc9f5b1a59ae33de67c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/82b3d09cf7880b28a823df9eafa52c3e49eed60ba50860494d8f2e7cdbad84f157cab95c68ef0852f28c2c9f03243dfdfd4998557557d754a8e043fb184a2fb2"} 22:41:58 INFO - Running post-run listener: copy_logs_to_upload_dir 22:41:58 INFO - Copying logs to upload dir... 22:41:58 INFO - mkdir: /builds/slave/test/build/upload/logs 22:41:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.130118 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-01-11 22:41:58.873057) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 22:41:58.876825) ========= 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.Kpi1eMKemA/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.jx77udWlSL/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3d1920f338093c3ee736bb1301f426c755b46212ef8170a8f7d25f77973c61f535d90ed601a1bc47a3e20e7414c81e6304f68a4b067cc7651f682bc9a2dec0e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9380a84fcb79c50ce92f89e7a62e6047500a23e8f4db89311ce3d97eff0a9488a4e59acb964cdd06c2354c2b0d40292e5174a9c70df84dc9f5b1a59ae33de67c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/82b3d09cf7880b28a823df9eafa52c3e49eed60ba50860494d8f2e7cdbad84f157cab95c68ef0852f28c2c9f03243dfdfd4998557557d754a8e043fb184a2fb2"} build_url:https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011456 build_url: 'https://queue.taskcluster.net/v1/task/adVIvxIyRHefqh7Jw1fY7g/artifacts/public/build/firefox-51.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3d1920f338093c3ee736bb1301f426c755b46212ef8170a8f7d25f77973c61f535d90ed601a1bc47a3e20e7414c81e6304f68a4b067cc7651f682bc9a2dec0e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9380a84fcb79c50ce92f89e7a62e6047500a23e8f4db89311ce3d97eff0a9488a4e59acb964cdd06c2354c2b0d40292e5174a9c70df84dc9f5b1a59ae33de67c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/82b3d09cf7880b28a823df9eafa52c3e49eed60ba50860494d8f2e7cdbad84f157cab95c68ef0852f28c2c9f03243dfdfd4998557557d754a8e043fb184a2fb2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 22:41:58.911561) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 22:41:58.911880) ========= 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.Kpi1eMKemA/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.jx77udWlSL/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.005255 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 22:41:58.960131) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 22:41:58.960446) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 22:41:58.960801) ========= ========= Total master_lag: 0.19 =========