builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0225 starttime: 1473268394.74 results: success (0) buildid: 20160907082120 builduid: d33f6c600360482da7f7644cd1acb751 revision: 95acb9299fafdc69463c49860caf367e4fbcc8e3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:14.738669) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:14.739066) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:14.739325) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:14.770935) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:14.771176) ========= 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.Xv6gL4ENIo/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.Gx4sY1eX1L/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-07 10:13:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.8M=0.001s 2016-09-07 10:13:14 (18.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.159688 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:14.952944) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:14.953215) ========= 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.Xv6gL4ENIo/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.Gx4sY1eX1L/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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.026116 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:15.001031) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-07 10:13:15.001325) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 95acb9299fafdc69463c49860caf367e4fbcc8e3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 95acb9299fafdc69463c49860caf367e4fbcc8e3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Xv6gL4ENIo/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.Gx4sY1eX1L/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-07 10:13:15,084 truncating revision to first 12 chars 2016-09-07 10:13:15,084 Setting DEBUG logging. 2016-09-07 10:13:15,084 attempt 1/10 2016-09-07 10:13:15,084 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/95acb9299faf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 10:13:16,107 unpacking tar archive at: mozilla-central-95acb9299faf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.291394 ========= master_lag: 0.21 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-07 10:13:16.503011) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:16.503271) ========= script_repo_revision: 95acb9299fafdc69463c49860caf367e4fbcc8e3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:16.503622) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:16.503909) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:16.541418) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-07 10:13:16.541663) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Xv6gL4ENIo/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.Gx4sY1eX1L/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:13:16 INFO - MultiFileLogger online at 20160907 10:13:16 in /builds/slave/test 10:13:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:13:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:13:16 INFO - {'append_to_log': False, 10:13:16 INFO - 'base_work_dir': '/builds/slave/test', 10:13:16 INFO - 'blob_upload_branch': 'Firefox', 10:13:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:13:16 INFO - 'branch': 'Firefox', 10:13:16 INFO - 'buildbot_json_path': 'buildprops.json', 10:13:16 INFO - 'config_files': ('talos/mac_config.py',), 10:13:16 INFO - 'default_actions': ('clobber', 10:13:16 INFO - 'read-buildbot-config', 10:13:16 INFO - 'download-and-extract', 10:13:16 INFO - 'populate-webroot', 10:13:16 INFO - 'create-virtualenv', 10:13:16 INFO - 'install', 10:13:16 INFO - 'run-tests'), 10:13:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:13:16 INFO - 'download_minidump_stackwalk': True, 10:13:16 INFO - 'download_symbols': 'ondemand', 10:13:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:13:16 INFO - 'tooltool.py': '/tools/tooltool.py', 10:13:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:13:16 INFO - '/tools/misc-python/virtualenv.py')}, 10:13:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:13:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:13:16 INFO - 'installer_path': 'installer.exe', 10:13:16 INFO - 'log_level': 'info', 10:13:16 INFO - 'log_name': 'talos', 10:13:16 INFO - 'log_to_console': True, 10:13:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:13:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:13:16 INFO - 'opt_config_files': (), 10:13:16 INFO - 'pip_index': False, 10:13:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:13:16 INFO - 'cmd': ('bash', 10:13:16 INFO - '-c', 10:13:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:13:16 INFO - 'enabled': True, 10:13:16 INFO - 'halt_on_failure': False, 10:13:16 INFO - 'name': 'check_screen_resolution'},), 10:13:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:13:16 INFO - 'cmd': ('bash', 10:13:16 INFO - '-c', 10:13:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:13:16 INFO - 'enabled': True, 10:13:16 INFO - 'halt_on_failure': False, 10:13:16 INFO - 'name': 'check_screen_resolution'},), 10:13:16 INFO - 'run_cmd_checks_enabled': True, 10:13:16 INFO - 'sps_profile': False, 10:13:16 INFO - 'sps_profile_interval': 0, 10:13:16 INFO - 'suite': 'dromaeojs-e10s', 10:13:16 INFO - 'system_bits': '32', 10:13:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:13:16 INFO - 'title': 't-yosemite-r7-0225', 10:13:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:13:16 INFO - 'use_talos_json': True, 10:13:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:13:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:13:16 INFO - 'work_dir': 'build'} 10:13:16 INFO - [mozharness: 2016-09-07 17:13:16.739095Z] Running clobber step. 10:13:16 INFO - Running pre-action listener: _resource_record_pre_action 10:13:16 INFO - Running main action method: clobber 10:13:16 INFO - rmtree: /builds/slave/test/build 10:13:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:13:18 INFO - Running post-action listener: _resource_record_post_action 10:13:18 INFO - [mozharness: 2016-09-07 17:13:18.724150Z] Finished clobber step (success) 10:13:18 INFO - [mozharness: 2016-09-07 17:13:18.724286Z] Running read-buildbot-config step. 10:13:18 INFO - Running pre-action listener: _resource_record_pre_action 10:13:18 INFO - Running main action method: read_buildbot_config 10:13:18 INFO - Using buildbot properties: 10:13:18 INFO - { 10:13:18 INFO - "project": "", 10:13:18 INFO - "product": "firefox", 10:13:18 INFO - "script_repo_revision": "production", 10:13:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:13:18 INFO - "repository": "", 10:13:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:13:18 INFO - "buildid": "20160907082120", 10:13:18 INFO - "pgo_build": "False", 10:13:18 INFO - "basedir": "/builds/slave/test", 10:13:18 INFO - "buildnumber": 20, 10:13:18 INFO - "slavename": "t-yosemite-r7-0225", 10:13:18 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:13:18 INFO - "platform": "yosemite_r7", 10:13:18 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:13:18 INFO - "branch": "mozilla-central", 10:13:18 INFO - "repo_path": "mozilla-central", 10:13:18 INFO - "revision": "95acb9299fafdc69463c49860caf367e4fbcc8e3", 10:13:18 INFO - "stage_platform": "macosx64", 10:13:18 INFO - "builduid": "d33f6c600360482da7f7644cd1acb751", 10:13:18 INFO - "slavebuilddir": "test" 10:13:18 INFO - } 10:13:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 10:13:18 INFO - Running post-action listener: _resource_record_post_action 10:13:18 INFO - [mozharness: 2016-09-07 17:13:18.727625Z] Finished read-buildbot-config step (success) 10:13:18 INFO - [mozharness: 2016-09-07 17:13:18.727744Z] Running download-and-extract step. 10:13:18 INFO - Running pre-action listener: _resource_record_pre_action 10:13:18 INFO - Running main action method: download_and_extract 10:13:18 INFO - mkdir: /builds/slave/test/build/tests 10:13:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:18 INFO - trying https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 10:13:18 INFO - Downloading https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 10:13:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 10:13:20 INFO - Downloaded 1199 bytes. 10:13:20 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 10:13:20 INFO - Using the following test package requirements: 10:13:20 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:13:20 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:13:20 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 10:13:20 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:13:20 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 10:13:20 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:13:20 INFO - u'jsshell-mac.zip'], 10:13:20 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:13:20 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 10:13:20 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:13:20 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:13:20 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 10:13:20 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:13:20 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 10:13:20 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:13:20 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 10:13:20 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:13:20 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 10:13:20 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:13:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:13:21 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 10:13:28 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:13:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:13:28 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 10:13:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 10:13:36 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 10:13:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:37 INFO - trying https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:13:37 INFO - Downloading https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 10:13:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 10:13:40 INFO - Downloaded 111562979 bytes. 10:13:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:13:40 INFO - mkdir: /builds/slave/test/properties 10:13:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:13:40 INFO - Writing to file /builds/slave/test/properties/build_url 10:13:40 INFO - Contents: 10:13:40 INFO - build_url:https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:13:40 INFO - Running post-action listener: _resource_record_post_action 10:13:40 INFO - Running post-action listener: set_extra_try_arguments 10:13:40 INFO - [mozharness: 2016-09-07 17:13:40.707829Z] Finished download-and-extract step (success) 10:13:40 INFO - [mozharness: 2016-09-07 17:13:40.707947Z] Running populate-webroot step. 10:13:40 INFO - Running pre-action listener: _resource_record_pre_action 10:13:40 INFO - Running main action method: populate_webroot 10:13:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:13:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:13:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:13:40 INFO - '--tppagecycles', 10:13:40 INFO - '7'], 10:13:40 INFO - 'tests': ['tsvgm']}}, 10:13:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:13:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:13:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:13:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:13:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:40 INFO - 'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:13:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:13:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:40 INFO - 'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['damp', 'tps']}, 10:13:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:40 INFO - 'tests': ['damp', 'tps']}, 10:13:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['dromaeo_dom']}, 10:13:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:13:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['basic_compositor_video']}, 10:13:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:13:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['a11yr', 10:13:40 INFO - 'ts_paint', 10:13:40 INFO - 'tpaint', 10:13:40 INFO - 'sessionrestore', 10:13:40 INFO - 'sessionrestore_no_auto_restore', 10:13:40 INFO - 'tabpaint']}, 10:13:40 INFO - 'other-e10s': {'tests': ['a11yr', 10:13:40 INFO - 'ts_paint', 10:13:40 INFO - 'tpaint', 10:13:40 INFO - 'sessionrestore', 10:13:40 INFO - 'sessionrestore_no_auto_restore', 10:13:40 INFO - 'tabpaint']}, 10:13:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:13:40 INFO - 'ts_paint', 10:13:40 INFO - 'tpaint', 10:13:40 INFO - 'sessionrestore', 10:13:40 INFO - 'sessionrestore_no_auto_restore', 10:13:40 INFO - 'tabpaint']}, 10:13:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:13:40 INFO - 'ts_paint', 10:13:40 INFO - 'tpaint', 10:13:40 INFO - 'sessionrestore', 10:13:40 INFO - 'sessionrestore_no_auto_restore', 10:13:40 INFO - 'tabpaint']}, 10:13:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['a11yr', 10:13:40 INFO - 'ts_paint', 10:13:40 INFO - 'tpaint', 10:13:40 INFO - 'sessionrestore', 10:13:40 INFO - 'sessionrestore_no_auto_restore', 10:13:40 INFO - 'tabpaint']}, 10:13:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['a11yr', 10:13:40 INFO - 'ts_paint', 10:13:40 INFO - 'tpaint', 10:13:40 INFO - 'sessionrestore', 10:13:40 INFO - 'sessionrestore_no_auto_restore', 10:13:40 INFO - 'tabpaint']}, 10:13:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['tsvgx', 10:13:40 INFO - 'tsvgr_opacity', 10:13:40 INFO - 'tart', 10:13:40 INFO - 'tscrollx', 10:13:40 INFO - 'cart']}, 10:13:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:13:40 INFO - 'tsvgr_opacity', 10:13:40 INFO - 'tart', 10:13:40 INFO - 'tscrollx', 10:13:40 INFO - 'cart']}, 10:13:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:40 INFO - 'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['tp5o']}, 10:13:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:40 INFO - 'tests': ['tp5o']}, 10:13:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:40 INFO - 'talos_options': ['--disable-e10s', 10:13:40 INFO - '--xperf_path', 10:13:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:13:40 INFO - 'tests': ['tp5n']}, 10:13:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:40 INFO - 'talos_options': ['--xperf_path', 10:13:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:13:40 INFO - 'tests': ['tp5n']}}, 10:13:40 INFO - 'talos.zip': {'path': '', 10:13:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:13:40 INFO - Running post-action listener: _resource_record_post_action 10:13:40 INFO - [mozharness: 2016-09-07 17:13:40.727027Z] Finished populate-webroot step (success) 10:13:40 INFO - [mozharness: 2016-09-07 17:13:40.727155Z] Running create-virtualenv step. 10:13:40 INFO - Running pre-action listener: _resource_record_pre_action 10:13:40 INFO - Running main action method: create_virtualenv 10:13:40 INFO - Creating virtualenv /builds/slave/test/build/venv 10:13:40 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:13:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:13:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:13:40 INFO - Using real prefix '/tools/python27' 10:13:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:13:41 INFO - Installing distribute.............................................................................................................................................................................................done. 10:13:44 INFO - Installing pip.................done. 10:13:44 INFO - Return code: 0 10:13:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:13:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:44 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 0x10e115a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef20c8bac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e079d30>, '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 0x10dddedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e071b20>, '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.Xv6gL4ENIo/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.Gx4sY1eX1L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:13:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:13:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:13:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xv6gL4ENIo/Render', 10:13:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:44 INFO - 'HOME': '/Users/cltbld', 10:13:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:44 INFO - 'LOGNAME': 'cltbld', 10:13:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:44 INFO - 'MOZ_NO_REMOTE': '1', 10:13:44 INFO - 'NO_EM_RESTART': '1', 10:13:44 INFO - 'PAGER': '/bin/cat', 10:13:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:44 INFO - 'PWD': '/builds/slave/test', 10:13:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:44 INFO - 'SHELL': '/bin/bash', 10:13:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gx4sY1eX1L/Listeners', 10:13:44 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 10:13:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:44 INFO - 'USER': 'cltbld', 10:13:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:44 INFO - 'XPC_FLAGS': '0x0', 10:13:44 INFO - 'XPC_SERVICE_NAME': '0', 10:13:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:13:45 INFO - Cleaning up... 10:13:45 INFO - Return code: 0 10:13:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:13:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:45 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 0x10e115a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef20c8bac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e079d30>, '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 0x10dddedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e071b20>, '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.Xv6gL4ENIo/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.Gx4sY1eX1L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:13:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:13:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:13:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xv6gL4ENIo/Render', 10:13:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:45 INFO - 'HOME': '/Users/cltbld', 10:13:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:45 INFO - 'LOGNAME': 'cltbld', 10:13:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:45 INFO - 'MOZ_NO_REMOTE': '1', 10:13:45 INFO - 'NO_EM_RESTART': '1', 10:13:45 INFO - 'PAGER': '/bin/cat', 10:13:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:45 INFO - 'PWD': '/builds/slave/test', 10:13:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:45 INFO - 'SHELL': '/bin/bash', 10:13:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gx4sY1eX1L/Listeners', 10:13:45 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 10:13:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:45 INFO - 'USER': 'cltbld', 10:13:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:45 INFO - 'XPC_FLAGS': '0x0', 10:13:45 INFO - 'XPC_SERVICE_NAME': '0', 10:13:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:45 INFO - Downloading/unpacking psutil>=3.1.1 10:13:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:13:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:13:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:13:48 INFO - Installing collected packages: psutil 10:13:48 INFO - Running setup.py install for psutil 10:13:48 INFO - building 'psutil._psutil_osx' extension 10:13:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:13:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:13:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:13:49 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:13:49 INFO - building 'psutil._psutil_posix' extension 10:13:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:13:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:13:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:13:49 INFO - ^ 10:13:49 INFO - 1 warning generated. 10:13:49 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:13:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:13:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:13:49 INFO - Successfully installed psutil 10:13:49 INFO - Cleaning up... 10:13:49 INFO - Return code: 0 10:13:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:13:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e115a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef20c8bac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e079d30>, '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 0x10dddedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e071b20>, '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.Xv6gL4ENIo/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.Gx4sY1eX1L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:13:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:13:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xv6gL4ENIo/Render', 10:13:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:49 INFO - 'HOME': '/Users/cltbld', 10:13:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:49 INFO - 'LOGNAME': 'cltbld', 10:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:49 INFO - 'MOZ_NO_REMOTE': '1', 10:13:49 INFO - 'NO_EM_RESTART': '1', 10:13:49 INFO - 'PAGER': '/bin/cat', 10:13:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:49 INFO - 'PWD': '/builds/slave/test', 10:13:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:49 INFO - 'SHELL': '/bin/bash', 10:13:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gx4sY1eX1L/Listeners', 10:13:49 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 10:13:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:49 INFO - 'USER': 'cltbld', 10:13:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:49 INFO - 'XPC_FLAGS': '0x0', 10:13:49 INFO - 'XPC_SERVICE_NAME': '0', 10:13:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:13:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:13:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:13:52 INFO - Installing collected packages: mozsystemmonitor 10:13:52 INFO - Running setup.py install for mozsystemmonitor 10:13:52 INFO - Successfully installed mozsystemmonitor 10:13:52 INFO - Cleaning up... 10:13:52 INFO - Return code: 0 10:13:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:13: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')]} 10:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13: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')]} 10:13:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e115a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef20c8bac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e079d30>, '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 0x10dddedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e071b20>, '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.Xv6gL4ENIo/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.Gx4sY1eX1L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:13:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:13:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:13:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xv6gL4ENIo/Render', 10:13:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:52 INFO - 'HOME': '/Users/cltbld', 10:13:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:52 INFO - 'LOGNAME': 'cltbld', 10:13:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:52 INFO - 'MOZ_NO_REMOTE': '1', 10:13:52 INFO - 'NO_EM_RESTART': '1', 10:13:52 INFO - 'PAGER': '/bin/cat', 10:13:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:52 INFO - 'PWD': '/builds/slave/test', 10:13:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:52 INFO - 'SHELL': '/bin/bash', 10:13:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gx4sY1eX1L/Listeners', 10:13:52 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 10:13:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:52 INFO - 'USER': 'cltbld', 10:13:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:52 INFO - 'XPC_FLAGS': '0x0', 10:13:52 INFO - 'XPC_SERVICE_NAME': '0', 10:13:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:52 INFO - Downloading/unpacking jsonschema==2.5.1 10:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:13:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:55 INFO - Downloading functools32-3.2.3-2.tar.gz 10:13:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:13:55 INFO - warning: no files found matching '*.txt' 10:13:55 INFO - no previously-included directories found matching 'build' 10:13:55 INFO - no previously-included directories found matching 'dist' 10:13:55 INFO - no previously-included directories found matching '.git*' 10:13:55 INFO - Installing collected packages: jsonschema, functools32 10:13:55 INFO - Running setup.py install for functools32 10:13:56 INFO - warning: no files found matching '*.txt' 10:13:56 INFO - no previously-included directories found matching 'build' 10:13:56 INFO - no previously-included directories found matching 'dist' 10:13:56 INFO - no previously-included directories found matching '.git*' 10:13:56 INFO - Successfully installed jsonschema functools32 10:13:56 INFO - Cleaning up... 10:13:56 INFO - Return code: 0 10:13:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:56 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 0x10e115a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef20c8bac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e079d30>, '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 0x10dddedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e071b20>, '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.Xv6gL4ENIo/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.Gx4sY1eX1L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:13:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:13:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:13:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xv6gL4ENIo/Render', 10:13:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:56 INFO - 'HOME': '/Users/cltbld', 10:13:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:56 INFO - 'LOGNAME': 'cltbld', 10:13:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:56 INFO - 'MOZ_NO_REMOTE': '1', 10:13:56 INFO - 'NO_EM_RESTART': '1', 10:13:56 INFO - 'PAGER': '/bin/cat', 10:13:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:56 INFO - 'PWD': '/builds/slave/test', 10:13:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:56 INFO - 'SHELL': '/bin/bash', 10:13:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gx4sY1eX1L/Listeners', 10:13:56 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 10:13:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:56 INFO - 'USER': 'cltbld', 10:13:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:56 INFO - 'XPC_FLAGS': '0x0', 10:13:56 INFO - 'XPC_SERVICE_NAME': '0', 10:13:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:13:56 INFO - Cleaning up... 10:13:56 INFO - Return code: 0 10:13:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:56 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 0x10e115a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef20c8bac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e079d30>, '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 0x10dddedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e071b20>, '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.Xv6gL4ENIo/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.Gx4sY1eX1L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:13:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:13:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:13:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xv6gL4ENIo/Render', 10:13:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:56 INFO - 'HOME': '/Users/cltbld', 10:13:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:56 INFO - 'LOGNAME': 'cltbld', 10:13:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:56 INFO - 'MOZ_NO_REMOTE': '1', 10:13:56 INFO - 'NO_EM_RESTART': '1', 10:13:56 INFO - 'PAGER': '/bin/cat', 10:13:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:56 INFO - 'PWD': '/builds/slave/test', 10:13:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:56 INFO - 'SHELL': '/bin/bash', 10:13:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gx4sY1eX1L/Listeners', 10:13:56 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 10:13:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:56 INFO - 'USER': 'cltbld', 10:13:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:56 INFO - 'XPC_FLAGS': '0x0', 10:13:56 INFO - 'XPC_SERVICE_NAME': '0', 10:13:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:56 INFO - Downloading/unpacking blobuploader==1.2.4 10:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:59 INFO - Downloading blobuploader-1.2.4.tar.gz 10:13:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:13:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:13:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:13:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:13:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:00 INFO - Downloading docopt-0.6.1.tar.gz 10:14:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:14:00 INFO - Installing collected packages: blobuploader, requests, docopt 10:14:00 INFO - Running setup.py install for blobuploader 10:14:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:14:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:14:00 INFO - Running setup.py install for requests 10:14:01 INFO - Running setup.py install for docopt 10:14:01 INFO - Successfully installed blobuploader requests docopt 10:14:01 INFO - Cleaning up... 10:14:01 INFO - Return code: 0 10:14:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:14:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:01 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 0x10e115a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef20c8bac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e079d30>, '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 0x10dddedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e071b20>, '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.Xv6gL4ENIo/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.Gx4sY1eX1L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:14:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:14:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:14:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xv6gL4ENIo/Render', 10:14:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:01 INFO - 'HOME': '/Users/cltbld', 10:14:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:01 INFO - 'LOGNAME': 'cltbld', 10:14:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:01 INFO - 'MOZ_NO_REMOTE': '1', 10:14:01 INFO - 'NO_EM_RESTART': '1', 10:14:01 INFO - 'PAGER': '/bin/cat', 10:14:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:01 INFO - 'PWD': '/builds/slave/test', 10:14:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:01 INFO - 'SHELL': '/bin/bash', 10:14:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gx4sY1eX1L/Listeners', 10:14:01 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 10:14:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:01 INFO - 'USER': 'cltbld', 10:14:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:01 INFO - 'XPC_FLAGS': '0x0', 10:14:01 INFO - 'XPC_SERVICE_NAME': '0', 10:14:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:14:01 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-irxDbO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:14:01 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-SO3weD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:14:01 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-qNomtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:14:01 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-5EVBmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:14:02 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-85uOra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:14:02 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-wl3rSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:14:02 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-b1sPxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:14:02 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-Sd7Nbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:14:02 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-XkT9lC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:14:02 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-28UNo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:14:02 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-B4bJGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:14:02 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-eHapjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:14:02 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-yg7Df3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:14:02 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-vnKa6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:14:03 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-Nyz4dv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:14:03 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-j3lzf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:14:03 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-yOCY9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:14:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:14:03 INFO - Running setup.py install for manifestparser 10:14:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:14:03 INFO - Running setup.py install for mozcrash 10:14:03 INFO - Running setup.py install for mozdebug 10:14:03 INFO - Running setup.py install for mozdevice 10:14:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:14:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:14:03 INFO - Running setup.py install for mozfile 10:14:04 INFO - Running setup.py install for mozhttpd 10:14:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:14:04 INFO - Running setup.py install for mozinfo 10:14:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:14:04 INFO - Running setup.py install for mozInstall 10:14:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:14:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:14:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:14:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:14:04 INFO - Running setup.py install for mozleak 10:14:04 INFO - Running setup.py install for mozlog 10:14:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:14:04 INFO - Running setup.py install for moznetwork 10:14:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:14:04 INFO - Running setup.py install for mozprocess 10:14:05 INFO - Running setup.py install for mozprofile 10:14:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:14:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:14:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:14:05 INFO - Running setup.py install for mozrunner 10:14:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:14:05 INFO - Running setup.py install for mozscreenshot 10:14:05 INFO - Running setup.py install for moztest 10:14:05 INFO - Running setup.py install for mozversion 10:14:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:14:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:14:05 INFO - Cleaning up... 10:14:05 INFO - Return code: 0 10:14:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:05 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 0x10e115a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef20c8bac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e079d30>, '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 0x10dddedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e071b20>, '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.Xv6gL4ENIo/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.Gx4sY1eX1L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:14:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:14:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xv6gL4ENIo/Render', 10:14:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:05 INFO - 'HOME': '/Users/cltbld', 10:14:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:05 INFO - 'LOGNAME': 'cltbld', 10:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:05 INFO - 'MOZ_NO_REMOTE': '1', 10:14:05 INFO - 'NO_EM_RESTART': '1', 10:14:05 INFO - 'PAGER': '/bin/cat', 10:14:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:05 INFO - 'PWD': '/builds/slave/test', 10:14:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:05 INFO - 'SHELL': '/bin/bash', 10:14:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gx4sY1eX1L/Listeners', 10:14:05 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 10:14:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:05 INFO - 'USER': 'cltbld', 10:14:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:05 INFO - 'XPC_FLAGS': '0x0', 10:14:05 INFO - 'XPC_SERVICE_NAME': '0', 10:14:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:14:06 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-trBYWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:14:06 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-kPFi_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:14:06 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)) 10:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:14:06 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-xOD3Ca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:14:06 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-ND5vOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:14:06 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-yRIRte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:14:06 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-FA688r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:14:06 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-VGGpcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:14:06 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-2sNw8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:14:07 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-YRXBlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:14:07 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-S_iOAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:14:07 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-6caKUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:14:07 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-wOqK5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:14:07 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-om7T3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:14:07 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-itq26C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:14:07 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)) 10:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:14:07 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-Zkyn0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:14:07 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-CleIEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:14:07 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)) 10:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:14:07 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-LergpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:14:07 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)) 10:14:07 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)) 10:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:14:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:10 INFO - Downloading blessings-1.6.tar.gz 10:14:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:14:10 INFO - Installing collected packages: blessings 10:14:10 INFO - Running setup.py install for blessings 10:14:10 INFO - Successfully installed blessings 10:14:10 INFO - Cleaning up... 10:14:10 INFO - Return code: 0 10:14:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:14:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:14:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:14:11 INFO - Reading from file tmpfile_stdout 10:14:11 INFO - Current package versions: 10:14:11 INFO - blessings == 1.6 10:14:11 INFO - blobuploader == 1.2.4 10:14:11 INFO - docopt == 0.6.1 10:14:11 INFO - functools32 == 3.2.3-2 10:14:11 INFO - jsonschema == 2.5.1 10:14:11 INFO - manifestparser == 1.1 10:14:11 INFO - mozInstall == 1.12 10:14:11 INFO - mozcrash == 0.17 10:14:11 INFO - mozdebug == 0.1 10:14:11 INFO - mozdevice == 0.48 10:14:11 INFO - mozfile == 1.2 10:14:11 INFO - mozhttpd == 0.7 10:14:11 INFO - mozinfo == 0.9 10:14:11 INFO - mozleak == 0.1 10:14:11 INFO - mozlog == 3.3 10:14:11 INFO - moznetwork == 0.27 10:14:11 INFO - mozprocess == 0.23 10:14:11 INFO - mozprofile == 0.28 10:14:11 INFO - mozrunner == 6.12 10:14:11 INFO - mozscreenshot == 0.1 10:14:11 INFO - mozsystemmonitor == 0.3 10:14:11 INFO - moztest == 0.7 10:14:11 INFO - mozversion == 1.4 10:14:11 INFO - psutil == 3.1.1 10:14:11 INFO - requests == 1.2.3 10:14:11 INFO - wsgiref == 0.1.2 10:14:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:14:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:11 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 0x10e115a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef20c8bac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e079d30>, '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 0x10dddedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e071b20>, '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.Xv6gL4ENIo/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.Gx4sY1eX1L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:14:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:14:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xv6gL4ENIo/Render', 10:14:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:11 INFO - 'HOME': '/Users/cltbld', 10:14:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:11 INFO - 'LOGNAME': 'cltbld', 10:14:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:11 INFO - 'MOZ_NO_REMOTE': '1', 10:14:11 INFO - 'NO_EM_RESTART': '1', 10:14:11 INFO - 'PAGER': '/bin/cat', 10:14:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:11 INFO - 'PWD': '/builds/slave/test', 10:14:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:11 INFO - 'SHELL': '/bin/bash', 10:14:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gx4sY1eX1L/Listeners', 10:14:11 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 10:14:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:11 INFO - 'USER': 'cltbld', 10:14:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:11 INFO - 'XPC_FLAGS': '0x0', 10:14:11 INFO - 'XPC_SERVICE_NAME': '0', 10:14:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:14:11 INFO - Cleaning up... 10:14:11 INFO - Return code: 0 10:14:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:14:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:11 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 0x10e115a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef20c8bac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e079d30>, '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 0x10dddedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e071b20>, '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.Xv6gL4ENIo/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.Gx4sY1eX1L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:14:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:14:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xv6gL4ENIo/Render', 10:14:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:11 INFO - 'HOME': '/Users/cltbld', 10:14:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:11 INFO - 'LOGNAME': 'cltbld', 10:14:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:11 INFO - 'MOZ_NO_REMOTE': '1', 10:14:11 INFO - 'NO_EM_RESTART': '1', 10:14:11 INFO - 'PAGER': '/bin/cat', 10:14:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:11 INFO - 'PWD': '/builds/slave/test', 10:14:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:11 INFO - 'SHELL': '/bin/bash', 10:14:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gx4sY1eX1L/Listeners', 10:14:11 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 10:14:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:11 INFO - 'USER': 'cltbld', 10:14:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:11 INFO - 'XPC_FLAGS': '0x0', 10:14:11 INFO - 'XPC_SERVICE_NAME': '0', 10:14:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:14:11 INFO - Cleaning up... 10:14:11 INFO - Return code: 0 10:14:11 INFO - Running post-action listener: _resource_record_post_action 10:14:11 INFO - Running post-action listener: _start_resource_monitoring 10:14:11 INFO - Starting resource monitoring. 10:14:11 INFO - [mozharness: 2016-09-07 17:14:11.700559Z] Finished create-virtualenv step (success) 10:14:11 INFO - [mozharness: 2016-09-07 17:14:11.701518Z] Running install step. 10:14:11 INFO - Running pre-action listener: _resource_record_pre_action 10:14:11 INFO - Running main action method: install 10:14:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:14:11 INFO - Reading from file tmpfile_stdout 10:14:11 INFO - Detecting whether we're running mozinstall >=1.0... 10:14:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:14:12 INFO - Reading from file tmpfile_stdout 10:14:12 INFO - Output received: 10:14:12 INFO - Usage: mozinstall [options] installer 10:14:12 INFO - Options: 10:14:12 INFO - -h, --help show this help message and exit 10:14:12 INFO - -d DEST, --destination=DEST 10:14:12 INFO - Directory to install application into. [default: 10:14:12 INFO - "/builds/slave/test"] 10:14:12 INFO - --app=APP Application being installed. [default: firefox] 10:14:12 INFO - mkdir: /builds/slave/test/build/application 10:14:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:14:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:14:45 INFO - Reading from file tmpfile_stdout 10:14:45 INFO - Output received: 10:14:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:14:45 INFO - Running post-action listener: _resource_record_post_action 10:14:45 INFO - [mozharness: 2016-09-07 17:14:45.859822Z] Finished install step (success) 10:14:45 INFO - [mozharness: 2016-09-07 17:14:45.859966Z] Running run-tests step. 10:14:45 INFO - Running pre-action listener: _resource_record_pre_action 10:14:45 INFO - Running main action method: run_tests 10:14:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:14:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:14:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:14:45 INFO - 2016-09-07 10:14:45.874 screenresolution[1647:7569] starting screenresolution argv=screenresolution get 10:14:45 INFO - 2016-09-07 10:14:45.877 screenresolution[1647:7569] Display 0: 1600x1200x32@60 10:14:45 INFO - 2016-09-07 10:14:45.882 screenresolution[1648:7571] starting screenresolution argv=screenresolution list 10:14:45 INFO - Available Modes on Display 0 10:14:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:14:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:14:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:14:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:14:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:14:46 INFO - Intel Iris: 10:14:46 INFO - Chipset Model: Intel Iris 10:14:46 INFO - Type: GPU 10:14:46 INFO - Bus: Built-In 10:14:46 INFO - VRAM (Dynamic, Max): 1536 MB 10:14:46 INFO - Vendor: Intel (0x8086) 10:14:46 INFO - Device ID: 0x0a2e 10:14:46 INFO - Revision ID: 0x0009 10:14:46 INFO - Displays: 10:14:46 INFO - Display: 10:14:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:14:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:14:46 INFO - Display Serial Number: 1600x1200 60 10:14:46 INFO - Main Display: Yes 10:14:46 INFO - Mirror: Off 10:14:46 INFO - Online: Yes 10:14:46 INFO - Rotation: Supported 10:14:46 INFO - Return code: 0 10:14:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:14:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:14:46 INFO - Python 2.7.3 10:14:46 INFO - Return code: 0 10:14:46 INFO - grabbing minidump binary from tooltool 10:14:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:14:46 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 0x10e079d30>, '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 0x10dddedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e071b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:14:46 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:14:46 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:14:46 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:14:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:14:46 INFO - Return code: 0 10:14:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:14:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:14:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:14:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:14:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:14:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0225', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 10:14:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0225 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 10:14:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xv6gL4ENIo/Render', 10:14:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:46 INFO - 'HOME': '/Users/cltbld', 10:14:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:46 INFO - 'LOGNAME': 'cltbld', 10:14:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:14:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:14:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:46 INFO - 'MOZ_NO_REMOTE': '1', 10:14:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:14:46 INFO - 'NO_EM_RESTART': '1', 10:14:46 INFO - 'PAGER': '/bin/cat', 10:14:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:46 INFO - 'PWD': '/builds/slave/test', 10:14:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:14:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:46 INFO - 'SHELL': '/bin/bash', 10:14:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gx4sY1eX1L/Listeners', 10:14:46 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 10:14:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:46 INFO - 'USER': 'cltbld', 10:14:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:46 INFO - 'XPC_FLAGS': '0x0', 10:14:46 INFO - 'XPC_SERVICE_NAME': '0', 10:14:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0225', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 10:14:46 INFO - mozversion application_buildid: 20160907082120 10:14:46 INFO - mozversion application_changeset: 95acb9299fafdc69463c49860caf367e4fbcc8e3 10:14:46 INFO - mozversion application_display_name: Nightly 10:14:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:14:46 INFO - mozversion application_name: Firefox 10:14:46 INFO - mozversion application_remotingname: firefox 10:14:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:14:46 INFO - mozversion application_vendor: Mozilla 10:14:46 INFO - mozversion application_version: 51.0a1 10:14:46 INFO - mozversion platform_buildid: 20160907082120 10:14:46 INFO - mozversion platform_changeset: 95acb9299fafdc69463c49860caf367e4fbcc8e3 10:14:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:14:46 INFO - mozversion platform_version: 51.0a1 10:14:46 INFO - using testdate: 1473268486 10:14:46 INFO - actual date: 1473268486 10:14:46 INFO - starting webserver on 'localhost:49276' 10:14:46 INFO - SUITE-START | Running 2 tests 10:14:46 INFO - TEST-START | dromaeo_css 10:14:46 INFO - operating with platform_type : mac_ 10:14:46 INFO - Initialising browser for dromaeo_css test... 10:14:46 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/tmpqb2TiL/profile http://localhost:49276/getInfo.html) 10:14:49 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 10:14:49 INFO - PROCESS | 1658 | colorDepth:24 10:14:49 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 10:14:49 INFO - PROCESS | 1658 | __metrics 10:14:49 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 10:14:50 INFO - TEST-INFO | 1658: exit 0 10:14:50 INFO - Browser initialized. 10:14:50 INFO - Running cycle 1/1 for dromaeo_css test... 10:14:50 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/tmpqb2TiL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:15:02 INFO - PROCESS | 1661 | RSS: Main: 244129792 10:15:02 INFO - PROCESS | 1661 | 10:15:52 INFO - PROCESS | 1661 | 1473268552224 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] 10:18:05 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 10:18:06 INFO - PROCESS | 1661 | RSS: Main: 262623232 10:18:06 INFO - PROCESS | 1661 | 10:21:08 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 10:21:09 INFO - PROCESS | 1661 | RSS: Main: 267223040 10:21:09 INFO - PROCESS | 1661 | 10:24:11 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 10:24:11 INFO - PROCESS | 1661 | RSS: Main: 272752640 10:24:11 INFO - PROCESS | 1661 | 10:27:13 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 10:27:14 INFO - PROCESS | 1661 | RSS: Main: 270872576 10:27:14 INFO - PROCESS | 1661 | 10:30:17 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 10:30:17 INFO - PROCESS | 1661 | RSS: Main: 276414464 10:30:17 INFO - PROCESS | 1661 | 10:33:20 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 10:33:20 INFO - PROCESS | 1661 | RSS: Main: 279285760 10:33:20 INFO - PROCESS | 1661 | 10:33:20 INFO - PROCESS | 1661 | __start_tp_report 10:33:20 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 10:33:20 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 10:33:20 INFO - PROCESS | 1661 | |i|pagename|runs| 10:33:20 INFO - PROCESS | 1661 | |0;dojo.html;3389.101887999679;3393.5588373511455;3393.6945674889257;3407.2163402417427;3454.4300190468566;4827.468978412372;5649.130345713189;5708.571857110718;5713.885722285562;5720.656760594362;5390.5418039466595;5683.772649094031;5751.7986870459545;5757.42425757424;5813.941860581393;5188.974055129738;5249.973750131244;5283.445238249986;5355.009323275187;5369.4362091980365;5325.360956685184;5372.785088596452;5378.757955891974;5396.244525766396;5428.240046393508;5103.948960510403;5222.843314700565;5244.423113457517;5338.546223570962;5355.491228333302;5244.554212891909;5254.92117618236;5284.815031473919;5297.602679799031;5307.708076055816;5121.82073627425;5201.193814958688;5241.109011468022;5244.213367994793;5261.5264626183825;5453.754581043862;5719.628224165416;5733.254676892019;5746.885062298772;5814.622049566765;6145.938540614581;6192.133101365813;6199.907001394938;6207.596506227081;6211.130441738115;6089.878202435971;6196.10156527301;6199.132121502993;6211.844703882439;6212.782552610682;5737.827865164052;5740.741666625012;5815.5929084963645;5817.0983497557945;5840.094785308284;6175.44421002112;6216.627002379872;6221.595596286317;6223.813285601439;6339.619622822645;6149.938500615026;6159.4456498915315;6167.876642467126;6177.2587289525545;6181.320054793969;6097.645669838225;6111.602745821597;6146;6197.96901015501;6234.438900498977;6012.218411606551;6130.000698177752;6214;6235.064740288903;6236.656983865931;6186.19579454677;6194.5044396448175;6205.131281620576;6205.131281620576;6230.408111229516;4865.245886887538;4878.682885612495;4928.48250933654;5005.399352077774;5021.899562008739;3602.7478076534917;4345.565443455693;4364.432623758891;4371.825126994884;4394.98915249488;5589.385167631557;5682.77268909239;5683.204351390808;5695.60130790849;5710.971445142748;5603.775848966077;6135.877282454415;6144.385561443909;6177.6911154443;6255.093011513305;5724.3130824300515;5740.483356497935;5780.682062486603;5861.912071318934;5871.32479764824;4277.764722940268;4720.952790472052;4882.658213924991;5500.669959802424;5536.446355364431;6151.723172457339;6268.905966410599;6342.182890855443;6384.48924086072;6403.847307484604;5043.62172837046;6209.130721698875;6210.844728881814;6211.409916057967;6228.439440450381;6371;6407.647579383179;6413.102165696713;6443.2590252119735;6444.8388790280615;5013.749312534432;6088.452039316306;6164.352742961924;6165;6185.8144255672405;4863.2461968394955;5599.524040456607;6156.107364432221;6201.627902325695;6202.131701561875;6134.35589263121;6148.846278843064;6163.167972323679;6174.166487524307;6191.133241346125;5922.466977972003;6094.055421409688;6131.386861313833;6150.138980542817;6150.938490615215;5992.730327135377;6017.699115044318;6029.186059881859;6068.393160683896;6078.5441091919165;6130.080487926846;6131.712340408564;6132.356102609162;6144.938550614615;6158.876822463615;5003.54968052877;5887.882242355215;5954.523638108854;5987;6042.607230530001 10:33:20 INFO - PROCESS | 1661 | |1;ext.html;465.3485120830836;481.74949026506226;544.4854612391289;548.0956421903858;596.3470000349615;33342.8332858336;38719.225615487674;41361.759147225595;41458.75623731289;41500.58499415005;41860;42497.36253956193;42512.787436062776;42548.787256063755;42558.14883702324;9640.325177237613;9774.804503909918;9800.068993445611;9813.165880900133;9844.015598440135;9723.319367644268;9771.36486128403;9793.755156121082;9794.16749576287;9855.655052073178;8225.218604232618;9670.129688328048;9839.704808855746;9850.704478865646;9867.309288349818;9711.417314961125;9789.412635241873;9812.558434870449;9815.656452024145;9843.212542996544;9675.854862177073;9756.609735610567;9760.219182465386;9782.5597848097;9796.853047204298;36132.81933590341;36287.911362659164;36365.27269454623;36643.63356366429;36683.4497482538;3461.6192218855904;3724.515812944327;3746.250749850041;4030.415589739501;4098.774567398792;30802.845985770153;31319.06042818719;31519;32041.839790800896;32741.50887736662;3208.582884225059;3229.450993331139;3232.6767323267723;3254.1376535218187;3337.581527850654;479037;482732;483202;487270;491019;61244.69377653172;61401.69299153476;61906;61941;62039.6898015507;30626.84686576553;32886.01341959745;35715.285694285965;65231.34768652348;65232;16579.50261492157;16608.584785380463;16740.58148546296;16746.162691865356;16746.6650666986;12580.496780128693;12706.936465317614;12816.743665126833;12817.743645127046;12840.871591283967;16712.83287167113;19265.51836204106;19307.165819247148;19354.709679354823;19376.4187074388;7517.774466766005;7871.724489642835;7961.323287520626;8004.5597492138495;8037.437379383387;854.5727136431784;911.4212475078373;945.4894357047255;946.4510583861481;1025.6717850287837;30380;30410.39179216403;30616.54075188874;30915.69084309128;31118.844405777825;1052.768390953989;1064.707205518473;1086.2559146984183;1131.332513816852;1136.3466007045981;12559.560415385418;15488.380464781509;15914.124723140338;15997.920010399874;16006.039637621547;681773;753310;782766.1723382693;783341.4992650689;992200;58204;58535;58885.705571472725;58984;59199.70400147972;1335.6193484856963;1349.8987575931844;1372;1373.5810577773875;1374.6082366525693;30927.381452370377;31799.205019873763;31814.363712725153;32380;32382;52400.95198096093;52647;52647.47352526426;53139.20291195712;53149;627820;633854;634082;634268;637979;25703.228903132935;26758.331041724112;27220.183394498195;27738;27796.722032779413;46025.76987115043;46292.305615416466;46796;46912.7654361726;47510;29617.5557366644;38410.231795363376;38517.80741096276;40067.59932400638;40357;38256.04359891025;38632.80683596564;39422;39555;40370.59629403668;37087.629123708415;37345.6265437353;37935.62064379321;38096.42855357117;38124.85625431241;28558.42883142367;33081.50377744384;33369.33261334808;34719.65280347164;34786;24698.753012469646;24938.37654058663;25471.617925731498;25916.870415648555;25978 10:33:20 INFO - PROCESS | 1661 | |2;jquery.html;374.33368603885094;380.8552749955017;386.87619961612285;391.82172111689187;406.81083296267246;19105.904470477646;21112.4721881953;21749.347519574418;21783.891080544574;21928.23251186207;21011.579768404623;21023;21102.261420850275;21360.893195534038;21504.462388440297;5653.095504719246;6073.301570319403;6153.046277826922;6168.537359698041;6172.197614310134;4173.332266837307;5980.282366116072;6424.582402143869;6721.327867213264;6748.797536073926;5818.4181581841685;6737.696803643823;6773;6780.457563394942;6805.081314022618;6052.515798736091;6167.074938759222;6182.72177752;6192.9380706192815;6194.25668919728;5885.382034886361;5913.9112913306335;6025.132209015219;6045.697715114271;6052.757889684407;13190.406431710597;13321.333933303296;13372.73254534904;13531.39108740109;21058.157673693033;1669.6326808102199;1699.73654083616;2120.0883620043373;2225.7774222577773;2300.766589475373;8813.206811386943;8932.19610235082;10163;14969.775453368207;15241.695166096615;1544.3513724235847;1549.3105568022236;1813.9274429022823;1913.1199648161862;1921.135489029928;124398;134820.999999999;136141;137627;137669;9810.950945245228;11308.34253950321;11487.540498380138;11536.884631153749;11636.243644163102;14458.20479873596;14469.348879300247;14503.85496145046;14510.1293922365;14514.201718905562;7514.323710866049;8063.758087257274;8356.582170891432;8369.163083691583;8525.147485251426;6855.348741869514;8352.916470835335;8394.622241999125;8534.87197692035;8577.05652378238;3829.4830197922925;4477.865664030084;4540.273556230987;4578.771061446914;4598.448186217675;1621.408186012097;1680.0679218898365;1823.1795691938416;1909.0740990619538;1932.159510612888;871.825634873028;917.5504003038596;951.6526467839327;984.675057231112;986.447589349952;20087.39737807868;21456.4635884101;21590.892045539673;21593.24423645165;21686.18079649667;921.3147410358566;944.3861490031397;958.4249450329746;1016.7458135466134;1037.818381783185;10993.395363255098;11397.601083962021;11455.770884582262;11456.828147577793;11482.712932176762;568561;582615;597436;597842;605217;123382;129146.35426822805;139371;139927.90108148183;140229;949.4635530925005;973.255459573427;977.628501169551;996.5465713100667;996.6113215845826;18788.81211187925;18806.247750089842;18944.621107577495;18992.050397480347;19100.426987190407;41725.58274417217;49587.25619115788;50489.24266135937;51621;51623.225651616005;80548.59725701335;86894.48291378045;91306;92018.61972070558;92145.53927230321;7820.960895195488;8153.22544358285;8203.507789532647;8227.259546640831;8344.624491897757;11916.940415298215;12574.622761317176;12643.747125057263;12936.288504132363;13154.671133221746;10353.327033742782;10644.84032739494;11276.759556448947;11434.714132146762;11471.483783229607;9182.449053056838;10292.794144117226;11373.829392559284;11591.710207244887;11619.012383947122;10579.89420105789;11480.94259528697;11735.413229338328;11788.351640659846;11921.582744604244;7463.328300452985;7594.030918767441;7987.960060199662;8170.754877353689;8346.582670866312;8209.38429617793;11151.944240278746;11274.718132046764;11321;11602.883971160518 10:33:20 INFO - PROCESS | 1661 | |3;mootools.html;1358.592422273318;1496.4762333183387;1517.9316930738119;1848.8151184881506;1864.8414884734798;6787.72849086036;8618.784530386736;8778.604962776675;9185.77035574111;9204.309676774245;8802;9001;9008.954955225232;9009.95495022524;9020.413673111243;2231.129859354853;2282.6233671444193;2370.1941339944415;2424.0303878448585;2442.291735396742;2367.562001029815;2393.940151496218;2394.365493144318;2396.808255339569;2415.2874901903892;2386.427257458206;2390.2948630154146;2391.1750446096157;2405.542946840095;2408.434018005767;2358.092134528219;2373.5134297468976;2380.440596459839;2391.56951748686;2392.629142482909;2359.9646005309933;2372.9288121356212;2377.227401094651;2377.441301294212;2378.3935096550267;8926.196642302159;8957.820843583091;8967.417117887382;9040.68357607487;9052.27581793455;5215;5339.412664606929;5348.411674715778;5363.54409875161;5370.731463426852;3087.5831762712196;3635.4365073413665;3655.9086022849374;3689.409694448875;3694.3350196964534;1078.7411021354897;1088.2545456362418;1088.3905013192636;1093.1528065749028;1094.9069329107035;10456.47717614124;11592.724800271963;11914.821277680841;11984.28094314344;11997.400129993553;4729.826149856579;4737.715737055788;4786.353842231324;4845.781939812717;4890.608751299887;10024.548895299584;10083.848742268872;10099.192064634812;10101.040401161878;10160.847587286196;5439.238506609078;5450.727463626882;5463.644863083887;5465.125579907196;5467.617266791366;4455.821767129343;4816.518348165226;4942.35749352589;4961.355023846877;4984.127777638899;3897.376419772879;3953.5651078381356;3992.8039578231856;4044.1911617675996;4081.836726530965;1143.0626885953438;1316.0853207021028;1317.5586178630074;1322.8941684665397;1324.079764563619;887.3433659092225;913.6254135804288;927.8700981862545;931.6366617019368;946.9526523673788;4967.503249675004;5248.9737551312;5856.648601083949;5986;5991.2510936132985;389.2817751248941;442.72993473980847;456.9543045695404;464.65847602012497;465.9138059459011;3889.416587511839;3893.7734613596626;3916.3538016227003;3992.480977472968;4027.234825383168;10503.369797812156;10768.892311076946;10862.511186996604;10904.127669786398;10949.4525273736;10790.352578845295;10808.621698240524;10829.29609575375;10829.29609575375;10850.457477126112;8207.466514676527;8286;8297.25324720778;8300.501969881827;8403.117672644406;4434.935615452333;5054.039732450822;5518.613697041169;5706.914396284142;5978.491828194652;9474.289428292768;10124.139448146696;10181.879993200593;10194.439305837954;10211.438370889673;10056.094951454405;10100.797984040424;10218.540165692413;10224.795504089729;10235.437050961971;6897.862042759017;7336.816579585339;7476.364509016795;7503.2496750324535;7549.0941087069905;8386.916130838614;8387.41288109851;8404.411691181558;8428.957855210685;8488.74533763988;7328.706851725871;7413.406927445899;7565;7619.390448764197;7697.0378702662165;7287.2348403419;7446.920196571358;7476.925230747623;7561.206073362438;7588.81027974283;6998.300169983165;7100.751473698396;7119.288071192839;7158.355747982707;7170.175429825428;4149.27387707144;4982.128127577587;5079.847604571869;5109.64232503736;5152.716600587004;5054.73631592102;5060.569851562511;5128.948130976636;5282;5297.576193904401 10:33:20 INFO - PROCESS | 1661 | |4;prototype.html;281.31499797991887;317.2275509135256;319.7857435518202;324.86355730593164;325.8289398066016;5055.342805435297;5685.943140568593;5764.942350576493;5783.4720820299635;5885.970570147143;5562.972185139079;5614.494695477406;5657.773689052443;5665.631733937302;5727.914081288774;4102.548719640837;4156.584341565849;4215.641670458015;4241.533431322552;4330.696851220416;3189.649138594753;3243.016424889096;4213.894652633693;4292.484901811787;4313.633341165989;3629.4374371972253;3896.8636097736585;4272.935905961397;4290.227759003378;4297.849575264866;3501.1072176594807;4184.832606695729;4185.162967406515;4225.7253278536955;4287.785610719483;3433.5536380270655;3499.230169362736;3687.7418580699364;3696.4270538066376;3700.35243832331;4398.868033958986;5476.67139971603;5499.532539734128;5510.476504732044;5546.27898373213;4135.669146468275;4589.5410458954175;4951.935333903192;4977.601791856643;4987.226979818134;5503.560274828233;6041.190846256468;6043.2748070231855;6070.484008859252;6071.666058366788;3764.228333191689;4111.794410279504;4119.97940010301;4122.9381559276635;4131.277026520377;5635.492805647511;5711.343195532525;5996.130561068664;6348.777792777277;6431.260405053432;3689.631036896289;4180.456540649757;4181.937270940938;4199.328107502785;4201.642860356843;3628.8004159771467;3963.504561929759;4223.176480586318;4305.53611771999;4307.04029666501;4852.902941941142;4971.130052240844;4975.850724478271;5028.497150284942;5085.389753229636;6029;6044.093385992136;6064.36324185963;6096.293037949381;6147.969260153671;1241.9565315213924;1441.3297816515162;1466.442751754348;1469.875060619838;1471.4187895781106;667.9131712877295;901.4681338010474;902.9232515236279;919.3610440743753;931.8352059925094;5299.920501192485;5603.719814009365;5639.577031722555;5651.406602306781;5684.943150568524;5254.797750227248;5282;5498.780048798083;5920.526357891358;6305.117283580303;5311.48622642545;5603.3836278009385;5645.943540564624;5696.550483292224;5712.914306285409;2694.8652567371946;2759.379139693553;2959.4229125320358;3242.9784617845303;3276.623188333372;6015.2856435915555;6135.32511423743;6465.316893489244;6479.3520647935775;6505.60966342021;5299.39057008449;6319.58922670025;6348;6392.904106438407;6442.226932768004;5697.376247769449;5885.410939046434;6458.903116453257;6504.1869766279215;6528.281888992302;4932.45742968266;5016.774245158904;5251.665192602649;5718.3702644746045;6255.71849266775;5719.48524632785;5902.789928064823;6118.296395914349;6157.568970172045;6192.845178870384;5440.972795135999;6110.663913484801;6522.380373864476;6528.759535688204;6562.507811914221;3366.983165084159;3751.587325394259;3860.9575414638452;4144.715138307134;4192.526409418458;6232.317274335814;6300.180976473029;6300.841444646511;6312.43188113072;6346.746130154742;5162.934826069602;5213.905079933256;5495.340559132929;5987.305712429337;6013.338532761305;5059.671121377099;5607.775688972395;5630.0991841305195;5779.479846813639;5992.460678538952;5316;5393.83818485446;5552;5796.82609521715;5905.881882362242;5058.974705126451;5218.9739051304505;5710.2298287531;5989.610675306091;6015.5488338373925;5096.592272618108;5489.58828087887;5683;5922.785828904978;5926.348101708722 10:33:20 INFO - PROCESS | 1661 | |5;yui.html;637.5632691606253;721.9602921839297;722.5917356693468;775.9107702614201;820.0610301205119;15767.842321576782;16088.436904708338;16098.436554720587;16116.597085072865;16145.19274036298;14352.784708229385;14425;14450.421983120663;14461.710765784677;14501.564953051398;688.3563867783613;837.3677873205721;845.8773477845708;852.7697521669146;879.459132633429;856.6787454704486;870.0951010948606;871.6469829718942;873.4802792338548;875.2997601918472;935.0415823780611;938.4322484896642;940.3934462271831;940.6096469964956;940.7130825098342;872.0625327772607;923.6490133749132;931.0735817861238;932.8600709893502;941.9246460283196;648.7469886744174;649.5583003557579;650.440621065883;708.5359089796192;709.3438569323407;14715.337809798584;15020.849791502163;15047.774283385757;15066;15083.095014299068;11989.700257493545;14735.926320368437;14772.039817411835;14776.26118694061;14858.2570871456;11853.288802671867;11933.940330298294;11972.880271197264;11984.940075299655;12031.51873925042;3835.861725938821;3895.162540053902;3915.549711783153;3918.608139186087;4004.619561141716;117535;120176;121017.39491302575;121442;121865;11180;11274.943625281821;11402.429878506042;11534.769304614028;11703.180777345504;23757.643635345485;23793.286201413986;23793.7620623795;23806.7619323808;23897.880510597683;6797.864042719118;7859.685612575548;8025.518468891885;8186.567835129826;8197.180281971756;3964.28642844285;4255.468066491689;4495.640348772155;4517.412736344254;4517.638588912879;3549.254656522108;3652.561692596852;3679.19057807282;3685.447182922583;3689.0592898810633;659.9769008084791;670.6847781542667;677.6340775980915;683.5693513086823;686.0977814174345;500.2321436594809;644.6969924135649;673.2627772589008;674.9763758268437;685.3078390825302;8725.738227853175;8745.851493462349;9427.858582121273;9576.898656654574;9706.805863882682;620.7796232337546;639.523554951564;677.5528151420137;684.2746688510196;687.7902239816904;4457.952381190417;4583.510359133228;4692.53074692528;4698.436187657503;4727.314539391838;133981;158634;163002;163815.36184638002;172983.43149116388;116122;130150.74623134307;130414.04378934324;138322;145081.09837803396;91857;95940;118778;121234.3938280303;122485.77514224921;7919;8738.650453981769;8757.6934807284;9063.274938005075;9277.953610231905;57884;63948.04077938742;67877.3212267871;67907.66046169738;68620.3137968634;98204.01795981948;102599.52517805823;103965;105180;105957;10527.684169474927;12099.939500302442;12102.878971210175;12263.509459621515;12515.310433091416;13131;13272;13343.466261349826;13382.799258011333;13488.190708557519;12440.253584784941;12456.065795065588;12489.18820276679;12610.180338277982;13029.022823288102;12264.32546209967;12272.202306850026;12588.559400420942;12946.805797913226;12953.676158096123;12064.638060858188;12161.391930403268;12327.260364378166;12365.443555040225;12594.87405125937;10571;10603.575856965635;10854.34873907568;10952.890471095187;11174.832377514182;7414.369778568882;9397.389169703947;10671.786564268827;10739.409332486475;10798.920107989139 10:33:20 INFO - PROCESS | 1661 | __end_tp_report 10:33:20 INFO - PROCESS | 1661 | __start_cc_report 10:33:20 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,374 10:33:20 INFO - PROCESS | 1661 | __end_cc_report 10:33:20 INFO - PROCESS | 1661 | __startTimestamp1473269600513__endTimestamp 10:33:20 INFO - PROCESS | 1661 | 10:33:20 INFO - PROCESS | 1661 | ------- Summary: start ------- 10:33:20 INFO - PROCESS | 1661 | Number of tests: 6 10:33:20 INFO - PROCESS | 1661 | 10:33:20 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5700.69 Median:5922.47 stddev:663.31 (11.2%) stddev-sans-first:640.08 10:33:20 INFO - PROCESS | 1661 | Values: 3389.1 3393.6 3393.7 3407.2 3454.4 4827.5 5649.1 5708.6 5713.9 5720.7 5390.5 5683.8 5751.8 5757.4 5813.9 5189.0 5250.0 5283.4 5355.0 5369.4 5325.4 5372.8 5378.8 5396.2 5428.2 5103.9 5222.8 5244.4 5338.5 5355.5 5244.6 5254.9 5284.8 5297.6 5307.7 5121.8 5201.2 5241.1 5244.2 5261.5 5453.8 5719.6 5733.3 5746.9 5814.6 6145.9 6192.1 6199.9 6207.6 6211.1 6089.9 6196.1 6199.1 6211.8 6212.8 5737.8 5740.7 5815.6 5817.1 5840.1 6175.4 6216.6 6221.6 6223.8 6339.6 6149.9 6159.4 6167.9 6177.3 6181.3 6097.6 6111.6 6146.0 6198.0 6234.4 6012.2 6130.0 6214.0 6235.1 6236.7 6186.2 6194.5 6205.1 6205.1 6230.4 4865.2 4878.7 4928.5 5005.4 5021.9 3602.7 4345.6 4364.4 4371.8 4395.0 5589.4 5682.8 5683.2 5695.6 5711.0 5603.8 6135.9 6144.4 6177.7 6255.1 5724.3 5740.5 5780.7 5861.9 5871.3 4277.8 4721.0 4882.7 5500.7 5536.4 6151.7 6268.9 6342.2 6384.5 6403.8 5043.6 6209.1 6210.8 6211.4 6228.4 6371.0 6407.6 6413.1 6443.3 6444.8 5013.7 6088.5 6164.4 6165.0 6185.8 4863.2 5599.5 6156.1 6201.6 6202.1 6134.4 6148.8 6163.2 6174.2 6191.1 5922.5 6094.1 6131.4 6150.1 6150.9 5992.7 6017.7 6029.2 6068.4 6078.5 6130.1 6131.7 6132.4 6144.9 6158.9 5003.5 5887.9 5954.5 5987.0 6042.6 10:33:20 INFO - PROCESS | 1661 | 10:33:20 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:75715.94 Median:39811.30 stddev:176025.74 (442.2%) stddev-sans-first:176428.87 10:33:20 INFO - PROCESS | 1661 | Values: 465.3 481.7 544.5 548.1 596.3 33342.8 38719.2 41361.8 41458.8 41500.6 41860.0 42497.4 42512.8 42548.8 42558.1 9640.3 9774.8 9800.1 9813.2 9844.0 9723.3 9771.4 9793.8 9794.2 9855.7 8225.2 9670.1 9839.7 9850.7 9867.3 9711.4 9789.4 9812.6 9815.7 9843.2 9675.9 9756.6 9760.2 9782.6 9796.9 36132.8 36287.9 36365.3 36643.6 36683.4 3461.6 3724.5 3746.3 4030.4 4098.8 30802.8 31319.1 31519.0 32041.8 32741.5 3208.6 3229.5 3232.7 3254.1 3337.6 479037.0 482732.0 483202.0 487270.0 491019.0 61244.7 61401.7 61906.0 61941.0 62039.7 30626.8 32886.0 35715.3 65231.3 65232.0 16579.5 16608.6 16740.6 16746.2 16746.7 12580.5 12706.9 12816.7 12817.7 12840.9 16712.8 19265.5 19307.2 19354.7 19376.4 7517.8 7871.7 7961.3 8004.6 8037.4 854.6 911.4 945.5 946.5 1025.7 30380.0 30410.4 30616.5 30915.7 31118.8 1052.8 1064.7 1086.3 1131.3 1136.3 12559.6 15488.4 15914.1 15997.9 16006.0 681773.0 753310.0 782766.2 783341.5 992200.0 58204.0 58535.0 58885.7 58984.0 59199.7 1335.6 1349.9 1372.0 1373.6 1374.6 30927.4 31799.2 31814.4 32380.0 32382.0 52401.0 52647.0 52647.5 53139.2 53149.0 627820.0 633854.0 634082.0 634268.0 637979.0 25703.2 26758.3 27220.2 27738.0 27796.7 46025.8 46292.3 46796.0 46912.8 47510.0 29617.6 38410.2 38517.8 40067.6 40357.0 38256.0 38632.8 39422.0 39555.0 40370.6 37087.6 37345.6 37935.6 38096.4 38124.9 28558.4 33081.5 33369.3 34719.7 34786.0 24698.8 24938.4 25471.6 25916.9 25978.0 10:33:20 INFO - PROCESS | 1661 | 10:33:20 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:35434.14 Median:2263.27 stddev:99378.13 (4390.9%) stddev-sans-first:99622.05 10:33:20 INFO - PROCESS | 1661 | Values: 374.3 380.9 386.9 391.8 406.8 19105.9 21112.5 21749.3 21783.9 21928.2 21011.6 21023.0 21102.3 21360.9 21504.5 5653.1 6073.3 6153.0 6168.5 6172.2 4173.3 5980.3 6424.6 6721.3 6748.8 5818.4 6737.7 6773.0 6780.5 6805.1 6052.5 6167.1 6182.7 6192.9 6194.3 5885.4 5913.9 6025.1 6045.7 6052.8 13190.4 13321.3 13372.7 13531.4 21058.2 1669.6 1699.7 2120.1 2225.8 2300.8 8813.2 8932.2 10163.0 14969.8 15241.7 1544.4 1549.3 1813.9 1913.1 1921.1 124398.0 134821.0 136141.0 137627.0 137669.0 9811.0 11308.3 11487.5 11536.9 11636.2 14458.2 14469.3 14503.9 14510.1 14514.2 7514.3 8063.8 8356.6 8369.2 8525.1 6855.3 8352.9 8394.6 8534.9 8577.1 3829.5 4477.9 4540.3 4578.8 4598.4 1621.4 1680.1 1823.2 1909.1 1932.2 871.8 917.6 951.7 984.7 986.4 20087.4 21456.5 21590.9 21593.2 21686.2 921.3 944.4 958.4 1016.7 1037.8 10993.4 11397.6 11455.8 11456.8 11482.7 568561.0 582615.0 597436.0 597842.0 605217.0 123382.0 129146.4 139371.0 139927.9 140229.0 949.5 973.3 977.6 996.5 996.6 18788.8 18806.2 18944.6 18992.1 19100.4 41725.6 49587.3 50489.2 51621.0 51623.2 80548.6 86894.5 91306.0 92018.6 92145.5 7821.0 8153.2 8203.5 8227.3 8344.6 11916.9 12574.6 12643.7 12936.3 13154.7 10353.3 10644.8 11276.8 11434.7 11471.5 9182.4 10292.8 11373.8 11591.7 11619.0 10579.9 11480.9 11735.4 11788.4 11921.6 7463.3 7594.0 7988.0 8170.8 8346.6 8209.4 11151.9 11274.7 11321.0 11602.9 10:33:20 INFO - PROCESS | 1661 | 10:33:20 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5731.06 Median:465.29 stddev:3250.87 (698.7%) stddev-sans-first:3243.38 10:33:20 INFO - PROCESS | 1661 | Values: 1358.6 1496.5 1517.9 1848.8 1864.8 6787.7 8618.8 8778.6 9185.8 9204.3 8802.0 9001.0 9009.0 9010.0 9020.4 2231.1 2282.6 2370.2 2424.0 2442.3 2367.6 2393.9 2394.4 2396.8 2415.3 2386.4 2390.3 2391.2 2405.5 2408.4 2358.1 2373.5 2380.4 2391.6 2392.6 2360.0 2372.9 2377.2 2377.4 2378.4 8926.2 8957.8 8967.4 9040.7 9052.3 5215.0 5339.4 5348.4 5363.5 5370.7 3087.6 3635.4 3655.9 3689.4 3694.3 1078.7 1088.3 1088.4 1093.2 1094.9 10456.5 11592.7 11914.8 11984.3 11997.4 4729.8 4737.7 4786.4 4845.8 4890.6 10024.5 10083.8 10099.2 10101.0 10160.8 5439.2 5450.7 5463.6 5465.1 5467.6 4455.8 4816.5 4942.4 4961.4 4984.1 3897.4 3953.6 3992.8 4044.2 4081.8 1143.1 1316.1 1317.6 1322.9 1324.1 887.3 913.6 927.9 931.6 947.0 4967.5 5249.0 5856.6 5986.0 5991.3 389.3 442.7 457.0 464.7 465.9 3889.4 3893.8 3916.4 3992.5 4027.2 10503.4 10768.9 10862.5 10904.1 10949.5 10790.4 10808.6 10829.3 10829.3 10850.5 8207.5 8286.0 8297.3 8300.5 8403.1 4434.9 5054.0 5518.6 5706.9 5978.5 9474.3 10124.1 10181.9 10194.4 10211.4 10056.1 10100.8 10218.5 10224.8 10235.4 6897.9 7336.8 7476.4 7503.2 7549.1 8386.9 8387.4 8404.4 8429.0 8488.7 7328.7 7413.4 7565.0 7619.4 7697.0 7287.2 7446.9 7476.9 7561.2 7588.8 6998.3 7100.8 7119.3 7158.4 7170.2 4149.3 4982.1 5079.8 5109.6 5152.7 5054.7 5060.6 5128.9 5282.0 5297.6 10:33:20 INFO - PROCESS | 1661 | 10:33:20 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4814.51 Median:5492.46 stddev:1522.68 (27.7%) stddev-sans-first:1488.45 10:33:20 INFO - PROCESS | 1661 | Values: 281.3 317.2 319.8 324.9 325.8 5055.3 5685.9 5764.9 5783.5 5886.0 5563.0 5614.5 5657.8 5665.6 5727.9 4102.5 4156.6 4215.6 4241.5 4330.7 3189.6 3243.0 4213.9 4292.5 4313.6 3629.4 3896.9 4272.9 4290.2 4297.8 3501.1 4184.8 4185.2 4225.7 4287.8 3433.6 3499.2 3687.7 3696.4 3700.4 4398.9 5476.7 5499.5 5510.5 5546.3 4135.7 4589.5 4951.9 4977.6 4987.2 5503.6 6041.2 6043.3 6070.5 6071.7 3764.2 4111.8 4120.0 4122.9 4131.3 5635.5 5711.3 5996.1 6348.8 6431.3 3689.6 4180.5 4181.9 4199.3 4201.6 3628.8 3963.5 4223.2 4305.5 4307.0 4852.9 4971.1 4975.9 5028.5 5085.4 6029.0 6044.1 6064.4 6096.3 6148.0 1242.0 1441.3 1466.4 1469.9 1471.4 667.9 901.5 902.9 919.4 931.8 5299.9 5603.7 5639.6 5651.4 5684.9 5254.8 5282.0 5498.8 5920.5 6305.1 5311.5 5603.4 5645.9 5696.6 5712.9 2694.9 2759.4 2959.4 3243.0 3276.6 6015.3 6135.3 6465.3 6479.4 6505.6 5299.4 6319.6 6348.0 6392.9 6442.2 5697.4 5885.4 6458.9 6504.2 6528.3 4932.5 5016.8 5251.7 5718.4 6255.7 5719.5 5902.8 6118.3 6157.6 6192.8 5441.0 6110.7 6522.4 6528.8 6562.5 3367.0 3751.6 3861.0 4144.7 4192.5 6232.3 6300.2 6300.8 6312.4 6346.7 5162.9 5213.9 5495.3 5987.3 6013.3 5059.7 5607.8 5630.1 5779.5 5992.5 5316.0 5393.8 5552.0 5796.8 5905.9 5059.0 5219.0 5710.2 5989.6 6015.5 5096.6 5489.6 5683.0 5922.8 5926.3 10:33:20 INFO - PROCESS | 1661 | 10:33:20 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:25803.58 Median:3600.91 stddev:42147.72 (1170.5%) stddev-sans-first:42223.60 10:33:20 INFO - PROCESS | 1661 | Values: 637.6 722.0 722.6 775.9 820.1 15767.8 16088.4 16098.4 16116.6 16145.2 14352.8 14425.0 14450.4 14461.7 14501.6 688.4 837.4 845.9 852.8 879.5 856.7 870.1 871.6 873.5 875.3 935.0 938.4 940.4 940.6 940.7 872.1 923.6 931.1 932.9 941.9 648.7 649.6 650.4 708.5 709.3 14715.3 15020.8 15047.8 15066.0 15083.1 11989.7 14735.9 14772.0 14776.3 14858.3 11853.3 11933.9 11972.9 11984.9 12031.5 3835.9 3895.2 3915.5 3918.6 4004.6 117535.0 120176.0 121017.4 121442.0 121865.0 11180.0 11274.9 11402.4 11534.8 11703.2 23757.6 23793.3 23793.8 23806.8 23897.9 6797.9 7859.7 8025.5 8186.6 8197.2 3964.3 4255.5 4495.6 4517.4 4517.6 3549.3 3652.6 3679.2 3685.4 3689.1 660.0 670.7 677.6 683.6 686.1 500.2 644.7 673.3 675.0 685.3 8725.7 8745.9 9427.9 9576.9 9706.8 620.8 639.5 677.6 684.3 687.8 4458.0 4583.5 4692.5 4698.4 4727.3 133981.0 158634.0 163002.0 163815.4 172983.4 116122.0 130150.7 130414.0 138322.0 145081.1 91857.0 95940.0 118778.0 121234.4 122485.8 7919.0 8738.7 8757.7 9063.3 9278.0 57884.0 63948.0 67877.3 67907.7 68620.3 98204.0 102599.5 103965.0 105180.0 105957.0 10527.7 12099.9 12102.9 12263.5 12515.3 13131.0 13272.0 13343.5 13382.8 13488.2 12440.3 12456.1 12489.2 12610.2 13029.0 12264.3 12272.2 12588.6 12946.8 12953.7 12064.6 12161.4 12327.3 12365.4 12594.9 10571.0 10603.6 10854.3 10952.9 11174.8 7414.4 9397.4 10671.8 10739.4 10798.9 10:33:20 INFO - PROCESS | 1661 | -------- Summary: end -------- 10:33:20 INFO - PROCESS | 1661 | 10:33:21 INFO - TEST-INFO | 1661: exit 0 10:33:21 INFO - TEST-OK | dromaeo_css | took 1114620ms 10:33:21 INFO - TEST-START | kraken 10:33:21 INFO - operating with platform_type : mac_ 10:33:21 INFO - Initialising browser for kraken test... 10:33:21 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/tmp8AfvIQ/profile http://localhost:49276/getInfo.html) 10:33:23 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 10:33:23 INFO - PROCESS | 1671 | colorDepth:24 10:33:23 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 10:33:23 INFO - PROCESS | 1671 | __metrics 10:33:23 ERROR - PROCESS | 1671 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 10:33:24 INFO - TEST-INFO | 1671: exit 0 10:33:24 INFO - Browser initialized. 10:33:24 INFO - Running cycle 1/1 for kraken test... 10:33:24 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/tmp8AfvIQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:33:36 INFO - PROCESS | 1673 | RSS: Main: 244105216 10:33:36 INFO - PROCESS | 1673 | 10:33:39 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 10:33:40 INFO - PROCESS | 1673 | RSS: Main: 256831488 10:33:40 INFO - PROCESS | 1673 | 10:33:42 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 10:33:43 INFO - PROCESS | 1673 | RSS: Main: 265449472 10:33:43 INFO - PROCESS | 1673 | 10:33:46 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 10:33:47 INFO - PROCESS | 1673 | RSS: Main: 264142848 10:33:47 INFO - PROCESS | 1673 | 10:33:49 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 10:33:50 INFO - PROCESS | 1673 | RSS: Main: 265383936 10:33:50 INFO - PROCESS | 1673 | 10:33:52 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:33:52 INFO - PROCESS | 1673 | RSS: Main: 267010048 10:33:52 INFO - PROCESS | 1673 | 10:33:55 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 10:33:56 INFO - PROCESS | 1673 | RSS: Main: 267137024 10:33:56 INFO - PROCESS | 1673 | 10:33:59 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 10:33:59 INFO - PROCESS | 1673 | RSS: Main: 267321344 10:33:59 INFO - PROCESS | 1673 | 10:34:02 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 10:34:03 INFO - PROCESS | 1673 | RSS: Main: 267628544 10:34:03 INFO - PROCESS | 1673 | 10:34:05 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:34:05 INFO - PROCESS | 1673 | RSS: Main: 267132928 10:34:05 INFO - PROCESS | 1673 | 10:34:07 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:34:08 INFO - PROCESS | 1673 | RSS: Main: 267268096 10:34:08 INFO - PROCESS | 1673 | 10:34:10 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:34:11 INFO - PROCESS | 1673 | RSS: Main: 267575296 10:34:11 INFO - PROCESS | 1673 | 10:34:14 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:34:14 INFO - PROCESS | 1673 | RSS: Main: 267911168 10:34:14 INFO - PROCESS | 1673 | 10:34:17 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:34:18 INFO - PROCESS | 1673 | RSS: Main: 267931648 10:34:18 INFO - PROCESS | 1673 | 10:34:20 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 10:34:20 INFO - PROCESS | 1673 | RSS: Main: 269885440 10:34:20 INFO - PROCESS | 1673 | 10:34:20 INFO - PROCESS | 1673 | __start_tp_report 10:34:20 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 10:34:20 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 10:34:20 INFO - PROCESS | 1673 | |i|pagename|runs| 10:34:20 INFO - PROCESS | 1673 | |0;ai-astar;110;117;116;115;116;117;114;142;114;130 10:34:20 INFO - PROCESS | 1673 | |1;audio-beat-detection;144;111;138;159;109;135;130;112;173;142 10:34:20 INFO - PROCESS | 1673 | |2;audio-dft;197;199;206;237;244;280;229;201;196;195 10:34:20 INFO - PROCESS | 1673 | |3;audio-fft;68;89;77;141;59;58;60;62;136;58 10:34:20 INFO - PROCESS | 1673 | |4;audio-oscillator;73;100;143;70;73;91;110;69;72;107 10:34:20 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;88;103;89;85;91;86;89;86;89;84 10:34:20 INFO - PROCESS | 1673 | |6;imaging-darkroom;95;134;129;152;104;95;94;118;153;113 10:34:20 INFO - PROCESS | 1673 | |7;imaging-desaturate;91;93;101;92;98;147;151;92;89;95 10:34:20 INFO - PROCESS | 1673 | |8;json-parse-financial;56;58;58;56;58;58;58;57;58;57 10:34:20 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;65;68;67;74;76;75;75;66;67;71 10:34:20 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;86;88;89;88;98;84;89;88;92;87 10:34:20 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;163;161;130;155;162;177;154;137;192;142 10:34:20 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;223;180;171;171;171;170;173;174;170;172 10:34:20 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;66;89;92;87;61;78;70;70;92;90 10:34:20 INFO - PROCESS | 1673 | __end_tp_report 10:34:20 INFO - PROCESS | 1673 | __start_cc_report 10:34:20 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,941 10:34:20 INFO - PROCESS | 1673 | __end_cc_report 10:34:20 INFO - PROCESS | 1673 | __startTimestamp1473269660389__endTimestamp 10:34:20 INFO - PROCESS | 1673 | 10:34:20 INFO - PROCESS | 1673 | ------- Summary: start ------- 10:34:20 INFO - PROCESS | 1673 | Number of tests: 14 10:34:20 INFO - PROCESS | 1673 | 10:34:20 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:119.10 Median:116.50 stddev:9.56 (8.2%) stddev-sans-first:9.56 10:34:20 INFO - PROCESS | 1673 | Values: 110.0 117.0 116.0 115.0 116.0 117.0 114.0 142.0 114.0 130.0 10:34:20 INFO - PROCESS | 1673 | 10:34:20 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:135.30 Median:140.00 stddev:20.99 (15.0%) stddev-sans-first:22.02 10:34:20 INFO - PROCESS | 1673 | Values: 144.0 111.0 138.0 159.0 109.0 135.0 130.0 112.0 173.0 142.0 10:34:20 INFO - PROCESS | 1673 | 10:34:20 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:218.40 Median:217.50 stddev:28.38 (13.0%) stddev-sans-first:29.02 10:34:20 INFO - PROCESS | 1673 | Values: 197.0 199.0 206.0 237.0 244.0 280.0 229.0 201.0 196.0 195.0 10:34:20 INFO - PROCESS | 1673 | 10:34:20 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:80.80 Median:61.00 stddev:32.00 (52.5%) stddev-sans-first:33.61 10:34:20 INFO - PROCESS | 1673 | Values: 68.0 89.0 77.0 141.0 59.0 58.0 60.0 62.0 136.0 58.0 10:34:20 INFO - PROCESS | 1673 | 10:34:20 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:90.80 Median:71.00 stddev:24.35 (34.3%) stddev-sans-first:24.96 10:34:20 INFO - PROCESS | 1673 | Values: 73.0 100.0 143.0 70.0 73.0 91.0 110.0 69.0 72.0 107.0 10:34:20 INFO - PROCESS | 1673 | 10:34:20 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:88.50 stddev:5.37 (6.1%) stddev-sans-first:5.69 10:34:20 INFO - PROCESS | 1673 | Values: 88.0 103.0 89.0 85.0 91.0 86.0 89.0 86.0 89.0 84.0 10:34:20 INFO - PROCESS | 1673 | 10:34:20 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:118.70 Median:152.50 stddev:22.63 (14.8%) stddev-sans-first:22.32 10:34:20 INFO - PROCESS | 1673 | Values: 95.0 134.0 129.0 152.0 104.0 95.0 94.0 118.0 153.0 113.0 10:34:20 INFO - PROCESS | 1673 | 10:34:20 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:104.90 Median:92.00 stddev:23.52 (25.6%) stddev-sans-first:24.40 10:34:20 INFO - PROCESS | 1673 | Values: 91.0 93.0 101.0 92.0 98.0 147.0 151.0 92.0 89.0 95.0 10:34:20 INFO - PROCESS | 1673 | 10:34:20 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.00 stddev:0.84 (1.5%) stddev-sans-first:0.73 10:34:20 INFO - PROCESS | 1673 | Values: 56.0 58.0 58.0 56.0 58.0 58.0 58.0 57.0 58.0 57.0 10:34:20 INFO - PROCESS | 1673 | 10:34:20 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:72.50 stddev:4.27 (5.9%) stddev-sans-first:4.06 10:34:20 INFO - PROCESS | 1673 | Values: 65.0 68.0 67.0 74.0 76.0 75.0 75.0 66.0 67.0 71.0 10:34:20 INFO - PROCESS | 1673 | 10:34:20 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:88.50 stddev:3.81 (4.3%) stddev-sans-first:3.90 10:34:20 INFO - PROCESS | 1673 | Values: 86.0 88.0 89.0 88.0 98.0 84.0 89.0 88.0 92.0 87.0 10:34:20 INFO - PROCESS | 1673 | 10:34:20 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:157.30 Median:161.50 stddev:18.46 (11.4%) stddev-sans-first:19.47 10:34:20 INFO - PROCESS | 1673 | Values: 163.0 161.0 130.0 155.0 162.0 177.0 154.0 137.0 192.0 142.0 10:34:20 INFO - PROCESS | 1673 | 10:34:20 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.50 Median:172.50 stddev:16.26 (9.4%) stddev-sans-first:3.13 10:34:20 INFO - PROCESS | 1673 | Values: 223.0 180.0 171.0 171.0 171.0 170.0 173.0 174.0 170.0 172.0 10:34:20 INFO - PROCESS | 1673 | 10:34:20 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.50 Median:88.00 stddev:11.91 (13.5%) stddev-sans-first:11.59 10:34:20 INFO - PROCESS | 1673 | Values: 66.0 89.0 92.0 87.0 61.0 78.0 70.0 70.0 92.0 90.0 10:34:20 INFO - PROCESS | 1673 | -------- Summary: end -------- 10:34:20 INFO - PROCESS | 1673 | 10:34:21 INFO - TEST-INFO | 1673: exit 0 10:34:21 INFO - TEST-OK | kraken | took 60106ms 10:34:21 INFO - SUITE-END | took 1174s 10:34:21 INFO - Completed test suite (00:19:35) 10:34:21 INFO - javascript benchmark 10:34:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7418.864436206549, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3389.101887999679, 3393.5588373511455, 3393.6945674889257, 3407.2163402417427, 3454.4300190468566, 4827.468978412372, 5649.130345713189, 5708.571857110718, 5713.885722285562, 5720.656760594362, 5390.5418039466595, 5683.772649094031, 5751.7986870459545, 5757.42425757424, 5813.941860581393, 5188.974055129738, 5249.973750131244, 5283.445238249986, 5355.009323275187, 5369.4362091980365, 5325.360956685184, 5372.785088596452, 5378.757955891974, 5396.244525766396, 5428.240046393508, 5103.948960510403, 5222.843314700565, 5244.423113457517, 5338.546223570962, 5355.491228333302, 5244.554212891909, 5254.92117618236, 5284.815031473919, 5297.602679799031, 5307.708076055816, 5121.82073627425, 5201.193814958688, 5241.109011468022, 5244.213367994793, 5261.5264626183825, 5453.754581043862, 5719.628224165416, 5733.254676892019, 5746.885062298772, 5814.622049566765, 6145.938540614581, 6192.133101365813, 6199.907001394938, 6207.596506227081, 6211.130441738115, 6089.878202435971, 6196.10156527301, 6199.132121502993, 6211.844703882439, 6212.782552610682, 5737.827865164052, 5740.741666625012, 5815.5929084963645, 5817.0983497557945, 5840.094785308284, 6175.44421002112, 6216.627002379872, 6221.595596286317, 6223.813285601439, 6339.619622822645, 6149.938500615026, 6159.4456498915315, 6167.876642467126, 6177.2587289525545, 6181.320054793969, 6097.645669838225, 6111.602745821597, 6146.0, 6197.96901015501, 6234.438900498977, 6012.218411606551, 6130.000698177752, 6214.0, 6235.064740288903, 6236.656983865931, 6186.19579454677, 6194.5044396448175, 6205.131281620576, 6205.131281620576, 6230.408111229516, 4865.245886887538, 4878.682885612495, 4928.48250933654, 5005.399352077774, 5021.899562008739, 3602.7478076534917, 4345.565443455693, 4364.432623758891, 4371.825126994884, 4394.98915249488, 5589.385167631557, 5682.77268909239, 5683.204351390808, 5695.60130790849, 5710.971445142748, 5603.775848966077, 6135.877282454415, 6144.385561443909, 6177.6911154443, 6255.093011513305, 5724.3130824300515, 5740.483356497935, 5780.682062486603, 5861.912071318934, 5871.32479764824, 4277.764722940268, 4720.952790472052, 4882.658213924991, 5500.669959802424, 5536.446355364431, 6151.723172457339, 6268.905966410599, 6342.182890855443, 6384.48924086072, 6403.847307484604, 5043.62172837046, 6209.130721698875, 6210.844728881814, 6211.409916057967, 6228.439440450381, 6371.0, 6407.647579383179, 6413.102165696713, 6443.2590252119735, 6444.8388790280615, 5013.749312534432, 6088.452039316306, 6164.352742961924, 6165.0, 6185.8144255672405, 4863.2461968394955, 5599.524040456607, 6156.107364432221, 6201.627902325695, 6202.131701561875, 6134.35589263121, 6148.846278843064, 6163.167972323679, 6174.166487524307, 6191.133241346125, 5922.466977972003, 6094.055421409688, 6131.386861313833, 6150.138980542817, 6150.938490615215, 5992.730327135377, 6017.699115044318, 6029.186059881859, 6068.393160683896, 6078.5441091919165, 6130.080487926846, 6131.712340408564, 6132.356102609162, 6144.938550614615, 6158.876822463615, 5003.54968052877, 5887.882242355215, 5954.523638108854, 5987.0, 6042.607230530001], "value": 5659.7663685656635, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.3485120830836, 481.74949026506226, 544.4854612391289, 548.0956421903858, 596.3470000349615, 33342.8332858336, 38719.225615487674, 41361.759147225595, 41458.75623731289, 41500.58499415005, 41860.0, 42497.36253956193, 42512.787436062776, 42548.787256063755, 42558.14883702324, 9640.325177237613, 9774.804503909918, 9800.068993445611, 9813.165880900133, 9844.015598440135, 9723.319367644268, 9771.36486128403, 9793.755156121082, 9794.16749576287, 9855.655052073178, 8225.218604232618, 9670.129688328048, 9839.704808855746, 9850.704478865646, 9867.309288349818, 9711.417314961125, 9789.412635241873, 9812.558434870449, 9815.656452024145, 9843.212542996544, 9675.854862177073, 9756.609735610567, 9760.219182465386, 9782.5597848097, 9796.853047204298, 36132.81933590341, 36287.911362659164, 36365.27269454623, 36643.63356366429, 36683.4497482538, 3461.6192218855904, 3724.515812944327, 3746.250749850041, 4030.415589739501, 4098.774567398792, 30802.845985770153, 31319.06042818719, 31519.0, 32041.839790800896, 32741.50887736662, 3208.582884225059, 3229.450993331139, 3232.6767323267723, 3254.1376535218187, 3337.581527850654, 479037.0, 482732.0, 483202.0, 487270.0, 491019.0, 61244.69377653172, 61401.69299153476, 61906.0, 61941.0, 62039.6898015507, 30626.84686576553, 32886.01341959745, 35715.285694285965, 65231.34768652348, 65232.0, 16579.50261492157, 16608.584785380463, 16740.58148546296, 16746.162691865356, 16746.6650666986, 12580.496780128693, 12706.936465317614, 12816.743665126833, 12817.743645127046, 12840.871591283967, 16712.83287167113, 19265.51836204106, 19307.165819247148, 19354.709679354823, 19376.4187074388, 7517.774466766005, 7871.724489642835, 7961.323287520626, 8004.5597492138495, 8037.437379383387, 854.5727136431784, 911.4212475078373, 945.4894357047255, 946.4510583861481, 1025.6717850287837, 30380.0, 30410.39179216403, 30616.54075188874, 30915.69084309128, 31118.844405777825, 1052.768390953989, 1064.707205518473, 1086.2559146984183, 1131.332513816852, 1136.3466007045981, 12559.560415385418, 15488.380464781509, 15914.124723140338, 15997.920010399874, 16006.039637621547, 681773.0, 753310.0, 782766.1723382693, 783341.4992650689, 992200.0, 58204.0, 58535.0, 58885.705571472725, 58984.0, 59199.70400147972, 1335.6193484856963, 1349.8987575931844, 1372.0, 1373.5810577773875, 1374.6082366525693, 30927.381452370377, 31799.205019873763, 31814.363712725153, 32380.0, 32382.0, 52400.95198096093, 52647.0, 52647.47352526426, 53139.20291195712, 53149.0, 627820.0, 633854.0, 634082.0, 634268.0, 637979.0, 25703.228903132935, 26758.331041724112, 27220.183394498195, 27738.0, 27796.722032779413, 46025.76987115043, 46292.305615416466, 46796.0, 46912.7654361726, 47510.0, 29617.5557366644, 38410.231795363376, 38517.80741096276, 40067.59932400638, 40357.0, 38256.04359891025, 38632.80683596564, 39422.0, 39555.0, 40370.59629403668, 37087.629123708415, 37345.6265437353, 37935.62064379321, 38096.42855357117, 38124.85625431241, 28558.42883142367, 33081.50377744384, 33369.33261334808, 34719.65280347164, 34786.0, 24698.753012469646, 24938.37654058663, 25471.617925731498, 25916.870415648555, 25978.0], "value": 20151.401305500804, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.33368603885094, 380.8552749955017, 386.87619961612285, 391.82172111689187, 406.81083296267246, 19105.904470477646, 21112.4721881953, 21749.347519574418, 21783.891080544574, 21928.23251186207, 21011.579768404623, 21023.0, 21102.261420850275, 21360.893195534038, 21504.462388440297, 5653.095504719246, 6073.301570319403, 6153.046277826922, 6168.537359698041, 6172.197614310134, 4173.332266837307, 5980.282366116072, 6424.582402143869, 6721.327867213264, 6748.797536073926, 5818.4181581841685, 6737.696803643823, 6773.0, 6780.457563394942, 6805.081314022618, 6052.515798736091, 6167.074938759222, 6182.72177752, 6192.9380706192815, 6194.25668919728, 5885.382034886361, 5913.9112913306335, 6025.132209015219, 6045.697715114271, 6052.757889684407, 13190.406431710597, 13321.333933303296, 13372.73254534904, 13531.39108740109, 21058.157673693033, 1669.6326808102199, 1699.73654083616, 2120.0883620043373, 2225.7774222577773, 2300.766589475373, 8813.206811386943, 8932.19610235082, 10163.0, 14969.775453368207, 15241.695166096615, 1544.3513724235847, 1549.3105568022236, 1813.9274429022823, 1913.1199648161862, 1921.135489029928, 124398.0, 134820.999999999, 136141.0, 137627.0, 137669.0, 9810.950945245228, 11308.34253950321, 11487.540498380138, 11536.884631153749, 11636.243644163102, 14458.20479873596, 14469.348879300247, 14503.85496145046, 14510.1293922365, 14514.201718905562, 7514.323710866049, 8063.758087257274, 8356.582170891432, 8369.163083691583, 8525.147485251426, 6855.348741869514, 8352.916470835335, 8394.622241999125, 8534.87197692035, 8577.05652378238, 3829.4830197922925, 4477.865664030084, 4540.273556230987, 4578.771061446914, 4598.448186217675, 1621.408186012097, 1680.0679218898365, 1823.1795691938416, 1909.0740990619538, 1932.159510612888, 871.825634873028, 917.5504003038596, 951.6526467839327, 984.675057231112, 986.447589349952, 20087.39737807868, 21456.4635884101, 21590.892045539673, 21593.24423645165, 21686.18079649667, 921.3147410358566, 944.3861490031397, 958.4249450329746, 1016.7458135466134, 1037.818381783185, 10993.395363255098, 11397.601083962021, 11455.770884582262, 11456.828147577793, 11482.712932176762, 568561.0, 582615.0, 597436.0, 597842.0, 605217.0, 123382.0, 129146.35426822805, 139371.0, 139927.90108148183, 140229.0, 949.4635530925005, 973.255459573427, 977.628501169551, 996.5465713100667, 996.6113215845826, 18788.81211187925, 18806.247750089842, 18944.621107577495, 18992.050397480347, 19100.426987190407, 41725.58274417217, 49587.25619115788, 50489.24266135937, 51621.0, 51623.225651616005, 80548.59725701335, 86894.48291378045, 91306.0, 92018.61972070558, 92145.53927230321, 7820.960895195488, 8153.22544358285, 8203.507789532647, 8227.259546640831, 8344.624491897757, 11916.940415298215, 12574.622761317176, 12643.747125057263, 12936.288504132363, 13154.671133221746, 10353.327033742782, 10644.84032739494, 11276.759556448947, 11434.714132146762, 11471.483783229607, 9182.449053056838, 10292.794144117226, 11373.829392559284, 11591.710207244887, 11619.012383947122, 10579.89420105789, 11480.94259528697, 11735.413229338328, 11788.351640659846, 11921.582744604244, 7463.328300452985, 7594.030918767441, 7987.960060199662, 8170.754877353689, 8346.582670866312, 8209.38429617793, 11151.944240278746, 11274.718132046764, 11321.0, 11602.883971160518], "value": 9536.390306385356, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1358.592422273318, 1496.4762333183387, 1517.9316930738119, 1848.8151184881506, 1864.8414884734798, 6787.72849086036, 8618.784530386736, 8778.604962776675, 9185.77035574111, 9204.309676774245, 8802.0, 9001.0, 9008.954955225232, 9009.95495022524, 9020.413673111243, 2231.129859354853, 2282.6233671444193, 2370.1941339944415, 2424.0303878448585, 2442.291735396742, 2367.562001029815, 2393.940151496218, 2394.365493144318, 2396.808255339569, 2415.2874901903892, 2386.427257458206, 2390.2948630154146, 2391.1750446096157, 2405.542946840095, 2408.434018005767, 2358.092134528219, 2373.5134297468976, 2380.440596459839, 2391.56951748686, 2392.629142482909, 2359.9646005309933, 2372.9288121356212, 2377.227401094651, 2377.441301294212, 2378.3935096550267, 8926.196642302159, 8957.820843583091, 8967.417117887382, 9040.68357607487, 9052.27581793455, 5215.0, 5339.412664606929, 5348.411674715778, 5363.54409875161, 5370.731463426852, 3087.5831762712196, 3635.4365073413665, 3655.9086022849374, 3689.409694448875, 3694.3350196964534, 1078.7411021354897, 1088.2545456362418, 1088.3905013192636, 1093.1528065749028, 1094.9069329107035, 10456.47717614124, 11592.724800271963, 11914.821277680841, 11984.28094314344, 11997.400129993553, 4729.826149856579, 4737.715737055788, 4786.353842231324, 4845.781939812717, 4890.608751299887, 10024.548895299584, 10083.848742268872, 10099.192064634812, 10101.040401161878, 10160.847587286196, 5439.238506609078, 5450.727463626882, 5463.644863083887, 5465.125579907196, 5467.617266791366, 4455.821767129343, 4816.518348165226, 4942.35749352589, 4961.355023846877, 4984.127777638899, 3897.376419772879, 3953.5651078381356, 3992.8039578231856, 4044.1911617675996, 4081.836726530965, 1143.0626885953438, 1316.0853207021028, 1317.5586178630074, 1322.8941684665397, 1324.079764563619, 887.3433659092225, 913.6254135804288, 927.8700981862545, 931.6366617019368, 946.9526523673788, 4967.503249675004, 5248.9737551312, 5856.648601083949, 5986.0, 5991.2510936132985, 389.2817751248941, 442.72993473980847, 456.9543045695404, 464.65847602012497, 465.9138059459011, 3889.416587511839, 3893.7734613596626, 3916.3538016227003, 3992.480977472968, 4027.234825383168, 10503.369797812156, 10768.892311076946, 10862.511186996604, 10904.127669786398, 10949.4525273736, 10790.352578845295, 10808.621698240524, 10829.29609575375, 10829.29609575375, 10850.457477126112, 8207.466514676527, 8286.0, 8297.25324720778, 8300.501969881827, 8403.117672644406, 4434.935615452333, 5054.039732450822, 5518.613697041169, 5706.914396284142, 5978.491828194652, 9474.289428292768, 10124.139448146696, 10181.879993200593, 10194.439305837954, 10211.438370889673, 10056.094951454405, 10100.797984040424, 10218.540165692413, 10224.795504089729, 10235.437050961971, 6897.862042759017, 7336.816579585339, 7476.364509016795, 7503.2496750324535, 7549.0941087069905, 8386.916130838614, 8387.41288109851, 8404.411691181558, 8428.957855210685, 8488.74533763988, 7328.706851725871, 7413.406927445899, 7565.0, 7619.390448764197, 7697.0378702662165, 7287.2348403419, 7446.920196571358, 7476.925230747623, 7561.206073362438, 7588.81027974283, 6998.300169983165, 7100.751473698396, 7119.288071192839, 7158.355747982707, 7170.175429825428, 4149.27387707144, 4982.128127577587, 5079.847604571869, 5109.64232503736, 5152.716600587004, 5054.73631592102, 5060.569851562511, 5128.948130976636, 5282.0, 5297.576193904401], "value": 4523.996858246958, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [281.31499797991887, 317.2275509135256, 319.7857435518202, 324.86355730593164, 325.8289398066016, 5055.342805435297, 5685.943140568593, 5764.942350576493, 5783.4720820299635, 5885.970570147143, 5562.972185139079, 5614.494695477406, 5657.773689052443, 5665.631733937302, 5727.914081288774, 4102.548719640837, 4156.584341565849, 4215.641670458015, 4241.533431322552, 4330.696851220416, 3189.649138594753, 3243.016424889096, 4213.894652633693, 4292.484901811787, 4313.633341165989, 3629.4374371972253, 3896.8636097736585, 4272.935905961397, 4290.227759003378, 4297.849575264866, 3501.1072176594807, 4184.832606695729, 4185.162967406515, 4225.7253278536955, 4287.785610719483, 3433.5536380270655, 3499.230169362736, 3687.7418580699364, 3696.4270538066376, 3700.35243832331, 4398.868033958986, 5476.67139971603, 5499.532539734128, 5510.476504732044, 5546.27898373213, 4135.669146468275, 4589.5410458954175, 4951.935333903192, 4977.601791856643, 4987.226979818134, 5503.560274828233, 6041.190846256468, 6043.2748070231855, 6070.484008859252, 6071.666058366788, 3764.228333191689, 4111.794410279504, 4119.97940010301, 4122.9381559276635, 4131.277026520377, 5635.492805647511, 5711.343195532525, 5996.130561068664, 6348.777792777277, 6431.260405053432, 3689.631036896289, 4180.456540649757, 4181.937270940938, 4199.328107502785, 4201.642860356843, 3628.8004159771467, 3963.504561929759, 4223.176480586318, 4305.53611771999, 4307.04029666501, 4852.902941941142, 4971.130052240844, 4975.850724478271, 5028.497150284942, 5085.389753229636, 6029.0, 6044.093385992136, 6064.36324185963, 6096.293037949381, 6147.969260153671, 1241.9565315213924, 1441.3297816515162, 1466.442751754348, 1469.875060619838, 1471.4187895781106, 667.9131712877295, 901.4681338010474, 902.9232515236279, 919.3610440743753, 931.8352059925094, 5299.920501192485, 5603.719814009365, 5639.577031722555, 5651.406602306781, 5684.943150568524, 5254.797750227248, 5282.0, 5498.780048798083, 5920.526357891358, 6305.117283580303, 5311.48622642545, 5603.3836278009385, 5645.943540564624, 5696.550483292224, 5712.914306285409, 2694.8652567371946, 2759.379139693553, 2959.4229125320358, 3242.9784617845303, 3276.623188333372, 6015.2856435915555, 6135.32511423743, 6465.316893489244, 6479.3520647935775, 6505.60966342021, 5299.39057008449, 6319.58922670025, 6348.0, 6392.904106438407, 6442.226932768004, 5697.376247769449, 5885.410939046434, 6458.903116453257, 6504.1869766279215, 6528.281888992302, 4932.45742968266, 5016.774245158904, 5251.665192602649, 5718.3702644746045, 6255.71849266775, 5719.48524632785, 5902.789928064823, 6118.296395914349, 6157.568970172045, 6192.845178870384, 5440.972795135999, 6110.663913484801, 6522.380373864476, 6528.759535688204, 6562.507811914221, 3366.983165084159, 3751.587325394259, 3860.9575414638452, 4144.715138307134, 4192.526409418458, 6232.317274335814, 6300.180976473029, 6300.841444646511, 6312.43188113072, 6346.746130154742, 5162.934826069602, 5213.905079933256, 5495.340559132929, 5987.305712429337, 6013.338532761305, 5059.671121377099, 5607.775688972395, 5630.0991841305195, 5779.479846813639, 5992.460678538952, 5316.0, 5393.83818485446, 5552.0, 5796.82609521715, 5905.881882362242, 5058.974705126451, 5218.9739051304505, 5710.2298287531, 5989.610675306091, 6015.5488338373925, 5096.592272618108, 5489.58828087887, 5683.0, 5922.785828904978, 5926.348101708722], "value": 4321.314021999243, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [637.5632691606253, 721.9602921839297, 722.5917356693468, 775.9107702614201, 820.0610301205119, 15767.842321576782, 16088.436904708338, 16098.436554720587, 16116.597085072865, 16145.19274036298, 14352.784708229385, 14425.0, 14450.421983120663, 14461.710765784677, 14501.564953051398, 688.3563867783613, 837.3677873205721, 845.8773477845708, 852.7697521669146, 879.459132633429, 856.6787454704486, 870.0951010948606, 871.6469829718942, 873.4802792338548, 875.2997601918472, 935.0415823780611, 938.4322484896642, 940.3934462271831, 940.6096469964956, 940.7130825098342, 872.0625327772607, 923.6490133749132, 931.0735817861238, 932.8600709893502, 941.9246460283196, 648.7469886744174, 649.5583003557579, 650.440621065883, 708.5359089796192, 709.3438569323407, 14715.337809798584, 15020.849791502163, 15047.774283385757, 15066.0, 15083.095014299068, 11989.700257493545, 14735.926320368437, 14772.039817411835, 14776.26118694061, 14858.2570871456, 11853.288802671867, 11933.940330298294, 11972.880271197264, 11984.940075299655, 12031.51873925042, 3835.861725938821, 3895.162540053902, 3915.549711783153, 3918.608139186087, 4004.619561141716, 117535.0, 120176.0, 121017.39491302575, 121442.0, 121865.0, 11180.0, 11274.943625281821, 11402.429878506042, 11534.769304614028, 11703.180777345504, 23757.643635345485, 23793.286201413986, 23793.7620623795, 23806.7619323808, 23897.880510597683, 6797.864042719118, 7859.685612575548, 8025.518468891885, 8186.567835129826, 8197.180281971756, 3964.28642844285, 4255.468066491689, 4495.640348772155, 4517.412736344254, 4517.638588912879, 3549.254656522108, 3652.561692596852, 3679.19057807282, 3685.447182922583, 3689.0592898810633, 659.9769008084791, 670.6847781542667, 677.6340775980915, 683.5693513086823, 686.0977814174345, 500.2321436594809, 644.6969924135649, 673.2627772589008, 674.9763758268437, 685.3078390825302, 8725.738227853175, 8745.851493462349, 9427.858582121273, 9576.898656654574, 9706.805863882682, 620.7796232337546, 639.523554951564, 677.5528151420137, 684.2746688510196, 687.7902239816904, 4457.952381190417, 4583.510359133228, 4692.53074692528, 4698.436187657503, 4727.314539391838, 133981.0, 158634.0, 163002.0, 163815.36184638002, 172983.43149116388, 116122.0, 130150.74623134307, 130414.04378934324, 138322.0, 145081.09837803396, 91857.0, 95940.0, 118778.0, 121234.3938280303, 122485.77514224921, 7919.0, 8738.650453981769, 8757.6934807284, 9063.274938005075, 9277.953610231905, 57884.0, 63948.04077938742, 67877.3212267871, 67907.66046169738, 68620.3137968634, 98204.01795981948, 102599.52517805823, 103965.0, 105180.0, 105957.0, 10527.684169474927, 12099.939500302442, 12102.878971210175, 12263.509459621515, 12515.310433091416, 13131.0, 13272.0, 13343.466261349826, 13382.799258011333, 13488.190708557519, 12440.253584784941, 12456.065795065588, 12489.18820276679, 12610.180338277982, 13029.022823288102, 12264.32546209967, 12272.202306850026, 12588.559400420942, 12946.805797913226, 12953.676158096123, 12064.638060858188, 12161.391930403268, 12327.260364378166, 12365.443555040225, 12594.87405125937, 10571.0, 10603.575856965635, 10854.34873907568, 10952.890471095187, 11174.832377514182, 7414.369778568882, 9397.389169703947, 10671.786564268827, 10739.409332486475, 10798.920107989139], "value": 7840.689392951851, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1588.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 117.0, 116.0, 115.0, 116.0, 117.0, 114.0, 142.0, 114.0, 130.0], "value": 119.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 111.0, 138.0, 159.0, 109.0, 135.0, 130.0, 112.0, 173.0, 142.0], "value": 135.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 199.0, 206.0, 237.0, 244.0, 280.0, 229.0, 201.0, 196.0, 195.0], "value": 218.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 89.0, 77.0, 141.0, 59.0, 58.0, 60.0, 62.0, 136.0, 58.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 100.0, 143.0, 70.0, 73.0, 91.0, 110.0, 69.0, 72.0, 107.0], "value": 90.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 103.0, 89.0, 85.0, 91.0, 86.0, 89.0, 86.0, 89.0, 84.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 134.0, 129.0, 152.0, 104.0, 95.0, 94.0, 118.0, 153.0, 113.0], "value": 118.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 101.0, 92.0, 98.0, 147.0, 151.0, 92.0, 89.0, 95.0], "value": 104.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 58.0, 56.0, 58.0, 58.0, 58.0, 57.0, 58.0, 57.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 67.0, 74.0, 76.0, 75.0, 75.0, 66.0, 67.0, 71.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 89.0, 88.0, 98.0, 84.0, 89.0, 88.0, 92.0, 87.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 130.0, 155.0, 162.0, 177.0, 154.0, 137.0, 192.0, 142.0], "value": 157.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [223.0, 180.0, 171.0, 171.0, 171.0, 170.0, 173.0, 174.0, 170.0, 172.0], "value": 177.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 89.0, 92.0, 87.0, 61.0, 78.0, 70.0, 70.0, 92.0, 90.0], "value": 79.5, "unit": "score"}]}]} 10:34:21 INFO - Return code: 0 10:34:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:34:21 ERROR - # TBPL SUCCESS # 10:34:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:34:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:34:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:34:21 INFO - 2016-09-07 10:34:21.718 screenresolution[1678:10580] starting screenresolution argv=screenresolution get 10:34:21 INFO - 2016-09-07 10:34:21.720 screenresolution[1678:10580] Display 0: 1600x1200x32@60 10:34:21 INFO - 2016-09-07 10:34:21.726 screenresolution[1679:10583] starting screenresolution argv=screenresolution list 10:34:21 INFO - Available Modes on Display 0 10:34:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:34:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:34:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:34:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:34:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:34:21 INFO - Intel Iris: 10:34:21 INFO - Chipset Model: Intel Iris 10:34:21 INFO - Type: GPU 10:34:21 INFO - Bus: Built-In 10:34:21 INFO - VRAM (Dynamic, Max): 1536 MB 10:34:21 INFO - Vendor: Intel (0x8086) 10:34:21 INFO - Device ID: 0x0a2e 10:34:21 INFO - Revision ID: 0x0009 10:34:21 INFO - Displays: 10:34:21 INFO - Display: 10:34:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:34:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:34:21 INFO - Display Serial Number: 1600x1200 60 10:34:21 INFO - Main Display: Yes 10:34:21 INFO - Mirror: Off 10:34:21 INFO - Online: Yes 10:34:21 INFO - Rotation: Supported 10:34:21 INFO - Return code: 0 10:34:21 INFO - Running post-action listener: _resource_record_post_action 10:34:21 INFO - [mozharness: 2016-09-07 17:34:21.962459Z] Finished run-tests step (success) 10:34:21 INFO - Running post-run listener: _resource_record_post_run 10:34:22 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 36922368; Write bytes: 709114368; Read time: 240; Write time: 1982 10:34:22 INFO - TinderboxPrint: CPU usage
54.6% 10:34:22 INFO - TinderboxPrint: I/O read bytes / time
36,922,368 / 240 10:34:22 INFO - TinderboxPrint: I/O write bytes / time
709,114,368 / 1,982 10:34:22 INFO - TinderboxPrint: CPU idle
2,198.6 (45.4%) 10:34:22 INFO - TinderboxPrint: CPU system
979.9 (20.3%) 10:34:22 INFO - TinderboxPrint: CPU user
1,659.2 (34.3%) 10:34:22 INFO - TinderboxPrint: Swap in / out
443,355,136 / 0 10:34:22 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 412037632; Write bytes: 410060800; Read time: 29817; Write time: 820 10:34:22 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 28855296; Write bytes: 297595392; Read time: 171; Write time: 1156 10:34:22 INFO - Running post-run listener: _upload_blobber_files 10:34:22 INFO - Blob upload gear active. 10:34:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:34:22 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:34:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:34:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:34:22 INFO - (blobuploader) - INFO - Open directory for files ... 10:34:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:34:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:34:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:34:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:34:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:34:23 INFO - (blobuploader) - INFO - Done attempting. 10:34:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:34:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:34:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:34:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:34:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:34:24 INFO - (blobuploader) - INFO - Done attempting. 10:34:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:34:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:34:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:34:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:34:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:34:27 INFO - (blobuploader) - INFO - Done attempting. 10:34:27 INFO - (blobuploader) - INFO - Iteration through files over. 10:34:27 INFO - Return code: 0 10:34:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:34:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:34:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23b3b247889ba7587bb08de223fe9bdfa47d48ffa884843a24b557f86a2fa4e10ed188966b8d898d643dd2384d023ee08086bcb952c5c86cab1b1c4d4719270f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e12b896fd8cef4595076e30d7423b8b5ece321406b6d5f24cfbeb59667de731ff048fe3f7bb5327bec5a62b4ba4ea9e3c5be196d5b0489717ab82f2d965bca5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f5211bdc561c8b8c9fe8ed5d50481197242ccc9392cd573c5801cc18765d4144a3c7242d0c1518dafac3f18aa56b2277413ad963c3c9084e8c10803820a114c"} 10:34:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:34:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:34:27 INFO - Contents: 10:34:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23b3b247889ba7587bb08de223fe9bdfa47d48ffa884843a24b557f86a2fa4e10ed188966b8d898d643dd2384d023ee08086bcb952c5c86cab1b1c4d4719270f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e12b896fd8cef4595076e30d7423b8b5ece321406b6d5f24cfbeb59667de731ff048fe3f7bb5327bec5a62b4ba4ea9e3c5be196d5b0489717ab82f2d965bca5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f5211bdc561c8b8c9fe8ed5d50481197242ccc9392cd573c5801cc18765d4144a3c7242d0c1518dafac3f18aa56b2277413ad963c3c9084e8c10803820a114c"} 10:34:27 INFO - Running post-run listener: copy_logs_to_upload_dir 10:34:27 INFO - Copying logs to upload dir... 10:34:27 INFO - mkdir: /builds/slave/test/build/upload/logs 10:34:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.003048 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-07 10:34:27.598437) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 10:34:27.602128) ========= 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.Xv6gL4ENIo/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.Gx4sY1eX1L/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23b3b247889ba7587bb08de223fe9bdfa47d48ffa884843a24b557f86a2fa4e10ed188966b8d898d643dd2384d023ee08086bcb952c5c86cab1b1c4d4719270f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e12b896fd8cef4595076e30d7423b8b5ece321406b6d5f24cfbeb59667de731ff048fe3f7bb5327bec5a62b4ba4ea9e3c5be196d5b0489717ab82f2d965bca5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f5211bdc561c8b8c9fe8ed5d50481197242ccc9392cd573c5801cc18765d4144a3c7242d0c1518dafac3f18aa56b2277413ad963c3c9084e8c10803820a114c"} build_url:https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011005 build_url: 'https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23b3b247889ba7587bb08de223fe9bdfa47d48ffa884843a24b557f86a2fa4e10ed188966b8d898d643dd2384d023ee08086bcb952c5c86cab1b1c4d4719270f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e12b896fd8cef4595076e30d7423b8b5ece321406b6d5f24cfbeb59667de731ff048fe3f7bb5327bec5a62b4ba4ea9e3c5be196d5b0489717ab82f2d965bca5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f5211bdc561c8b8c9fe8ed5d50481197242ccc9392cd573c5801cc18765d4144a3c7242d0c1518dafac3f18aa56b2277413ad963c3c9084e8c10803820a114c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 10:34:27.638928) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:34:27.639272) ========= 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.Xv6gL4ENIo/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.Gx4sY1eX1L/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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.005172 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:34:27.691352) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 10:34:27.692283) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 10:34:27.692995) ========= ========= Total master_lag: 0.38 =========