builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0236 starttime: 1473280996.24 results: success (0) buildid: 20160907124615 builduid: 83b3135725bb4640b1e2734b93f5e2e1 revision: f46da98e6eddfc6df2b5e0aa565cc47fd752c0a7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 13:43:16.243247) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 13:43:16.243708) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 13:43:16.243987) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 13:43:16.282262) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 13:43:16.282657) ========= 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.bHJdSwkhsq/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.9snY1JPPP9/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 13:43:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 80.4M=0s 2016-09-07 13:43:16 (80.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.151316 ========= master_lag: 0.82 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 13:43:17.255977) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2016-09-07 13:43:17.256268) ========= 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.bHJdSwkhsq/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.9snY1JPPP9/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.021695 ========= master_lag: 2.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2016-09-07 13:43:19.295418) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-07 13:43:19.295804) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f46da98e6eddfc6df2b5e0aa565cc47fd752c0a7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev f46da98e6eddfc6df2b5e0aa565cc47fd752c0a7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bHJdSwkhsq/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.9snY1JPPP9/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 13:43:19,335 truncating revision to first 12 chars 2016-09-07 13:43:19,335 Setting DEBUG logging. 2016-09-07 13:43:19,335 attempt 1/10 2016-09-07 13:43:19,335 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f46da98e6edd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 13:43:20,776 unpacking tar archive at: fx-team-f46da98e6edd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.726080 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-07 13:43:21.052064) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 13:43:21.052431) ========= script_repo_revision: f46da98e6eddfc6df2b5e0aa565cc47fd752c0a7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 13:43:21.052904) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 13:43:21.053188) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 13:43:21.072835) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-07 13:43:21.073247) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bHJdSwkhsq/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.9snY1JPPP9/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 13:43:21 INFO - MultiFileLogger online at 20160907 13:43:21 in /builds/slave/test 13:43:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 13:43:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:43:21 INFO - {'append_to_log': False, 13:43:21 INFO - 'base_work_dir': '/builds/slave/test', 13:43:21 INFO - 'blob_upload_branch': 'Fx-Team', 13:43:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:43:21 INFO - 'branch': 'Fx-Team', 13:43:21 INFO - 'buildbot_json_path': 'buildprops.json', 13:43:21 INFO - 'config_files': ('talos/mac_config.py',), 13:43:21 INFO - 'default_actions': ('clobber', 13:43:21 INFO - 'read-buildbot-config', 13:43:21 INFO - 'download-and-extract', 13:43:21 INFO - 'populate-webroot', 13:43:21 INFO - 'create-virtualenv', 13:43:21 INFO - 'install', 13:43:21 INFO - 'run-tests'), 13:43:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:43:21 INFO - 'download_minidump_stackwalk': True, 13:43:21 INFO - 'download_symbols': 'ondemand', 13:43:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:43:21 INFO - 'tooltool.py': '/tools/tooltool.py', 13:43:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:43:21 INFO - '/tools/misc-python/virtualenv.py')}, 13:43:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:43:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:43:21 INFO - 'installer_path': 'installer.exe', 13:43:21 INFO - 'log_level': 'info', 13:43:21 INFO - 'log_name': 'talos', 13:43:21 INFO - 'log_to_console': True, 13:43:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:43:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:43:21 INFO - 'opt_config_files': (), 13:43:21 INFO - 'pip_index': False, 13:43:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:43:21 INFO - 'cmd': ('bash', 13:43:21 INFO - '-c', 13:43:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:43:21 INFO - 'enabled': True, 13:43:21 INFO - 'halt_on_failure': False, 13:43:21 INFO - 'name': 'check_screen_resolution'},), 13:43:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:43:21 INFO - 'cmd': ('bash', 13:43:21 INFO - '-c', 13:43:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:43:21 INFO - 'enabled': True, 13:43:21 INFO - 'halt_on_failure': False, 13:43:21 INFO - 'name': 'check_screen_resolution'},), 13:43:21 INFO - 'run_cmd_checks_enabled': True, 13:43:21 INFO - 'sps_profile': False, 13:43:21 INFO - 'sps_profile_interval': 0, 13:43:21 INFO - 'suite': 'dromaeojs', 13:43:21 INFO - 'system_bits': '32', 13:43:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:43:21 INFO - 'title': 't-yosemite-r7-0236', 13:43:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:43:21 INFO - 'use_talos_json': True, 13:43:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:43:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:43:21 INFO - 'work_dir': 'build'} 13:43:21 INFO - [mozharness: 2016-09-07 20:43:21.231486Z] Running clobber step. 13:43:21 INFO - Running pre-action listener: _resource_record_pre_action 13:43:21 INFO - Running main action method: clobber 13:43:21 INFO - rmtree: /builds/slave/test/build 13:43:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:43:22 INFO - Running post-action listener: _resource_record_post_action 13:43:22 INFO - [mozharness: 2016-09-07 20:43:22.285209Z] Finished clobber step (success) 13:43:22 INFO - [mozharness: 2016-09-07 20:43:22.285393Z] Running read-buildbot-config step. 13:43:22 INFO - Running pre-action listener: _resource_record_pre_action 13:43:22 INFO - Running main action method: read_buildbot_config 13:43:22 INFO - Using buildbot properties: 13:43:22 INFO - { 13:43:22 INFO - "project": "", 13:43:22 INFO - "product": "firefox", 13:43:22 INFO - "script_repo_revision": "production", 13:43:22 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 13:43:22 INFO - "repository": "", 13:43:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 13:43:22 INFO - "buildid": "20160907124615", 13:43:22 INFO - "pgo_build": "False", 13:43:22 INFO - "basedir": "/builds/slave/test", 13:43:22 INFO - "buildnumber": 146, 13:43:22 INFO - "slavename": "t-yosemite-r7-0236", 13:43:22 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 13:43:22 INFO - "platform": "yosemite_r7", 13:43:22 INFO - "scheduler": "tests-fx-team-macosx64-talos", 13:43:22 INFO - "branch": "fx-team", 13:43:22 INFO - "repo_path": "integration/fx-team", 13:43:22 INFO - "revision": "f46da98e6eddfc6df2b5e0aa565cc47fd752c0a7", 13:43:22 INFO - "stage_platform": "macosx64", 13:43:22 INFO - "builduid": "83b3135725bb4640b1e2734b93f5e2e1", 13:43:22 INFO - "slavebuilddir": "test" 13:43:22 INFO - } 13:43:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/MXceWftITIuqnG98ghoOMw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 13:43:22 INFO - Running post-action listener: _resource_record_post_action 13:43:22 INFO - [mozharness: 2016-09-07 20:43:22.290245Z] Finished read-buildbot-config step (success) 13:43:22 INFO - [mozharness: 2016-09-07 20:43:22.290364Z] Running download-and-extract step. 13:43:22 INFO - Running pre-action listener: _resource_record_pre_action 13:43:22 INFO - Running main action method: download_and_extract 13:43:22 INFO - mkdir: /builds/slave/test/build/tests 13:43:22 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')]} 13:43:22 INFO - trying https://queue.taskcluster.net/v1/task/MXceWftITIuqnG98ghoOMw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 13:43:22 INFO - Downloading https://queue.taskcluster.net/v1/task/MXceWftITIuqnG98ghoOMw/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 13:43:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MXceWftITIuqnG98ghoOMw/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 13:43:23 INFO - Downloaded 1199 bytes. 13:43:23 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 13:43:23 INFO - Using the following test package requirements: 13:43:23 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 13:43:23 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:43:23 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 13:43:23 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:43:23 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 13:43:23 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:43:23 INFO - u'jsshell-mac.zip'], 13:43:23 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:43:23 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 13:43:23 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 13:43:23 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:43:23 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 13:43:23 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:43:23 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 13:43:23 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:43:23 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 13:43:23 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:43:23 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 13:43:23 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:43:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MXceWftITIuqnG98ghoOMw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 13:43:24 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 13:43:31 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 13:43:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MXceWftITIuqnG98ghoOMw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 13:43:31 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 13:43:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MXceWftITIuqnG98ghoOMw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 13:43:38 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 13:43:39 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')]} 13:43:39 INFO - trying https://queue.taskcluster.net/v1/task/MXceWftITIuqnG98ghoOMw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 13:43:39 INFO - Downloading https://queue.taskcluster.net/v1/task/MXceWftITIuqnG98ghoOMw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 13:43:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MXceWftITIuqnG98ghoOMw/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 13:43:42 INFO - Downloaded 111553418 bytes. 13:43:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MXceWftITIuqnG98ghoOMw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 13:43:42 INFO - mkdir: /builds/slave/test/properties 13:43:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:43:42 INFO - Writing to file /builds/slave/test/properties/build_url 13:43:42 INFO - Contents: 13:43:42 INFO - build_url:https://queue.taskcluster.net/v1/task/MXceWftITIuqnG98ghoOMw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 13:43:43 INFO - Running post-action listener: _resource_record_post_action 13:43:43 INFO - Running post-action listener: set_extra_try_arguments 13:43:43 INFO - [mozharness: 2016-09-07 20:43:43.378653Z] Finished download-and-extract step (success) 13:43:43 INFO - [mozharness: 2016-09-07 20:43:43.378756Z] Running populate-webroot step. 13:43:43 INFO - Running pre-action listener: _resource_record_pre_action 13:43:43 INFO - Running main action method: populate_webroot 13:43:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:43:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:43:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:43:43 INFO - '--tppagecycles', 13:43:43 INFO - '7'], 13:43:43 INFO - 'tests': ['tsvgm']}}, 13:43:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:43:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:43:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:43:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:43:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:43:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:43:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:43:43 INFO - 'talos_options': ['--disable-e10s'], 13:43:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:43:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:43:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:43:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:43:43 INFO - 'talos_options': ['--disable-e10s'], 13:43:43 INFO - 'tests': ['damp', 'tps']}, 13:43:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:43:43 INFO - 'tests': ['damp', 'tps']}, 13:43:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:43:43 INFO - 'tests': ['dromaeo_dom']}, 13:43:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:43:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:43:43 INFO - 'tests': ['basic_compositor_video']}, 13:43:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:43:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:43:43 INFO - 'tests': ['a11yr', 13:43:43 INFO - 'ts_paint', 13:43:43 INFO - 'tpaint', 13:43:43 INFO - 'sessionrestore', 13:43:43 INFO - 'sessionrestore_no_auto_restore', 13:43:43 INFO - 'tabpaint']}, 13:43:43 INFO - 'other-e10s': {'tests': ['a11yr', 13:43:43 INFO - 'ts_paint', 13:43:43 INFO - 'tpaint', 13:43:43 INFO - 'sessionrestore', 13:43:43 INFO - 'sessionrestore_no_auto_restore', 13:43:43 INFO - 'tabpaint']}, 13:43:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:43:43 INFO - 'ts_paint', 13:43:43 INFO - 'tpaint', 13:43:43 INFO - 'sessionrestore', 13:43:43 INFO - 'sessionrestore_no_auto_restore', 13:43:43 INFO - 'tabpaint']}, 13:43:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:43:43 INFO - 'ts_paint', 13:43:43 INFO - 'tpaint', 13:43:43 INFO - 'sessionrestore', 13:43:43 INFO - 'sessionrestore_no_auto_restore', 13:43:43 INFO - 'tabpaint']}, 13:43:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:43:43 INFO - 'tests': ['a11yr', 13:43:43 INFO - 'ts_paint', 13:43:43 INFO - 'tpaint', 13:43:43 INFO - 'sessionrestore', 13:43:43 INFO - 'sessionrestore_no_auto_restore', 13:43:43 INFO - 'tabpaint']}, 13:43:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:43:43 INFO - 'tests': ['a11yr', 13:43:43 INFO - 'ts_paint', 13:43:43 INFO - 'tpaint', 13:43:43 INFO - 'sessionrestore', 13:43:43 INFO - 'sessionrestore_no_auto_restore', 13:43:43 INFO - 'tabpaint']}, 13:43:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:43:43 INFO - 'tests': ['tsvgx', 13:43:43 INFO - 'tsvgr_opacity', 13:43:43 INFO - 'tart', 13:43:43 INFO - 'tscrollx', 13:43:43 INFO - 'cart']}, 13:43:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:43:43 INFO - 'tsvgr_opacity', 13:43:43 INFO - 'tart', 13:43:43 INFO - 'tscrollx', 13:43:43 INFO - 'cart']}, 13:43:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:43:43 INFO - 'talos_options': ['--disable-e10s'], 13:43:43 INFO - 'tests': ['tp5o']}, 13:43:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:43:43 INFO - 'tests': ['tp5o']}, 13:43:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:43:43 INFO - 'talos_options': ['--disable-e10s', 13:43:43 INFO - '--xperf_path', 13:43:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:43:43 INFO - 'tests': ['tp5n']}, 13:43:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:43:43 INFO - 'talos_options': ['--xperf_path', 13:43:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:43:43 INFO - 'tests': ['tp5n']}}, 13:43:43 INFO - 'talos.zip': {'path': '', 13:43:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:43:43 INFO - Running post-action listener: _resource_record_post_action 13:43:43 INFO - [mozharness: 2016-09-07 20:43:43.397539Z] Finished populate-webroot step (success) 13:43:43 INFO - [mozharness: 2016-09-07 20:43:43.397678Z] Running create-virtualenv step. 13:43:43 INFO - Running pre-action listener: _resource_record_pre_action 13:43:43 INFO - Running main action method: create_virtualenv 13:43:43 INFO - Creating virtualenv /builds/slave/test/build/venv 13:43:43 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 13:43:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:43:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:43:43 INFO - Using real prefix '/tools/python27' 13:43:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:43:44 INFO - Installing distribute.............................................................................................................................................................................................done. 13:43:47 INFO - Installing pip.................done. 13:43:47 INFO - Return code: 0 13:43:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:43:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:43:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:43:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:43:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:43:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:43:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:43:47 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 0x106affa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b94618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd65959b3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a63d30>, '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 0x1067c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a5bb20>, '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.bHJdSwkhsq/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.9snY1JPPP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 13:43:47 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 13:43:47 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 13:43:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHJdSwkhsq/Render', 13:43:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:43:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:43:47 INFO - 'HOME': '/Users/cltbld', 13:43:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:43:47 INFO - 'LOGNAME': 'cltbld', 13:43:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:43:47 INFO - 'MOZ_NO_REMOTE': '1', 13:43:47 INFO - 'NO_EM_RESTART': '1', 13:43:47 INFO - 'PAGER': '/bin/cat', 13:43:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:43:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:43:47 INFO - 'PWD': '/builds/slave/test', 13:43:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:43:47 INFO - 'SHELL': '/bin/bash', 13:43:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9snY1JPPP9/Listeners', 13:43:47 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 13:43:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:43:47 INFO - 'USER': 'cltbld', 13:43:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:43:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:43:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:43:47 INFO - 'XPC_FLAGS': '0x0', 13:43:47 INFO - 'XPC_SERVICE_NAME': '0', 13:43:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:43:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:43:47 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 13:43:47 INFO - Cleaning up... 13:43:47 INFO - Return code: 0 13:43:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:43:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:43:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:43:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:43:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:43:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:43:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:43:47 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 0x106affa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b94618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd65959b3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a63d30>, '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 0x1067c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a5bb20>, '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.bHJdSwkhsq/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.9snY1JPPP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 13:43:47 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 13:43:47 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 13:43:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHJdSwkhsq/Render', 13:43:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:43:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:43:47 INFO - 'HOME': '/Users/cltbld', 13:43:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:43:47 INFO - 'LOGNAME': 'cltbld', 13:43:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:43:47 INFO - 'MOZ_NO_REMOTE': '1', 13:43:47 INFO - 'NO_EM_RESTART': '1', 13:43:47 INFO - 'PAGER': '/bin/cat', 13:43:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:43:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:43:47 INFO - 'PWD': '/builds/slave/test', 13:43:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:43:47 INFO - 'SHELL': '/bin/bash', 13:43:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9snY1JPPP9/Listeners', 13:43:47 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 13:43:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:43:47 INFO - 'USER': 'cltbld', 13:43:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:43:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:43:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:43:47 INFO - 'XPC_FLAGS': '0x0', 13:43:47 INFO - 'XPC_SERVICE_NAME': '0', 13:43:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:43:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:43:47 INFO - Downloading/unpacking psutil>=3.1.1 13:43:47 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 13:43:47 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 13:43:47 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 13:43:47 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 13:43:47 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 13:43:47 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 13:43:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:43:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:43:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:43:50 INFO - Installing collected packages: psutil 13:43:50 INFO - Running setup.py install for psutil 13:43:50 INFO - building 'psutil._psutil_osx' extension 13:43:50 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 13:43:50 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 13:43:50 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 13:43:51 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 13:43:51 INFO - building 'psutil._psutil_posix' extension 13:43:51 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 13:43:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:43:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:43:51 INFO - ^ 13:43:51 INFO - 1 warning generated. 13:43:51 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 13:43:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:43:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:43:51 INFO - Successfully installed psutil 13:43:51 INFO - Cleaning up... 13:43:51 INFO - Return code: 0 13:43:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:43:51 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')]} 13:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:43:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:43:51 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')]} 13:43:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:43:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:43:51 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 0x106affa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b94618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd65959b3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a63d30>, '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 0x1067c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a5bb20>, '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.bHJdSwkhsq/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.9snY1JPPP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 13:43:51 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 13:43:51 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 13:43:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHJdSwkhsq/Render', 13:43:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:43:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:43:51 INFO - 'HOME': '/Users/cltbld', 13:43:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:43:51 INFO - 'LOGNAME': 'cltbld', 13:43:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:43:51 INFO - 'MOZ_NO_REMOTE': '1', 13:43:51 INFO - 'NO_EM_RESTART': '1', 13:43:51 INFO - 'PAGER': '/bin/cat', 13:43:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:43:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:43:51 INFO - 'PWD': '/builds/slave/test', 13:43:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:43:51 INFO - 'SHELL': '/bin/bash', 13:43:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9snY1JPPP9/Listeners', 13:43:51 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 13:43:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:43:51 INFO - 'USER': 'cltbld', 13:43:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:43:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:43:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:43:51 INFO - 'XPC_FLAGS': '0x0', 13:43:51 INFO - 'XPC_SERVICE_NAME': '0', 13:43:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:43:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:43:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:43:51 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 13:43:51 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 13:43:51 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 13:43:51 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 13:43:51 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 13:43:51 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 13:43:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:43:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:43:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:43:54 INFO - Installing collected packages: mozsystemmonitor 13:43:54 INFO - Running setup.py install for mozsystemmonitor 13:43:54 INFO - Successfully installed mozsystemmonitor 13:43:54 INFO - Cleaning up... 13:43:54 INFO - Return code: 0 13:43:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:43:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:43:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:43:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:43:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:43:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:43:54 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 0x106affa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b94618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd65959b3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a63d30>, '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 0x1067c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a5bb20>, '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.bHJdSwkhsq/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.9snY1JPPP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 13:43:54 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 13:43:54 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 13:43:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHJdSwkhsq/Render', 13:43:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:43:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:43:54 INFO - 'HOME': '/Users/cltbld', 13:43:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:43:54 INFO - 'LOGNAME': 'cltbld', 13:43:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:43:54 INFO - 'MOZ_NO_REMOTE': '1', 13:43:54 INFO - 'NO_EM_RESTART': '1', 13:43:54 INFO - 'PAGER': '/bin/cat', 13:43:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:43:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:43:54 INFO - 'PWD': '/builds/slave/test', 13:43:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:43:54 INFO - 'SHELL': '/bin/bash', 13:43:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9snY1JPPP9/Listeners', 13:43:54 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 13:43:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:43:54 INFO - 'USER': 'cltbld', 13:43:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:43:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:43:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:43:54 INFO - 'XPC_FLAGS': '0x0', 13:43:54 INFO - 'XPC_SERVICE_NAME': '0', 13:43:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:43:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:43:54 INFO - Downloading/unpacking jsonschema==2.5.1 13:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:43:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:43:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:43:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:43:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:43:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:43:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:43:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:43:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:43:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:43:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:43:57 INFO - Downloading functools32-3.2.3-2.tar.gz 13:43:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:43:57 INFO - warning: no files found matching '*.txt' 13:43:57 INFO - no previously-included directories found matching 'build' 13:43:57 INFO - no previously-included directories found matching 'dist' 13:43:57 INFO - no previously-included directories found matching '.git*' 13:43:57 INFO - Installing collected packages: jsonschema, functools32 13:43:57 INFO - Running setup.py install for functools32 13:43:57 INFO - warning: no files found matching '*.txt' 13:43:57 INFO - no previously-included directories found matching 'build' 13:43:57 INFO - no previously-included directories found matching 'dist' 13:43:57 INFO - no previously-included directories found matching '.git*' 13:43:57 INFO - Successfully installed jsonschema functools32 13:43:57 INFO - Cleaning up... 13:43:57 INFO - Return code: 0 13:43:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:43:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:43:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:43:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:43:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:43:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:43:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106affa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b94618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd65959b3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a63d30>, '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 0x1067c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a5bb20>, '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.bHJdSwkhsq/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.9snY1JPPP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 13:43:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:43:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:43:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHJdSwkhsq/Render', 13:43:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:43:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:43:57 INFO - 'HOME': '/Users/cltbld', 13:43:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:43:57 INFO - 'LOGNAME': 'cltbld', 13:43:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:43:57 INFO - 'MOZ_NO_REMOTE': '1', 13:43:57 INFO - 'NO_EM_RESTART': '1', 13:43:57 INFO - 'PAGER': '/bin/cat', 13:43:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:43:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:43:57 INFO - 'PWD': '/builds/slave/test', 13:43:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:43:57 INFO - 'SHELL': '/bin/bash', 13:43:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9snY1JPPP9/Listeners', 13:43:57 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 13:43:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:43:57 INFO - 'USER': 'cltbld', 13:43:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:43:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:43:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:43:57 INFO - 'XPC_FLAGS': '0x0', 13:43:57 INFO - 'XPC_SERVICE_NAME': '0', 13:43:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:43:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:43:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:43:58 INFO - Cleaning up... 13:43:58 INFO - Return code: 0 13:43:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:43:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:43:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:43:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:43:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:43:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:43:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106affa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b94618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd65959b3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a63d30>, '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 0x1067c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a5bb20>, '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.bHJdSwkhsq/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.9snY1JPPP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 13:43:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:43:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:43:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHJdSwkhsq/Render', 13:43:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:43:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:43:58 INFO - 'HOME': '/Users/cltbld', 13:43:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:43:58 INFO - 'LOGNAME': 'cltbld', 13:43:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:43:58 INFO - 'MOZ_NO_REMOTE': '1', 13:43:58 INFO - 'NO_EM_RESTART': '1', 13:43:58 INFO - 'PAGER': '/bin/cat', 13:43:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:43:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:43:58 INFO - 'PWD': '/builds/slave/test', 13:43:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:43:58 INFO - 'SHELL': '/bin/bash', 13:43:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9snY1JPPP9/Listeners', 13:43:58 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 13:43:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:43:58 INFO - 'USER': 'cltbld', 13:43:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:43:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:43:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:43:58 INFO - 'XPC_FLAGS': '0x0', 13:43:58 INFO - 'XPC_SERVICE_NAME': '0', 13:43:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:43:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:43:58 INFO - Downloading/unpacking blobuploader==1.2.4 13:43:58 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 13:43:58 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 13:43:58 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 13:43:58 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 13:43:58 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 13:43:58 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 13:44:00 INFO - Downloading blobuploader-1.2.4.tar.gz 13:44:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:44:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:44:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:44:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:44:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:44:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:44:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:44:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:44:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:44:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:44:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:44:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:44:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:44:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:44:01 INFO - Downloading docopt-0.6.1.tar.gz 13:44:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:44:02 INFO - Installing collected packages: blobuploader, requests, docopt 13:44:02 INFO - Running setup.py install for blobuploader 13:44:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:44:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:44:02 INFO - Running setup.py install for requests 13:44:02 INFO - Running setup.py install for docopt 13:44:02 INFO - Successfully installed blobuploader requests docopt 13:44:02 INFO - Cleaning up... 13:44:02 INFO - Return code: 0 13:44:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:44:02 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')]} 13:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:02 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')]} 13:44:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:02 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 0x106affa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b94618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd65959b3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a63d30>, '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 0x1067c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a5bb20>, '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.bHJdSwkhsq/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.9snY1JPPP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 13:44:02 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 13:44:02 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 13:44:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHJdSwkhsq/Render', 13:44:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:02 INFO - 'HOME': '/Users/cltbld', 13:44:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:02 INFO - 'LOGNAME': 'cltbld', 13:44:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:02 INFO - 'MOZ_NO_REMOTE': '1', 13:44:02 INFO - 'NO_EM_RESTART': '1', 13:44:02 INFO - 'PAGER': '/bin/cat', 13:44:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:02 INFO - 'PWD': '/builds/slave/test', 13:44:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:02 INFO - 'SHELL': '/bin/bash', 13:44:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9snY1JPPP9/Listeners', 13:44:02 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 13:44:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:02 INFO - 'USER': 'cltbld', 13:44:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:02 INFO - 'XPC_FLAGS': '0x0', 13:44:02 INFO - 'XPC_SERVICE_NAME': '0', 13:44:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:44:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-zzyzx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:44:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-7J4_Qj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:44:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-CMi6BK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:44:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-wYxV4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:44:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-1k_37f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:44:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-241OLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:44:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-mTZNod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:44:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-zJrds8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:44:04 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-mPlx65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:44:04 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-2uyDKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:44:04 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-JplGL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:44:04 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-HYp0wR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:44:04 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-IWqidv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:44:04 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-fSAFrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:44:04 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-x0bgb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:44:04 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-6eEWIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:44:04 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-XmaZdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:44:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:44:05 INFO - Running setup.py install for manifestparser 13:44:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:44:05 INFO - Running setup.py install for mozcrash 13:44:05 INFO - Running setup.py install for mozdebug 13:44:05 INFO - Running setup.py install for mozdevice 13:44:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:44:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:44:05 INFO - Running setup.py install for mozfile 13:44:05 INFO - Running setup.py install for mozhttpd 13:44:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:44:05 INFO - Running setup.py install for mozinfo 13:44:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:44:06 INFO - Running setup.py install for mozInstall 13:44:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:44:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:44:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:44:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:44:06 INFO - Running setup.py install for mozleak 13:44:06 INFO - Running setup.py install for mozlog 13:44:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:44:06 INFO - Running setup.py install for moznetwork 13:44:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:44:06 INFO - Running setup.py install for mozprocess 13:44:06 INFO - Running setup.py install for mozprofile 13:44:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:44:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:44:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:44:06 INFO - Running setup.py install for mozrunner 13:44:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:44:07 INFO - Running setup.py install for mozscreenshot 13:44:07 INFO - Running setup.py install for moztest 13:44:07 INFO - Running setup.py install for mozversion 13:44:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:44:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:44:07 INFO - Cleaning up... 13:44:07 INFO - Return code: 0 13:44:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:44:07 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')]} 13:44:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:07 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')]} 13:44:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:07 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 0x106affa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b94618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd65959b3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a63d30>, '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 0x1067c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a5bb20>, '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.bHJdSwkhsq/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.9snY1JPPP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 13:44:07 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 13:44:07 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 13:44:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHJdSwkhsq/Render', 13:44:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:07 INFO - 'HOME': '/Users/cltbld', 13:44:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:07 INFO - 'LOGNAME': 'cltbld', 13:44:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:07 INFO - 'MOZ_NO_REMOTE': '1', 13:44:07 INFO - 'NO_EM_RESTART': '1', 13:44:07 INFO - 'PAGER': '/bin/cat', 13:44:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:07 INFO - 'PWD': '/builds/slave/test', 13:44:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:07 INFO - 'SHELL': '/bin/bash', 13:44:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9snY1JPPP9/Listeners', 13:44:07 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 13:44:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:07 INFO - 'USER': 'cltbld', 13:44:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:07 INFO - 'XPC_FLAGS': '0x0', 13:44:07 INFO - 'XPC_SERVICE_NAME': '0', 13:44:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:44:07 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-GAOJWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:44:08 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)) 13:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:44:08 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-meSxAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:44:08 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)) 13:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:44:08 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-8nwfl2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:44:08 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)) 13:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:44:08 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-DIUchw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:44:08 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)) 13:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:44:08 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-koCdbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:44:08 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)) 13:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:44:08 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-nq5v7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:44:08 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)) 13:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:44:08 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-lYPSzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:44:08 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)) 13:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:44:08 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-M2zg1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:44:08 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)) 13:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:44:08 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-ZawevR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:44:08 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)) 13:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:44:08 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-cLBj3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:44:08 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)) 13:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:44:08 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-tpyI1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:44:09 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)) 13:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:44:09 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Ls2nOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:44:09 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)) 13:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:44:09 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-V6VaRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:44:09 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)) 13:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:44:09 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-YTk443-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:44:09 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)) 13:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:44:09 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-MOyH9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:44:09 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)) 13:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:44:09 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-qyjwYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:44:09 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)) 13:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:44:09 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-BVWwiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:44:09 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)) 13:44:09 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)) 13:44:09 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)) 13:44:09 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)) 13:44:09 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)) 13:44:09 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)) 13:44:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:44:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:44:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:44:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:44:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:44:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:44:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:44:12 INFO - Downloading blessings-1.6.tar.gz 13:44:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:44:12 INFO - Installing collected packages: blessings 13:44:12 INFO - Running setup.py install for blessings 13:44:12 INFO - Successfully installed blessings 13:44:12 INFO - Cleaning up... 13:44:12 INFO - Return code: 0 13:44:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:44:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:44:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:44:12 INFO - Reading from file tmpfile_stdout 13:44:12 INFO - Current package versions: 13:44:12 INFO - blessings == 1.6 13:44:12 INFO - blobuploader == 1.2.4 13:44:12 INFO - docopt == 0.6.1 13:44:12 INFO - functools32 == 3.2.3-2 13:44:12 INFO - jsonschema == 2.5.1 13:44:12 INFO - manifestparser == 1.1 13:44:12 INFO - mozInstall == 1.12 13:44:12 INFO - mozcrash == 0.17 13:44:12 INFO - mozdebug == 0.1 13:44:12 INFO - mozdevice == 0.48 13:44:12 INFO - mozfile == 1.2 13:44:12 INFO - mozhttpd == 0.7 13:44:12 INFO - mozinfo == 0.9 13:44:12 INFO - mozleak == 0.1 13:44:12 INFO - mozlog == 3.3 13:44:12 INFO - moznetwork == 0.27 13:44:12 INFO - mozprocess == 0.23 13:44:12 INFO - mozprofile == 0.28 13:44:12 INFO - mozrunner == 6.12 13:44:12 INFO - mozscreenshot == 0.1 13:44:12 INFO - mozsystemmonitor == 0.3 13:44:12 INFO - moztest == 0.7 13:44:12 INFO - mozversion == 1.4 13:44:12 INFO - psutil == 3.1.1 13:44:12 INFO - requests == 1.2.3 13:44:12 INFO - wsgiref == 0.1.2 13:44:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:44:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:13 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 0x106affa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b94618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd65959b3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a63d30>, '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 0x1067c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a5bb20>, '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.bHJdSwkhsq/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.9snY1JPPP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 13:44:13 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 13:44:13 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 13:44:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHJdSwkhsq/Render', 13:44:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:13 INFO - 'HOME': '/Users/cltbld', 13:44:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:13 INFO - 'LOGNAME': 'cltbld', 13:44:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:13 INFO - 'MOZ_NO_REMOTE': '1', 13:44:13 INFO - 'NO_EM_RESTART': '1', 13:44:13 INFO - 'PAGER': '/bin/cat', 13:44:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:13 INFO - 'PWD': '/builds/slave/test', 13:44:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:13 INFO - 'SHELL': '/bin/bash', 13:44:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9snY1JPPP9/Listeners', 13:44:13 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 13:44:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:13 INFO - 'USER': 'cltbld', 13:44:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:13 INFO - 'XPC_FLAGS': '0x0', 13:44:13 INFO - 'XPC_SERVICE_NAME': '0', 13:44:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:13 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)) 13:44:13 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)) 13:44:13 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)) 13:44:13 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)) 13:44:13 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)) 13:44:13 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)) 13:44:13 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)) 13:44:13 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)) 13:44:13 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)) 13:44:13 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)) 13:44:13 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)) 13:44:13 INFO - Cleaning up... 13:44:13 INFO - Return code: 0 13:44:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:44:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106affa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b94618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd65959b3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a63d30>, '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 0x1067c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a5bb20>, '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.bHJdSwkhsq/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.9snY1JPPP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 13:44:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:44:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:44:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHJdSwkhsq/Render', 13:44:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:13 INFO - 'HOME': '/Users/cltbld', 13:44:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:13 INFO - 'LOGNAME': 'cltbld', 13:44:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:13 INFO - 'MOZ_NO_REMOTE': '1', 13:44:13 INFO - 'NO_EM_RESTART': '1', 13:44:13 INFO - 'PAGER': '/bin/cat', 13:44:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:13 INFO - 'PWD': '/builds/slave/test', 13:44:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:13 INFO - 'SHELL': '/bin/bash', 13:44:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9snY1JPPP9/Listeners', 13:44:13 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 13:44:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:13 INFO - 'USER': 'cltbld', 13:44:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:13 INFO - 'XPC_FLAGS': '0x0', 13:44:13 INFO - 'XPC_SERVICE_NAME': '0', 13:44:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:44:13 INFO - Cleaning up... 13:44:13 INFO - Return code: 0 13:44:13 INFO - Running post-action listener: _resource_record_post_action 13:44:13 INFO - Running post-action listener: _start_resource_monitoring 13:44:13 INFO - Starting resource monitoring. 13:44:13 INFO - [mozharness: 2016-09-07 20:44:13.714961Z] Finished create-virtualenv step (success) 13:44:13 INFO - [mozharness: 2016-09-07 20:44:13.715927Z] Running install step. 13:44:13 INFO - Running pre-action listener: _resource_record_pre_action 13:44:13 INFO - Running main action method: install 13:44:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:44:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:44:13 INFO - Reading from file tmpfile_stdout 13:44:13 INFO - Detecting whether we're running mozinstall >=1.0... 13:44:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:44:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:44:14 INFO - Reading from file tmpfile_stdout 13:44:14 INFO - Output received: 13:44:14 INFO - Usage: mozinstall [options] installer 13:44:14 INFO - Options: 13:44:14 INFO - -h, --help show this help message and exit 13:44:14 INFO - -d DEST, --destination=DEST 13:44:14 INFO - Directory to install application into. [default: 13:44:14 INFO - "/builds/slave/test"] 13:44:14 INFO - --app=APP Application being installed. [default: firefox] 13:44:14 INFO - mkdir: /builds/slave/test/build/application 13:44:14 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'] 13:44:14 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 13:44:48 INFO - Reading from file tmpfile_stdout 13:44:48 INFO - Output received: 13:44:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:44:48 INFO - Running post-action listener: _resource_record_post_action 13:44:48 INFO - [mozharness: 2016-09-07 20:44:48.238904Z] Finished install step (success) 13:44:48 INFO - [mozharness: 2016-09-07 20:44:48.239044Z] Running run-tests step. 13:44:48 INFO - Running pre-action listener: _resource_record_pre_action 13:44:48 INFO - Running main action method: run_tests 13:44:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:44:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:44:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:44:48 INFO - 2016-09-07 13:44:48.253 screenresolution[1943:12224] starting screenresolution argv=screenresolution get 13:44:48 INFO - 2016-09-07 13:44:48.256 screenresolution[1943:12224] Display 0: 1600x1200x32@60 13:44:48 INFO - 2016-09-07 13:44:48.261 screenresolution[1944:12226] starting screenresolution argv=screenresolution list 13:44:48 INFO - Available Modes on Display 0 13:44:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:44:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:44:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:44:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:44:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:44:48 INFO - Intel Iris: 13:44:48 INFO - Chipset Model: Intel Iris 13:44:48 INFO - Type: GPU 13:44:48 INFO - Bus: Built-In 13:44:48 INFO - VRAM (Dynamic, Max): 1536 MB 13:44:48 INFO - Vendor: Intel (0x8086) 13:44:48 INFO - Device ID: 0x0a2e 13:44:48 INFO - Revision ID: 0x0009 13:44:48 INFO - Displays: 13:44:48 INFO - Display: 13:44:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:44:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:44:48 INFO - Display Serial Number: 1600x1200 60 13:44:48 INFO - Main Display: Yes 13:44:48 INFO - Mirror: Off 13:44:48 INFO - Online: Yes 13:44:48 INFO - Rotation: Supported 13:44:48 INFO - Return code: 0 13:44:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:44:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:44:48 INFO - Python 2.7.3 13:44:48 INFO - Return code: 0 13:44:48 INFO - grabbing minidump binary from tooltool 13:44:48 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')]} 13:44:48 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 0x106a63d30>, '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 0x1067c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a5bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:44:48 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 13:44:48 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 13:44:48 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 13:44:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:44:48 INFO - Return code: 0 13:44:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:44:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:44:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:44:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:44:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:44:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MXceWftITIuqnG98ghoOMw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:44:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MXceWftITIuqnG98ghoOMw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0236 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:44:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHJdSwkhsq/Render', 13:44:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:48 INFO - 'HOME': '/Users/cltbld', 13:44:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:48 INFO - 'LOGNAME': 'cltbld', 13:44:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:44:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:48 INFO - 'MOZ_NO_REMOTE': '1', 13:44:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:44:48 INFO - 'NO_EM_RESTART': '1', 13:44:48 INFO - 'PAGER': '/bin/cat', 13:44:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:48 INFO - 'PWD': '/builds/slave/test', 13:44:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:44:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:48 INFO - 'SHELL': '/bin/bash', 13:44:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9snY1JPPP9/Listeners', 13:44:48 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 13:44:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:48 INFO - 'USER': 'cltbld', 13:44:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:48 INFO - 'XPC_FLAGS': '0x0', 13:44:48 INFO - 'XPC_SERVICE_NAME': '0', 13:44:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MXceWftITIuqnG98ghoOMw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:44:48 INFO - mozversion application_buildid: 20160907124615 13:44:48 INFO - mozversion application_changeset: f46da98e6eddfc6df2b5e0aa565cc47fd752c0a7 13:44:48 INFO - mozversion application_display_name: Nightly 13:44:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:44:48 INFO - mozversion application_name: Firefox 13:44:48 INFO - mozversion application_remotingname: firefox 13:44:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 13:44:48 INFO - mozversion application_vendor: Mozilla 13:44:48 INFO - mozversion application_version: 51.0a1 13:44:48 INFO - mozversion platform_buildid: 20160907124615 13:44:48 INFO - mozversion platform_changeset: f46da98e6eddfc6df2b5e0aa565cc47fd752c0a7 13:44:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 13:44:48 INFO - mozversion platform_version: 51.0a1 13:44:48 INFO - using testdate: 1473281088 13:44:48 INFO - actual date: 1473281088 13:44:48 INFO - starting webserver on 'localhost:51800' 13:44:48 INFO - SUITE-START | Running 2 tests 13:44:48 INFO - TEST-START | dromaeo_css 13:44:48 INFO - operating with platform_type : mac_ 13:44:48 INFO - Initialising browser for dromaeo_css test... 13:44:48 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpdHjAPm/profile http://localhost:51800/getInfo.html) 13:44:51 INFO - PROCESS | 1954 | __metrics Screen width/height:1600/1200 13:44:51 INFO - PROCESS | 1954 | colorDepth:24 13:44:51 INFO - PROCESS | 1954 | Browser inner width/height: 1024/689 13:44:51 INFO - PROCESS | 1954 | __metrics 13:44:51 INFO - PROCESS | 1954 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:44:51 INFO - PROCESS | 1954 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:44:51 INFO - TEST-INFO | 1954: exit 0 13:44:51 INFO - Browser initialized. 13:44:51 INFO - Running cycle 1/1 for dromaeo_css test... 13:44:51 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpdHjAPm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:45:03 INFO - PROCESS | 1956 | RSS: Main: 232701952 13:45:03 INFO - PROCESS | 1956 | 13:45:55 INFO - PROCESS | 1956 | 1473281155010 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] 13:48:07 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:51800/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51800/tests/dromaeo/cssquery-ext.html) 13:48:09 INFO - PROCESS | 1956 | RSS: Main: 359022592 13:48:09 INFO - PROCESS | 1956 | 13:51:12 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:51800/tests/dromaeo/cssquery-ext.html (next: http://localhost:51800/tests/dromaeo/cssquery-jquery.html) 13:51:12 INFO - PROCESS | 1956 | RSS: Main: 369287168 13:51:12 INFO - PROCESS | 1956 | 13:54:15 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:51800/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51800/tests/dromaeo/cssquery-mootools.html) 13:54:15 INFO - PROCESS | 1956 | RSS: Main: 338821120 13:54:15 INFO - PROCESS | 1956 | 13:57:18 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:51800/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51800/tests/dromaeo/cssquery-prototype.html) 13:57:18 INFO - PROCESS | 1956 | RSS: Main: 339566592 13:57:18 INFO - PROCESS | 1956 | 14:00:22 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:51800/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51800/tests/dromaeo/cssquery-yui.html) 14:00:22 INFO - PROCESS | 1956 | RSS: Main: 331284480 14:00:22 INFO - PROCESS | 1956 | 14:03:25 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:51800/tests/dromaeo/cssquery-yui.html (next: http://localhost:51800/tests/dromaeo/cssquery-dojo.html) 14:03:26 INFO - PROCESS | 1956 | RSS: Main: 474378240 14:03:26 INFO - PROCESS | 1956 | 14:03:26 INFO - PROCESS | 1956 | __start_tp_report 14:03:26 INFO - PROCESS | 1956 | _x_x_mozilla_page_load 14:03:26 INFO - PROCESS | 1956 | _x_x_mozilla_page_load_details 14:03:26 INFO - PROCESS | 1956 | |i|pagename|runs| 14:03:26 INFO - PROCESS | 1956 | |0;dojo.html;3257.0717345556513;3309.5697559317287;3309.6855798699125;3336.4494858348376;3375.1055970167895;5627.127795191742;5656.745446454909;5716.685582292972;5721.485066344028;5730.484256416927;5688.886222275552;5709.086546152616;5763.827085187451;5801.883962320751;5820.53435725142;5131.512506311894;5191.766370513317;5219.71291578963;5220.582353411718;5272.437326043245;5333.466653334654;5333.626646134772;5335.359756829186;5338.599605029638;5369;5146.6911985281;5174.353205849269;5192.792288308463;5230.927659829745;5258.447862974371;5222.477752224785;5246.842594722164;5247.1866860636665;5252.711100889488;5259.211118332242;5144;5188.377394712621;5196.246544251062;5207.583393328562;5209.609279304067;5638.887222255573;5641.407652196502;5726.656400615976;5728.42715728428;5774.162746401748;6207.937920620826;6275.215598050244;6279.403456671609;6281;6292.874142517124;6122.886104953791;6278.089676996856;6319.6524191169465;6323.74705011799;6343.746250149989;5757.165211044375;5763.106718458687;5800.129980502874;5819.418058194146;5870.618409803349;6255.655938923312;6313.494920406357;6314.873702525923;6317.399847014526;6343.651099189497;5951.196588460589;5996.370381110008;6143.201383820075;6167.290761562388;6176.289726681489;6151.507879369639;6198.039303907902;6213.565050446516;6271.905921411182;6280;5217.34783152106;6231.532635052298;6237.157983672237;6239.656818874914;6249.375062493714;6020.096985452217;6195.721192546346;6214.378562143749;6243.094751261133;6260.060990851409;4893.266010098442;4906.460289368167;4936.333594964705;4937.432195297515;4941.555260026543;4423.51341352451;4456.68803183774;4463.196624607537;4494.797734101973;4558.612517936012;5688.77244910199;5695.60130790849;5698.857528561819;5701.600887937804;5703.600747947604;6111.7436766921655;6252.874942501125;6307.211598550181;6320.5891617044745;6323.399277068763;5662.065759149777;5684.573656975744;5712.914306285409;5722.513586345124;5769.07694768835;5398.217258497496;5452.40023597404;5454.91817622736;5456.699881506555;5469.835904922858;6210.5031597473;6320.273168585672;6329.90505142414;6337.271213810286;6358.205224346957;6217.098520714561;6251.124842522141;6265.122882796503;6344.9048264277;6353.4599559038;6327.588706734047;6328.335524769833;6340.651264180515;6341.682915854281;6352.110704501281;5974.790882319098;6230.532710046855;6232.127502149793;6238.313785483684;6253.437190652863;6191.318954914866;6235.033569796688;6235.719392627252;6349.206349206349;6350.237971443456;6080.969595151996;6153.138560601609;6157.753689852356;6164.691765411621;6173.59871608344;5955.255593050869;6112.602680825732;6135.509159267337;6137.478314343331;6161.199044124235;5908.881822363443;5919.822405327847;5957.374475680164;6009;6028.57799953999;6131.141640170469;6135.938640613536;6146.139540464428;6150.538709596887;6163.3528479509005;5808.419158084158;5948.940510594839;5954.404559544011;5955.612885162451;5962.731677074629 14:03:26 INFO - PROCESS | 1956 | |1;ext.html;478.53342990584207;501.52605787530797;513.82016294297;520.469121496074;525.9763310651023;30585.69414305856;40250;41233.7938310308;41327.79336103319;41379.58620413795;41437.37843932343;41881.58118418815;41889.58110418895;42023.78988105063;42074;8305.833883322344;8720.825583488326;9822.656207032755;9827.017298270188;9837.754056148618;8692.739217823475;9710.805783884318;11869.7626047479;11872.881271187307;12224.205426647284;11868.7626247475;11935.985441237504;11952;11956.342401167933;11988.100892433058;11150;11192.496337664743;11960;12034.69913252167;12066.33635150062;11541.30752154865;11738.119641026957;11760.588379406765;11765.529378824836;11768.764624707543;39616.405753913714;39999.40000899989;40023.99940001494;40062.198756025005;40092.79953600242;3348.09601407621;3382.5433566468705;3490.9825450872836;3585.964140358615;3682.318771027342;25538.106166284277;30349.393012139633;31506.684933150606;31667.683323166933;31872.6812731872;2623.6589243398425;3031.9848400758074;3074.5388191771412;3154.4006638738565;3160.573322601442;499715;502144.4892775549;502943;503102;504182;33482.66517334844;33589.3282134356;33719.66280337214;60939.08591371133;61997;34659.653403466145;35147.4727879082;35150.29699405997;35216.64783352134;35230;14436.350364233635;14574.562763117123;14588.270586470633;14591.124532528082;14594.781078283835;11187.4965626547;11406.828897566544;12145.392730363446;12302.569410070604;12431.31627760482;7518.060242469692;7754.108277548042;7841.4118941078505;7847.411444141712;7869.567173805382;3160.241542029896;3205.2788122672214;3226.9031929042167;3229.4187046331417;3252.495863141217;884.5798245833178;917.5779141594941;918.7794929217074;986.7483791633088;1027.897210278981;28578.857105714338;29577.55633665497;29711.702882970894;30380.392392152033;30645.540316895265;1070.3363914373138;1079.7246702090918;1104.2601457023814;1111.6553868300691;1127.1490025031048;12598.748025039447;14467.710645787236;15164.696706065817;15170.73829126721;15558.533244002698;636146.8192659008;640178;640681;641457;646230;55701;56344;56350.154747677996;56435.50991636014;56460.435395645516;1193.9791478286113;1310.698539335939;1319.808627748971;1332.580237225271;1333.8799508044322;29604.555931660612;30385.696143038287;30668.539971899994;31088.067357979297;31275.061748147593;47470.287945681535;48022;48077.03845923132;48199.75900120477;48263.75868120637;448214;449572;449766;449967;450329;22191.22330718417;23285.924963131045;26950.517721525503;27955.440891181657;28086.157415277576;46192.30711539262;46266;46920.7653961728;47280.76359618318;47814;34816.651833481345;35223.37027428296;37267.06832329214;38254.808725956194;38654.80672596619;32155.678443215267;33982.150446239044;37760.244795104496;38058.91057430798;39137.80431097826;36274;37217.25565488729;37437;37694;37964;26956.837791950682;30559.847200763856;33467.33065338728;33729.988100357026;33740;23184.652230216223;24910.501789964463;25108;25146.62280065764;25401.61897571501 14:03:26 INFO - PROCESS | 1956 | |2;jquery.html;298.19041302862723;371.1778410820033;374.6571886723648;382.3271042964383;403.4936155125319;15076.47232346868;17659.381921632747;21227.787722122775;21595.784042159572;21782;20671.793282067174;21199.68200476994;21467.03398347074;21562.460938476506;21669.45826354342;5820.999999999989;5842.152887831262;5924.140999555062;5931.466168044875;6098.237720284965;5944;6048.0625503047095;6059.030555111183;6065.575409721299;6074.757009719606;4156.5012198536215;6218.160548325986;6584.341565843425;6635.535512514126;6726.865462690744;6410.519211059142;6679.332066793282;6702.698378572976;6709;6752.358525940028;5840.445157710011;5855.063189889597;5855.76576936922;5856.619319744203;5857.26784151981;25579.97680092794;26398.472030559475;26484.205473835817;26620.06829760949;26633.201003969913;2477.206605218907;2772.9029483968166;2984.4329577380227;3028.439738648357;3115.7040081192476;15618.609534761608;16245.675086498322;16297.022178669318;16319.347226110942;16330.591735206595;1652.7686123942658;1824.1791193962638;1929.411529483507;1950.6683863743056;2013.4496437743003;128191;130442;130569;133658.3317083418;136348;10017.549210285555;10056.195504359634;11169.553217871195;11220.831687524693;11352.886471135183;14170.433182672783;14195.361208745631;14196.503122390666;14208.644783880485;14215.715685686228;6880.65596720172;8390.454620449773;8616;8755.868661970075;8768.210861022411;8581.313494920392;8587;8598.312135029182;8780.078091800397;8800.647974080965;6052.577644253597;6405.6797160141805;6672.466202703772;6825.624590647562;6933.0293758873795;1777.7155655095123;1818.6999145140903;1828.469743774317;1836.6051298970785;1843.4008947092248;883.6332921837366;905.8233644439271;951.3150531617224;1007.1539906478594;1071.8177909755361;15211.619709507351;21766.564668706855;22666.66000010001;22674.319770406913;22683;922.7600823785864;925.3753716241511;945.9101385368378;977.2182254196756;987.7975015121883;9580.700631848305;9605.519724013771;10465.063963166838;10702.555155053968;12048.75902481963;548559;555412;559380;559815;569669;73261.26738732652;116321;117665.23502147474;127223;127345;852.3607294528979;1025.0927928782933;1070.743021674808;1073.1629329123296;1074.5057273654206;17206.010972409895;18465.16906739173;19311.903440482707;19697.901510492356;20018.89990550038;53859.461405385446;55165;55889;57625.13562296485;58410;54062.45937540574;82358.58820705858;84999.72500412376;85391.71912421443;85664.57167714121;7920.168382320004;8118.350531957311;8128.268455839003;8171.387145964195;8409.369297302605;11891.821622675494;12878.549250776177;13090.541831035867;13256;13513.864861351654;9830.705078847646;10289.228307876789;10452.857164281151;11791.056715462571;11926.045916326499;10423.739406514595;10881.945590271998;11210.27133236337;11589.072874170215;11979.640610781691;11473.36896470702;11550.59572914944;11854.11094167958;12059.57791477294;12138.453769580214;7978.4813987092975;8206.548639824867;8254.958725206576;8284.212999765012;8382.790430239293;10457.895421045692;11215.775684486427;11412.201145919706;11662.416879156179;11711.707207319885 14:03:26 INFO - PROCESS | 1956 | |3;mootools.html;1257.924524528529;1491.8582734640213;1545.590418539087;1718.4243278501701;1833.94498165055;7701.961490192548;8611.698590549331;8832.425892317011;8832.690855820056;8897.68858089967;8574.571271436434;8666.956665216681;8679.001914779803;8689;8689.391742578022;2372.6678265042906;2406.6750988616577;2409.012304954969;2409.855408675476;2410.963835542468;2048.836093112555;2353.188150088217;2389.880505974703;2397.3646983549374;2404.5311164322966;2347.3310106619615;2368.822338324624;2376.762323767618;2384.9642255366184;2389.1996181279205;2326.0579465316573;2351.318117745852;2354.2937118864274;2359.563480756057;2366.420227044368;2309.099451214028;2349.0721165139844;2349.1543044504137;2349.4596242864236;2352.447174913894;8612.440191387539;8686.782830429227;8799.384043116985;8808.955955220246;8811;5185.974070129625;5367.946320536784;5372.838814835562;5373.704446255454;5384.326959130109;3630.9273814523563;3662.212624285765;3702.333579955607;3722.4602432647384;3723.7579557328686;1073.1575713065283;1086.1365214654368;1088.2708585247904;1111.699841042922;1114;11463.484143213574;11513.963743263146;11590.652280431515;11601.245919015322;11610.24533405326;4309.159713855768;4408.801603927767;4562.771861406916;4563.133004729091;4567.862964111082;9035.232005279624;9042.457452552868;9043.276537876955;9044.050374710692;9046.502442365732;5171.689698618095;5172.689638621695;5177.171652535651;5177.715225662625;5184.870378240575;4269.338252570857;4344.761038142869;4412.558744125626;4575.153596584642;4862.100511405401;3692.6491983262085;3717.4052151655605;3753.662170404622;3794.886153415402;3797.9430308545393;1161.5469966712988;1163.5752950173296;1166.4284500594647;1169.602335206034;1172.5954545681727;638.2341190571333;824.431142511677;895.3821862914568;906.1482206725789;933.3186773655269;5109.9744501277255;5297.947020529822;5474.753636086386;5726.541876649858;5848.736806843703;875.6847534887436;883;908.3732224764891;914.5244472874068;952.7189479103681;3810.7713537187856;3816.2558301131567;3858.8070596470066;3939.5075615548058;3960.2673505401594;10367.118794902366;10829.566817327219;10853.294535855144;10864.293820901616;10889.945550272198;10780.407077610807;10796.94601527003;10805.945970270099;10809.83785243222;10813.56745730162;7732.458727888994;8081.676732930617;8104.51372917615;8109.959450202711;8191.95904020488;5865.208196893535;5870.972579798604;5894.2926848778425;6061.060535616986;6071.026916252147;9935.403875767477;10034.1972642187;10072.446015468926;10085.798284034425;10087.142592879394;9279.76800579991;9697.757556061155;10087.798244035224;10191.980801919748;10297.794044118926;6639.966800165968;7431.071116110486;7512.661930213263;7531.88702169457;7540.472166948261;8020.238077382639;8099.959500202697;8121.147279535564;8171.346292296483;8208;7368.189499154982;7381.151167615578;7449.664765085476;7450.962745186456;7498.700051997859;7140.428765698835;7243.637818108968;7279;7345.3021962913435;7397.963010184914;5844.649321040752;6807.455403567604;6932.44540436774;6945.930540694529;6952.443804495527;4095.5290141634096;4816.566509014206;4983.651144419856;4989.925151122663;5009.824656137018;4895.632827537877;4941.728204948762;5049.924251136309;5094.388673359221;5196 14:03:26 INFO - PROCESS | 1956 | |4;prototype.html;266.32220997561205;298.9491786396311;308.9397567474342;311.38967623458024;311.54669955215167;4158.625723684868;4996.52533009364;5278.287431196787;5436.565074794017;5437.918431223535;5104.983531505062;5176.27532145499;5241.554467870235;5258.237555554442;5356.946430535693;3548.3790336691104;4052.9797351013203;4085.836566537335;4107.219628270619;4145.523264824538;3984.7409918355215;4120.237756015132;4135.875923722293;4150.232207041692;4167.562405947378;4042.676585873138;4082.9996650820444;4089.6932730045364;4091.3249313863175;4103.774292413916;3402.2004828865197;4039.697022723293;4040.969552764041;4096.365063415175;4115.053537686349;3003.8798448062053;3009.1423944175804;3533.876314329012;3579;3589.569251689788;4126.988887722498;5182.870428239286;5248.790048398059;5283.28675628789;5295.920561191585;3522.3131489359585;3785.9621403785886;4581.977090114528;4720.386349774507;4729.503402142795;5076.82231121909;5123.359580052494;5584.329880414376;5624.943750562483;5652.806164517076;3568.232829941575;3719.776813391178;3895.552011518655;3934.488516492838;3965.4250133730447;5907.675077870673;5910.320313163998;5916.585838991272;5920.644761314335;5931.466168044896;4012.374988129812;4021;4025.6376926076796;4028.576999415078;4048.817803198863;3906.4921560197563;3993.9216411568714;4034.11249525104;4077.6126268005087;4087.734297270668;3839.6544311012144;4610.677252592286;4614.76926153691;4629.800844598856;4680.882977925579;5684.857878553069;5711.200431939532;5727.971360143172;5734.971325143348;5762.394948530427;1282.0833104330447;1283.4011127466954;1303.4004357995238;1357.260293140241;1365.2968721108637;820.5445977482435;823.5470491229919;844.9619767110372;855.9786005349916;857.5712143928037;3927.8953493599256;4179.456670632799;4195.937060944088;4395.340698895191;4417.712848664826;4438.844640437569;4702.741349225757;4704.294355846582;4723.5984941279685;4784.679430476216;4266.637335826489;4268.911427585946;4281.324172392255;4324.880808403922;4400.603945644907;1971.142552989454;2396.3410062233024;2531.139412599725;2531.797456203499;2569.7044839843284;4600.60894823937;4911.263310503382;4950.108980383494;4980.2778597103215;5024.668462857346;4201.332154082119;4351.151525452569;4541.729954432803;4716.9292460613115;4839.879003024953;3943.881683549498;4020.1053644944177;4650.395613513265;4847.3698419206685;4907.214845624754;4568.862934111982;4576.414325905853;4652.023075154256;4700.952990470051;4717.481077081587;4482.148391805546;4484.75333856641;4707.8587642370785;4733.650909490779;4747.525247475363;4401.579131477739;4470.530594287553;4684.672072954861;4874.2444921037295;4940.554425559371;3219;3275.4104261233206;3387.525746395556;3392.389369913439;3400.438927576922;4117.011917139805;4501.164266512076;4745.928811067905;4826.731436224969;4833.728147770542;4828.975855120702;5101.591872650253;5333.7066461345;5390.733177703159;5655.802046928337;4792.592629626521;4862.05189987949;5597.552195824405;5598.888022239451;5715.8856822864145;5111.233315002779;5336.226247194213;5478.479544443271;5522.723863806874;5535.39110697831;4719.787609557509;5178.844634660966;5500.779968801363;5620.381758006698;5641.40765219646;4184.4351012614125;4547.8635640930825;5492.286002819608;5524.088525393265;5537.612367134262 14:03:26 INFO - PROCESS | 1956 | |5;yui.html;699.6501749125438;718.6155406857329;760.3764912771526;794.451828238515;810.7486679129469;15151.469698560553;16114.516564503054;16139.515814525554;16139.596510087255;16176.11031393273;14565.927170364133;14662.486812961548;14722.41110355587;14725.484608038721;14786;666.2404858461358;674.595242854286;680.6562685843667;682.4949537342355;828.602270909962;650.4861159683868;812.0620683111016;829.9792505187359;846.2172490446343;847.0978407995495;829.6847198064727;841.3941961787503;841.6549214821894;841.8316336732647;841.9980223534005;835.9540225287607;836.4646626159263;836.7155167243167;838.8783626374172;843.3084870406269;514.1644827155872;616.3066550131102;617.8208319587314;621.564904566805;645.880512105262;14651.780223296659;14678.412863485448;14678.559643210721;14704.044237124554;14706.4852730155;13546.59360219195;13960.720785584333;13961.86038139626;14030.859691403057;14206.644833879132;12264.83484069012;12650.114491985565;12660;12745.617631470977;13011.414486348138;4488.573585509404;4507.842225522091;4553.4991150973365;4617.884552886171;4644.930326045112;107644;109879;110377;110939.44530277378;111021;10739.516721747541;10915.945420272848;10969.067629251604;11048.723781905355;11398.316101033965;21788.237411690516;21799.34601961912;21825;21827.12691492354;21829.12683492674;7650.349720273728;7724.802655588393;7784.190041605194;7857.978462799913;7994.160613135653;4285.10012897295;4362.258416069212;4402.295632698753;4524.909501809945;4535.183666939916;3320.833958302075;3416.197193659488;3422.709069729051;3423.1613254752747;3427.5715535558056;576.9451902069296;579.2238400543292;591.7603370634899;592.431265984651;601.8736065426309;511.0417966313163;544.6051612580926;555.7999120316683;598.0043228025376;610.0422336931065;7502.332008120797;8994.86507702385;9019.594118264562;9050.86423703645;9110.59002344896;568.7980766827799;580.3876909860138;598.964062156272;610.008735804318;611.2298503885048;4188.4136220929095;4365.192439398721;4395.164918665508;4422.557744225616;4426.712263702849;165656;168977;169270;173711;176877.1156144211;91020.54489727509;100707.98584028278;133255.33372333075;137811.31094344464;141869;107253.78238652853;119026.28763411335;120021;120528;122884;7012.894806578008;8294.83410331802;8370.790730231793;8661.350398720248;8701.651933922825;49660;63648.36351636425;65643;65763.67118164379;66455.66772166108;82769.82607347931;96132.51933740286;102669.48665256627;103131;103804.48097759765;10027.247956403153;11379.317240965569;11445.656630301104;11907.987821035309;11959.641210763692;12104.152709310263;12122.878771212176;12217.93891030539;12272.140950133406;12630.242185468902;10747.516361763895;11044.392558409365;11592.246503977214;11760.88239117598;11828.82256766132;11634.412228581577;11678.941605292259;12030.699232519257;12051.035917126785;12252.75494490123;11386.829197562209;11567.826482602935;11716.94141529287;11855.229410088317;11924.986376158124;8264.108633362403;9410.623575057194;9570.665026724031;9711.10285926476;9882.604695812088;9626.759331016501;9768.413895166314;9790.167835733757;9791.951040244754;9797.804043919225 14:03:26 INFO - PROCESS | 1956 | __end_tp_report 14:03:26 INFO - PROCESS | 1956 | __start_cc_report 14:03:26 INFO - PROCESS | 1956 | _x_x_mozilla_cycle_collect,1241 14:03:26 INFO - PROCESS | 1956 | __end_cc_report 14:03:26 INFO - PROCESS | 1956 | __startTimestamp1473282206071__endTimestamp 14:03:26 INFO - PROCESS | 1956 | 14:03:26 INFO - PROCESS | 1956 | ------- Summary: start ------- 14:03:26 INFO - PROCESS | 1956 | Number of tests: 6 14:03:26 INFO - PROCESS | 1956 | 14:03:26 INFO - PROCESS | 1956 | [#0] dojo.html Cycles:165 Average:5758.65 Median:5955.26 stddev:642.93 (10.8%) stddev-sans-first:614.22 14:03:26 INFO - PROCESS | 1956 | Values: 3257.1 3309.6 3309.7 3336.4 3375.1 5627.1 5656.7 5716.7 5721.5 5730.5 5688.9 5709.1 5763.8 5801.9 5820.5 5131.5 5191.8 5219.7 5220.6 5272.4 5333.5 5333.6 5335.4 5338.6 5369.0 5146.7 5174.4 5192.8 5230.9 5258.4 5222.5 5246.8 5247.2 5252.7 5259.2 5144.0 5188.4 5196.2 5207.6 5209.6 5638.9 5641.4 5726.7 5728.4 5774.2 6207.9 6275.2 6279.4 6281.0 6292.9 6122.9 6278.1 6319.7 6323.7 6343.7 5757.2 5763.1 5800.1 5819.4 5870.6 6255.7 6313.5 6314.9 6317.4 6343.7 5951.2 5996.4 6143.2 6167.3 6176.3 6151.5 6198.0 6213.6 6271.9 6280.0 5217.3 6231.5 6237.2 6239.7 6249.4 6020.1 6195.7 6214.4 6243.1 6260.1 4893.3 4906.5 4936.3 4937.4 4941.6 4423.5 4456.7 4463.2 4494.8 4558.6 5688.8 5695.6 5698.9 5701.6 5703.6 6111.7 6252.9 6307.2 6320.6 6323.4 5662.1 5684.6 5712.9 5722.5 5769.1 5398.2 5452.4 5454.9 5456.7 5469.8 6210.5 6320.3 6329.9 6337.3 6358.2 6217.1 6251.1 6265.1 6344.9 6353.5 6327.6 6328.3 6340.7 6341.7 6352.1 5974.8 6230.5 6232.1 6238.3 6253.4 6191.3 6235.0 6235.7 6349.2 6350.2 6081.0 6153.1 6157.8 6164.7 6173.6 5955.3 6112.6 6135.5 6137.5 6161.2 5908.9 5919.8 5957.4 6009.0 6028.6 6131.1 6135.9 6146.1 6150.5 6163.4 5808.4 5948.9 5954.4 5955.6 5962.7 14:03:26 INFO - PROCESS | 1956 | 14:03:26 INFO - PROCESS | 1956 | [#1] ext.html Cycles:180 Average:65157.45 Median:18682.35 stddev:143662.96 (769.0%) stddev-sans-first:143983.90 14:03:26 INFO - PROCESS | 1956 | Values: 478.5 501.5 513.8 520.5 526.0 30585.7 40250.0 41233.8 41327.8 41379.6 41437.4 41881.6 41889.6 42023.8 42074.0 8305.8 8720.8 9822.7 9827.0 9837.8 8692.7 9710.8 11869.8 11872.9 12224.2 11868.8 11936.0 11952.0 11956.3 11988.1 11150.0 11192.5 11960.0 12034.7 12066.3 11541.3 11738.1 11760.6 11765.5 11768.8 39616.4 39999.4 40024.0 40062.2 40092.8 3348.1 3382.5 3491.0 3586.0 3682.3 25538.1 30349.4 31506.7 31667.7 31872.7 2623.7 3032.0 3074.5 3154.4 3160.6 499715.0 502144.5 502943.0 503102.0 504182.0 33482.7 33589.3 33719.7 60939.1 61997.0 34659.7 35147.5 35150.3 35216.6 35230.0 14436.4 14574.6 14588.3 14591.1 14594.8 11187.5 11406.8 12145.4 12302.6 12431.3 7518.1 7754.1 7841.4 7847.4 7869.6 3160.2 3205.3 3226.9 3229.4 3252.5 884.6 917.6 918.8 986.7 1027.9 28578.9 29577.6 29711.7 30380.4 30645.5 1070.3 1079.7 1104.3 1111.7 1127.1 12598.7 14467.7 15164.7 15170.7 15558.5 636146.8 640178.0 640681.0 641457.0 646230.0 55701.0 56344.0 56350.2 56435.5 56460.4 1194.0 1310.7 1319.8 1332.6 1333.9 29604.6 30385.7 30668.5 31088.1 31275.1 47470.3 48022.0 48077.0 48199.8 48263.8 448214.0 449572.0 449766.0 449967.0 450329.0 22191.2 23285.9 26950.5 27955.4 28086.2 46192.3 46266.0 46920.8 47280.8 47814.0 34816.7 35223.4 37267.1 38254.8 38654.8 32155.7 33982.2 37760.2 38058.9 39137.8 36274.0 37217.3 37437.0 37694.0 37964.0 26956.8 30559.8 33467.3 33730.0 33740.0 23184.7 24910.5 25108.0 25146.6 25401.6 14:03:26 INFO - PROCESS | 1956 | 14:03:26 INFO - PROCESS | 1956 | [#2] jquery.html Cycles:180 Average:34292.68 Median:26552.14 stddev:93629.63 (352.6%) stddev-sans-first:93857.50 14:03:26 INFO - PROCESS | 1956 | Values: 298.2 371.2 374.7 382.3 403.5 15076.5 17659.4 21227.8 21595.8 21782.0 20671.8 21199.7 21467.0 21562.5 21669.5 5821.0 5842.2 5924.1 5931.5 6098.2 5944.0 6048.1 6059.0 6065.6 6074.8 4156.5 6218.2 6584.3 6635.5 6726.9 6410.5 6679.3 6702.7 6709.0 6752.4 5840.4 5855.1 5855.8 5856.6 5857.3 25580.0 26398.5 26484.2 26620.1 26633.2 2477.2 2772.9 2984.4 3028.4 3115.7 15618.6 16245.7 16297.0 16319.3 16330.6 1652.8 1824.2 1929.4 1950.7 2013.4 128191.0 130442.0 130569.0 133658.3 136348.0 10017.5 10056.2 11169.6 11220.8 11352.9 14170.4 14195.4 14196.5 14208.6 14215.7 6880.7 8390.5 8616.0 8755.9 8768.2 8581.3 8587.0 8598.3 8780.1 8800.6 6052.6 6405.7 6672.5 6825.6 6933.0 1777.7 1818.7 1828.5 1836.6 1843.4 883.6 905.8 951.3 1007.2 1071.8 15211.6 21766.6 22666.7 22674.3 22683.0 922.8 925.4 945.9 977.2 987.8 9580.7 9605.5 10465.1 10702.6 12048.8 548559.0 555412.0 559380.0 559815.0 569669.0 73261.3 116321.0 117665.2 127223.0 127345.0 852.4 1025.1 1070.7 1073.2 1074.5 17206.0 18465.2 19311.9 19697.9 20018.9 53859.5 55165.0 55889.0 57625.1 58410.0 54062.5 82358.6 84999.7 85391.7 85664.6 7920.2 8118.4 8128.3 8171.4 8409.4 11891.8 12878.5 13090.5 13256.0 13513.9 9830.7 10289.2 10452.9 11791.1 11926.0 10423.7 10881.9 11210.3 11589.1 11979.6 11473.4 11550.6 11854.1 12059.6 12138.5 7978.5 8206.5 8255.0 8284.2 8382.8 10457.9 11215.8 11412.2 11662.4 11711.7 14:03:26 INFO - PROCESS | 1956 | 14:03:26 INFO - PROCESS | 1956 | [#3] mootools.html Cycles:180 Average:5623.04 Median:4962.69 stddev:3164.83 (63.8%) stddev-sans-first:3156.70 14:03:26 INFO - PROCESS | 1956 | Values: 1257.9 1491.9 1545.6 1718.4 1833.9 7702.0 8611.7 8832.4 8832.7 8897.7 8574.6 8667.0 8679.0 8689.0 8689.4 2372.7 2406.7 2409.0 2409.9 2411.0 2048.8 2353.2 2389.9 2397.4 2404.5 2347.3 2368.8 2376.8 2385.0 2389.2 2326.1 2351.3 2354.3 2359.6 2366.4 2309.1 2349.1 2349.2 2349.5 2352.4 8612.4 8686.8 8799.4 8809.0 8811.0 5186.0 5367.9 5372.8 5373.7 5384.3 3630.9 3662.2 3702.3 3722.5 3723.8 1073.2 1086.1 1088.3 1111.7 1114.0 11463.5 11514.0 11590.7 11601.2 11610.2 4309.2 4408.8 4562.8 4563.1 4567.9 9035.2 9042.5 9043.3 9044.1 9046.5 5171.7 5172.7 5177.2 5177.7 5184.9 4269.3 4344.8 4412.6 4575.2 4862.1 3692.6 3717.4 3753.7 3794.9 3797.9 1161.5 1163.6 1166.4 1169.6 1172.6 638.2 824.4 895.4 906.1 933.3 5110.0 5297.9 5474.8 5726.5 5848.7 875.7 883.0 908.4 914.5 952.7 3810.8 3816.3 3858.8 3939.5 3960.3 10367.1 10829.6 10853.3 10864.3 10889.9 10780.4 10796.9 10805.9 10809.8 10813.6 7732.5 8081.7 8104.5 8110.0 8192.0 5865.2 5871.0 5894.3 6061.1 6071.0 9935.4 10034.2 10072.4 10085.8 10087.1 9279.8 9697.8 10087.8 10192.0 10297.8 6640.0 7431.1 7512.7 7531.9 7540.5 8020.2 8100.0 8121.1 8171.3 8208.0 7368.2 7381.2 7449.7 7451.0 7498.7 7140.4 7243.6 7279.0 7345.3 7398.0 5844.6 6807.5 6932.4 6945.9 6952.4 4095.5 4816.6 4983.7 4989.9 5009.8 4895.6 4941.7 5049.9 5094.4 5196.0 14:03:26 INFO - PROCESS | 1956 | 14:03:26 INFO - PROCESS | 1956 | [#4] prototype.html Cycles:180 Average:4240.29 Median:4579.20 stddev:1282.80 (28.0%) stddev-sans-first:1251.24 14:03:26 INFO - PROCESS | 1956 | Values: 266.3 298.9 308.9 311.4 311.5 4158.6 4996.5 5278.3 5436.6 5437.9 5105.0 5176.3 5241.6 5258.2 5356.9 3548.4 4053.0 4085.8 4107.2 4145.5 3984.7 4120.2 4135.9 4150.2 4167.6 4042.7 4083.0 4089.7 4091.3 4103.8 3402.2 4039.7 4041.0 4096.4 4115.1 3003.9 3009.1 3533.9 3579.0 3589.6 4127.0 5182.9 5248.8 5283.3 5295.9 3522.3 3786.0 4582.0 4720.4 4729.5 5076.8 5123.4 5584.3 5624.9 5652.8 3568.2 3719.8 3895.6 3934.5 3965.4 5907.7 5910.3 5916.6 5920.6 5931.5 4012.4 4021.0 4025.6 4028.6 4048.8 3906.5 3993.9 4034.1 4077.6 4087.7 3839.7 4610.7 4614.8 4629.8 4680.9 5684.9 5711.2 5728.0 5735.0 5762.4 1282.1 1283.4 1303.4 1357.3 1365.3 820.5 823.5 845.0 856.0 857.6 3927.9 4179.5 4195.9 4395.3 4417.7 4438.8 4702.7 4704.3 4723.6 4784.7 4266.6 4268.9 4281.3 4324.9 4400.6 1971.1 2396.3 2531.1 2531.8 2569.7 4600.6 4911.3 4950.1 4980.3 5024.7 4201.3 4351.2 4541.7 4716.9 4839.9 3943.9 4020.1 4650.4 4847.4 4907.2 4568.9 4576.4 4652.0 4701.0 4717.5 4482.1 4484.8 4707.9 4733.7 4747.5 4401.6 4470.5 4684.7 4874.2 4940.6 3219.0 3275.4 3387.5 3392.4 3400.4 4117.0 4501.2 4745.9 4826.7 4833.7 4829.0 5101.6 5333.7 5390.7 5655.8 4792.6 4862.1 5597.6 5598.9 5715.9 5111.2 5336.2 5478.5 5522.7 5535.4 4719.8 5178.8 5500.8 5620.4 5641.4 4184.4 4547.9 5492.3 5524.1 5537.6 14:03:26 INFO - PROCESS | 1956 | 14:03:26 INFO - PROCESS | 1956 | [#5] yui.html Cycles:180 Average:25276.90 Median:4380.18 stddev:42159.04 (962.5%) stddev-sans-first:42236.92 14:03:26 INFO - PROCESS | 1956 | Values: 699.7 718.6 760.4 794.5 810.7 15151.5 16114.5 16139.5 16139.6 16176.1 14565.9 14662.5 14722.4 14725.5 14786.0 666.2 674.6 680.7 682.5 828.6 650.5 812.1 830.0 846.2 847.1 829.7 841.4 841.7 841.8 842.0 836.0 836.5 836.7 838.9 843.3 514.2 616.3 617.8 621.6 645.9 14651.8 14678.4 14678.6 14704.0 14706.5 13546.6 13960.7 13961.9 14030.9 14206.6 12264.8 12650.1 12660.0 12745.6 13011.4 4488.6 4507.8 4553.5 4617.9 4644.9 107644.0 109879.0 110377.0 110939.4 111021.0 10739.5 10915.9 10969.1 11048.7 11398.3 21788.2 21799.3 21825.0 21827.1 21829.1 7650.3 7724.8 7784.2 7858.0 7994.2 4285.1 4362.3 4402.3 4524.9 4535.2 3320.8 3416.2 3422.7 3423.2 3427.6 576.9 579.2 591.8 592.4 601.9 511.0 544.6 555.8 598.0 610.0 7502.3 8994.9 9019.6 9050.9 9110.6 568.8 580.4 599.0 610.0 611.2 4188.4 4365.2 4395.2 4422.6 4426.7 165656.0 168977.0 169270.0 173711.0 176877.1 91020.5 100708.0 133255.3 137811.3 141869.0 107253.8 119026.3 120021.0 120528.0 122884.0 7012.9 8294.8 8370.8 8661.4 8701.7 49660.0 63648.4 65643.0 65763.7 66455.7 82769.8 96132.5 102669.5 103131.0 103804.5 10027.2 11379.3 11445.7 11908.0 11959.6 12104.2 12122.9 12217.9 12272.1 12630.2 10747.5 11044.4 11592.2 11760.9 11828.8 11634.4 11678.9 12030.7 12051.0 12252.8 11386.8 11567.8 11716.9 11855.2 11925.0 8264.1 9410.6 9570.7 9711.1 9882.6 9626.8 9768.4 9790.2 9792.0 9797.8 14:03:26 INFO - PROCESS | 1956 | -------- Summary: end -------- 14:03:26 INFO - PROCESS | 1956 | 14:03:26 INFO - TEST-INFO | 1956: exit 0 14:03:26 INFO - TEST-OK | dromaeo_css | took 1117882ms 14:03:26 INFO - TEST-START | kraken 14:03:26 INFO - operating with platform_type : mac_ 14:03:26 INFO - Initialising browser for kraken test... 14:03:26 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpqN1xkM/profile http://localhost:51800/getInfo.html) 14:03:29 INFO - PROCESS | 1960 | __metrics Screen width/height:1600/1200 14:03:29 INFO - PROCESS | 1960 | colorDepth:24 14:03:29 INFO - PROCESS | 1960 | Browser inner width/height: 1024/689 14:03:29 INFO - PROCESS | 1960 | __metrics 14:03:29 INFO - PROCESS | 1960 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:03:29 INFO - PROCESS | 1960 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:03:29 INFO - TEST-INFO | 1960: exit 0 14:03:29 INFO - Browser initialized. 14:03:29 INFO - Running cycle 1/1 for kraken test... 14:03:29 INFO - TEST-INFO | started process 1961 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpqN1xkM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:03:41 INFO - PROCESS | 1961 | RSS: Main: 232808448 14:03:41 INFO - PROCESS | 1961 | 14:03:44 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51800/tests/kraken/driver.html?testName=audio-beat-detection) 14:03:45 INFO - PROCESS | 1961 | RSS: Main: 292167680 14:03:45 INFO - PROCESS | 1961 | 14:03:48 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51800/tests/kraken/driver.html?testName=audio-dft) 14:03:48 INFO - PROCESS | 1961 | RSS: Main: 287399936 14:03:48 INFO - PROCESS | 1961 | 14:03:52 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51800/tests/kraken/driver.html?testName=audio-fft) 14:03:52 INFO - PROCESS | 1961 | RSS: Main: 376778752 14:03:52 INFO - PROCESS | 1961 | 14:03:55 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51800/tests/kraken/driver.html?testName=audio-oscillator) 14:03:55 INFO - PROCESS | 1961 | RSS: Main: 281104384 14:03:55 INFO - PROCESS | 1961 | 14:03:57 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51800/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:03:58 INFO - PROCESS | 1961 | RSS: Main: 285106176 14:03:58 INFO - PROCESS | 1961 | 14:04:01 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51800/tests/kraken/driver.html?testName=imaging-darkroom) 14:04:01 INFO - PROCESS | 1961 | RSS: Main: 324268032 14:04:01 INFO - PROCESS | 1961 | 14:04:04 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51800/tests/kraken/driver.html?testName=imaging-desaturate) 14:04:05 INFO - PROCESS | 1961 | RSS: Main: 323178496 14:04:05 INFO - PROCESS | 1961 | 14:04:08 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51800/tests/kraken/driver.html?testName=json-parse-financial) 14:04:08 INFO - PROCESS | 1961 | RSS: Main: 322711552 14:04:08 INFO - PROCESS | 1961 | 14:04:10 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51800/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:04:11 INFO - PROCESS | 1961 | RSS: Main: 298016768 14:04:11 INFO - PROCESS | 1961 | 14:04:12 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51800/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:04:13 INFO - PROCESS | 1961 | RSS: Main: 303542272 14:04:13 INFO - PROCESS | 1961 | 14:04:15 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51800/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:04:16 INFO - PROCESS | 1961 | RSS: Main: 308350976 14:04:16 INFO - PROCESS | 1961 | 14:04:19 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51800/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:04:20 INFO - PROCESS | 1961 | RSS: Main: 315506688 14:04:20 INFO - PROCESS | 1961 | 14:04:22 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51800/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:04:23 INFO - PROCESS | 1961 | RSS: Main: 317968384 14:04:23 INFO - PROCESS | 1961 | 14:04:25 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51800/tests/kraken/driver.html?testName=ai-astar) 14:04:25 INFO - PROCESS | 1961 | RSS: Main: 362127360 14:04:25 INFO - PROCESS | 1961 | 14:04:25 INFO - PROCESS | 1961 | __start_tp_report 14:04:25 INFO - PROCESS | 1961 | _x_x_mozilla_page_load 14:04:25 INFO - PROCESS | 1961 | _x_x_mozilla_page_load_details 14:04:25 INFO - PROCESS | 1961 | |i|pagename|runs| 14:04:25 INFO - PROCESS | 1961 | |0;ai-astar;113;113;117;112;113;115;156;163;114;122 14:04:25 INFO - PROCESS | 1961 | |1;audio-beat-detection;148;108;131;162;109;129;129;108;161;110 14:04:25 INFO - PROCESS | 1961 | |2;audio-dft;185;184;184;197;258;188;183;182;184;182 14:04:25 INFO - PROCESS | 1961 | |3;audio-fft;82;75;76;153;61;73;81;95;149;58 14:04:25 INFO - PROCESS | 1961 | |4;audio-oscillator;82;84;140;100;99;113;121;69;71;120 14:04:25 INFO - PROCESS | 1961 | |5;imaging-gaussian-blur;89;86;89;87;88;84;87;85;87;84 14:04:25 INFO - PROCESS | 1961 | |6;imaging-darkroom;97;97;97;97;96;96;117;159;97;96 14:04:25 INFO - PROCESS | 1961 | |7;imaging-desaturate;87;85;84;87;84;87;87;84;87;86 14:04:25 INFO - PROCESS | 1961 | |8;json-parse-financial;55;56;56;54;54;55;55;55;54;53 14:04:25 INFO - PROCESS | 1961 | |9;json-stringify-tinderbox;66;70;66;77;73;78;72;71;71;72 14:04:25 INFO - PROCESS | 1961 | |10;stanford-crypto-aes;88;87;88;86;88;85;83;85;86;89 14:04:25 INFO - PROCESS | 1961 | |11;stanford-crypto-ccm;139;144;120;160;148;158;145;137;237;138 14:04:25 INFO - PROCESS | 1961 | |12;stanford-crypto-pbkdf2;166;184;168;170;170;167;170;169;168;166 14:04:25 INFO - PROCESS | 1961 | |13;stanford-crypto-sha256-iterative;68;69;69;69;69;68;73;86;65;58 14:04:25 INFO - PROCESS | 1961 | __end_tp_report 14:04:25 INFO - PROCESS | 1961 | __start_cc_report 14:04:25 INFO - PROCESS | 1961 | _x_x_mozilla_cycle_collect,1987 14:04:25 INFO - PROCESS | 1961 | __end_cc_report 14:04:25 INFO - PROCESS | 1961 | __startTimestamp1473282265523__endTimestamp 14:04:25 INFO - PROCESS | 1961 | 14:04:25 INFO - PROCESS | 1961 | ------- Summary: start ------- 14:04:25 INFO - PROCESS | 1961 | Number of tests: 14 14:04:25 INFO - PROCESS | 1961 | 14:04:25 INFO - PROCESS | 1961 | [#0] ai-astar Cycles:10 Average:123.80 Median:116.00 stddev:19.11 (16.5%) stddev-sans-first:19.86 14:04:25 INFO - PROCESS | 1961 | Values: 113.0 113.0 117.0 112.0 113.0 115.0 156.0 163.0 114.0 122.0 14:04:25 INFO - PROCESS | 1961 | 14:04:25 INFO - PROCESS | 1961 | [#1] audio-beat-detection Cycles:10 Average:129.50 Median:130.00 stddev:21.39 (16.5%) stddev-sans-first:21.62 14:04:25 INFO - PROCESS | 1961 | Values: 148.0 108.0 131.0 162.0 109.0 129.0 129.0 108.0 161.0 110.0 14:04:25 INFO - PROCESS | 1961 | 14:04:25 INFO - PROCESS | 1961 | [#2] audio-dft Cycles:10 Average:192.70 Median:184.50 stddev:23.37 (12.7%) stddev-sans-first:24.62 14:04:25 INFO - PROCESS | 1961 | Values: 185.0 184.0 184.0 197.0 258.0 188.0 183.0 182.0 184.0 182.0 14:04:25 INFO - PROCESS | 1961 | 14:04:25 INFO - PROCESS | 1961 | [#3] audio-fft Cycles:10 Average:90.30 Median:75.50 stddev:33.66 (44.6%) stddev-sans-first:35.56 14:04:25 INFO - PROCESS | 1961 | Values: 82.0 75.0 76.0 153.0 61.0 73.0 81.0 95.0 149.0 58.0 14:04:25 INFO - PROCESS | 1961 | 14:04:25 INFO - PROCESS | 1961 | [#4] audio-oscillator Cycles:10 Average:99.90 Median:70.00 stddev:23.55 (33.6%) stddev-sans-first:24.08 14:04:25 INFO - PROCESS | 1961 | Values: 82.0 84.0 140.0 100.0 99.0 113.0 121.0 69.0 71.0 120.0 14:04:25 INFO - PROCESS | 1961 | 14:04:25 INFO - PROCESS | 1961 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.00 stddev:1.84 (2.1%) stddev-sans-first:1.73 14:04:25 INFO - PROCESS | 1961 | Values: 89.0 86.0 89.0 87.0 88.0 84.0 87.0 85.0 87.0 84.0 14:04:25 INFO - PROCESS | 1961 | 14:04:25 INFO - PROCESS | 1961 | [#6] imaging-darkroom Cycles:10 Average:104.90 Median:97.00 stddev:20.06 (20.7%) stddev-sans-first:21.08 14:04:25 INFO - PROCESS | 1961 | Values: 97.0 97.0 97.0 97.0 96.0 96.0 117.0 159.0 97.0 96.0 14:04:25 INFO - PROCESS | 1961 | 14:04:25 INFO - PROCESS | 1961 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:87.00 stddev:1.40 (1.6%) stddev-sans-first:1.41 14:04:25 INFO - PROCESS | 1961 | Values: 87.0 85.0 84.0 87.0 84.0 87.0 87.0 84.0 87.0 86.0 14:04:25 INFO - PROCESS | 1961 | 14:04:25 INFO - PROCESS | 1961 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:0.95 (1.7%) stddev-sans-first:1.00 14:04:25 INFO - PROCESS | 1961 | Values: 55.0 56.0 56.0 54.0 54.0 55.0 55.0 55.0 54.0 53.0 14:04:25 INFO - PROCESS | 1961 | 14:04:25 INFO - PROCESS | 1961 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:72.00 stddev:3.92 (5.4%) stddev-sans-first:3.60 14:04:25 INFO - PROCESS | 1961 | Values: 66.0 70.0 66.0 77.0 73.0 78.0 72.0 71.0 71.0 72.0 14:04:25 INFO - PROCESS | 1961 | 14:04:25 INFO - PROCESS | 1961 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:87.50 stddev:1.84 (2.1%) stddev-sans-first:1.87 14:04:25 INFO - PROCESS | 1961 | Values: 88.0 87.0 88.0 86.0 88.0 85.0 83.0 85.0 86.0 89.0 14:04:25 INFO - PROCESS | 1961 | 14:04:25 INFO - PROCESS | 1961 | [#11] stanford-crypto-ccm Cycles:10 Average:152.60 Median:146.50 stddev:31.74 (21.7%) stddev-sans-first:33.28 14:04:25 INFO - PROCESS | 1961 | Values: 139.0 144.0 120.0 160.0 148.0 158.0 145.0 137.0 237.0 138.0 14:04:25 INFO - PROCESS | 1961 | 14:04:25 INFO - PROCESS | 1961 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:169.50 stddev:5.22 (3.1%) stddev-sans-first:5.36 14:04:25 INFO - PROCESS | 1961 | Values: 166.0 184.0 168.0 170.0 170.0 167.0 170.0 169.0 168.0 166.0 14:04:25 INFO - PROCESS | 1961 | 14:04:25 INFO - PROCESS | 1961 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:69.00 stddev:7.01 (10.2%) stddev-sans-first:7.42 14:04:25 INFO - PROCESS | 1961 | Values: 68.0 69.0 69.0 69.0 69.0 68.0 73.0 86.0 65.0 58.0 14:04:25 INFO - PROCESS | 1961 | -------- Summary: end -------- 14:04:25 INFO - PROCESS | 1961 | 14:04:26 INFO - TEST-INFO | 1961: exit 0 14:04:26 INFO - TEST-OK | kraken | took 59739ms 14:04:26 INFO - SUITE-END | took 1177s 14:04:26 INFO - Completed test suite (00:19:37) 14:04:26 INFO - javascript benchmark 14:04:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3257.0717345556513, 3309.5697559317287, 3309.6855798699125, 3336.4494858348376, 3375.1055970167895, 5627.127795191742, 5656.745446454909, 5716.685582292972, 5721.485066344028, 5730.484256416927, 5688.886222275552, 5709.086546152616, 5763.827085187451, 5801.883962320751, 5820.53435725142, 5131.512506311894, 5191.766370513317, 5219.71291578963, 5220.582353411718, 5272.437326043245, 5333.466653334654, 5333.626646134772, 5335.359756829186, 5338.599605029638, 5369.0, 5146.6911985281, 5174.353205849269, 5192.792288308463, 5230.927659829745, 5258.447862974371, 5222.477752224785, 5246.842594722164, 5247.1866860636665, 5252.711100889488, 5259.211118332242, 5144.0, 5188.377394712621, 5196.246544251062, 5207.583393328562, 5209.609279304067, 5638.887222255573, 5641.407652196502, 5726.656400615976, 5728.42715728428, 5774.162746401748, 6207.937920620826, 6275.215598050244, 6279.403456671609, 6281.0, 6292.874142517124, 6122.886104953791, 6278.089676996856, 6319.6524191169465, 6323.74705011799, 6343.746250149989, 5757.165211044375, 5763.106718458687, 5800.129980502874, 5819.418058194146, 5870.618409803349, 6255.655938923312, 6313.494920406357, 6314.873702525923, 6317.399847014526, 6343.651099189497, 5951.196588460589, 5996.370381110008, 6143.201383820075, 6167.290761562388, 6176.289726681489, 6151.507879369639, 6198.039303907902, 6213.565050446516, 6271.905921411182, 6280.0, 5217.34783152106, 6231.532635052298, 6237.157983672237, 6239.656818874914, 6249.375062493714, 6020.096985452217, 6195.721192546346, 6214.378562143749, 6243.094751261133, 6260.060990851409, 4893.266010098442, 4906.460289368167, 4936.333594964705, 4937.432195297515, 4941.555260026543, 4423.51341352451, 4456.68803183774, 4463.196624607537, 4494.797734101973, 4558.612517936012, 5688.77244910199, 5695.60130790849, 5698.857528561819, 5701.600887937804, 5703.600747947604, 6111.7436766921655, 6252.874942501125, 6307.211598550181, 6320.5891617044745, 6323.399277068763, 5662.065759149777, 5684.573656975744, 5712.914306285409, 5722.513586345124, 5769.07694768835, 5398.217258497496, 5452.40023597404, 5454.91817622736, 5456.699881506555, 5469.835904922858, 6210.5031597473, 6320.273168585672, 6329.90505142414, 6337.271213810286, 6358.205224346957, 6217.098520714561, 6251.124842522141, 6265.122882796503, 6344.9048264277, 6353.4599559038, 6327.588706734047, 6328.335524769833, 6340.651264180515, 6341.682915854281, 6352.110704501281, 5974.790882319098, 6230.532710046855, 6232.127502149793, 6238.313785483684, 6253.437190652863, 6191.318954914866, 6235.033569796688, 6235.719392627252, 6349.206349206349, 6350.237971443456, 6080.969595151996, 6153.138560601609, 6157.753689852356, 6164.691765411621, 6173.59871608344, 5955.255593050869, 6112.602680825732, 6135.509159267337, 6137.478314343331, 6161.199044124235, 5908.881822363443, 5919.822405327847, 5957.374475680164, 6009.0, 6028.57799953999, 6131.141640170469, 6135.938640613536, 6146.139540464428, 6150.538709596887, 6163.3528479509005, 5808.419158084158, 5948.940510594839, 5954.404559544011, 5955.612885162451, 5962.731677074629], "value": 5716.490572802421, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.53342990584207, 501.52605787530797, 513.82016294297, 520.469121496074, 525.9763310651023, 30585.69414305856, 40250.0, 41233.7938310308, 41327.79336103319, 41379.58620413795, 41437.37843932343, 41881.58118418815, 41889.58110418895, 42023.78988105063, 42074.0, 8305.833883322344, 8720.825583488326, 9822.656207032755, 9827.017298270188, 9837.754056148618, 8692.739217823475, 9710.805783884318, 11869.7626047479, 11872.881271187307, 12224.205426647284, 11868.7626247475, 11935.985441237504, 11952.0, 11956.342401167933, 11988.100892433058, 11150.0, 11192.496337664743, 11960.0, 12034.69913252167, 12066.33635150062, 11541.30752154865, 11738.119641026957, 11760.588379406765, 11765.529378824836, 11768.764624707543, 39616.405753913714, 39999.40000899989, 40023.99940001494, 40062.198756025005, 40092.79953600242, 3348.09601407621, 3382.5433566468705, 3490.9825450872836, 3585.964140358615, 3682.318771027342, 25538.106166284277, 30349.393012139633, 31506.684933150606, 31667.683323166933, 31872.6812731872, 2623.6589243398425, 3031.9848400758074, 3074.5388191771412, 3154.4006638738565, 3160.573322601442, 499715.0, 502144.4892775549, 502943.0, 503102.0, 504182.0, 33482.66517334844, 33589.3282134356, 33719.66280337214, 60939.08591371133, 61997.0, 34659.653403466145, 35147.4727879082, 35150.29699405997, 35216.64783352134, 35230.0, 14436.350364233635, 14574.562763117123, 14588.270586470633, 14591.124532528082, 14594.781078283835, 11187.4965626547, 11406.828897566544, 12145.392730363446, 12302.569410070604, 12431.31627760482, 7518.060242469692, 7754.108277548042, 7841.4118941078505, 7847.411444141712, 7869.567173805382, 3160.241542029896, 3205.2788122672214, 3226.9031929042167, 3229.4187046331417, 3252.495863141217, 884.5798245833178, 917.5779141594941, 918.7794929217074, 986.7483791633088, 1027.897210278981, 28578.857105714338, 29577.55633665497, 29711.702882970894, 30380.392392152033, 30645.540316895265, 1070.3363914373138, 1079.7246702090918, 1104.2601457023814, 1111.6553868300691, 1127.1490025031048, 12598.748025039447, 14467.710645787236, 15164.696706065817, 15170.73829126721, 15558.533244002698, 636146.8192659008, 640178.0, 640681.0, 641457.0, 646230.0, 55701.0, 56344.0, 56350.154747677996, 56435.50991636014, 56460.435395645516, 1193.9791478286113, 1310.698539335939, 1319.808627748971, 1332.580237225271, 1333.8799508044322, 29604.555931660612, 30385.696143038287, 30668.539971899994, 31088.067357979297, 31275.061748147593, 47470.287945681535, 48022.0, 48077.03845923132, 48199.75900120477, 48263.75868120637, 448214.0, 449572.0, 449766.0, 449967.0, 450329.0, 22191.22330718417, 23285.924963131045, 26950.517721525503, 27955.440891181657, 28086.157415277576, 46192.30711539262, 46266.0, 46920.7653961728, 47280.76359618318, 47814.0, 34816.651833481345, 35223.37027428296, 37267.06832329214, 38254.808725956194, 38654.80672596619, 32155.678443215267, 33982.150446239044, 37760.244795104496, 38058.91057430798, 39137.80431097826, 36274.0, 37217.25565488729, 37437.0, 37694.0, 37964.0, 26956.837791950682, 30559.847200763856, 33467.33065338728, 33729.988100357026, 33740.0, 23184.652230216223, 24910.501789964463, 25108.0, 25146.62280065764, 25401.61897571501], "value": 18470.472830478437, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.19041302862723, 371.1778410820033, 374.6571886723648, 382.3271042964383, 403.4936155125319, 15076.47232346868, 17659.381921632747, 21227.787722122775, 21595.784042159572, 21782.0, 20671.793282067174, 21199.68200476994, 21467.03398347074, 21562.460938476506, 21669.45826354342, 5820.999999999989, 5842.152887831262, 5924.140999555062, 5931.466168044875, 6098.237720284965, 5944.0, 6048.0625503047095, 6059.030555111183, 6065.575409721299, 6074.757009719606, 4156.5012198536215, 6218.160548325986, 6584.341565843425, 6635.535512514126, 6726.865462690744, 6410.519211059142, 6679.332066793282, 6702.698378572976, 6709.0, 6752.358525940028, 5840.445157710011, 5855.063189889597, 5855.76576936922, 5856.619319744203, 5857.26784151981, 25579.97680092794, 26398.472030559475, 26484.205473835817, 26620.06829760949, 26633.201003969913, 2477.206605218907, 2772.9029483968166, 2984.4329577380227, 3028.439738648357, 3115.7040081192476, 15618.609534761608, 16245.675086498322, 16297.022178669318, 16319.347226110942, 16330.591735206595, 1652.7686123942658, 1824.1791193962638, 1929.411529483507, 1950.6683863743056, 2013.4496437743003, 128191.0, 130442.0, 130569.0, 133658.3317083418, 136348.0, 10017.549210285555, 10056.195504359634, 11169.553217871195, 11220.831687524693, 11352.886471135183, 14170.433182672783, 14195.361208745631, 14196.503122390666, 14208.644783880485, 14215.715685686228, 6880.65596720172, 8390.454620449773, 8616.0, 8755.868661970075, 8768.210861022411, 8581.313494920392, 8587.0, 8598.312135029182, 8780.078091800397, 8800.647974080965, 6052.577644253597, 6405.6797160141805, 6672.466202703772, 6825.624590647562, 6933.0293758873795, 1777.7155655095123, 1818.6999145140903, 1828.469743774317, 1836.6051298970785, 1843.4008947092248, 883.6332921837366, 905.8233644439271, 951.3150531617224, 1007.1539906478594, 1071.8177909755361, 15211.619709507351, 21766.564668706855, 22666.66000010001, 22674.319770406913, 22683.0, 922.7600823785864, 925.3753716241511, 945.9101385368378, 977.2182254196756, 987.7975015121883, 9580.700631848305, 9605.519724013771, 10465.063963166838, 10702.555155053968, 12048.75902481963, 548559.0, 555412.0, 559380.0, 559815.0, 569669.0, 73261.26738732652, 116321.0, 117665.23502147474, 127223.0, 127345.0, 852.3607294528979, 1025.0927928782933, 1070.743021674808, 1073.1629329123296, 1074.5057273654206, 17206.010972409895, 18465.16906739173, 19311.903440482707, 19697.901510492356, 20018.89990550038, 53859.461405385446, 55165.0, 55889.0, 57625.13562296485, 58410.0, 54062.45937540574, 82358.58820705858, 84999.72500412376, 85391.71912421443, 85664.57167714121, 7920.168382320004, 8118.350531957311, 8128.268455839003, 8171.387145964195, 8409.369297302605, 11891.821622675494, 12878.549250776177, 13090.541831035867, 13256.0, 13513.864861351654, 9830.705078847646, 10289.228307876789, 10452.857164281151, 11791.056715462571, 11926.045916326499, 10423.739406514595, 10881.945590271998, 11210.27133236337, 11589.072874170215, 11979.640610781691, 11473.36896470702, 11550.59572914944, 11854.11094167958, 12059.57791477294, 12138.453769580214, 7978.4813987092975, 8206.548639824867, 8254.958725206576, 8284.212999765012, 8382.790430239293, 10457.895421045692, 11215.775684486427, 11412.201145919706, 11662.416879156179, 11711.707207319885], "value": 9930.829711265813, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1257.924524528529, 1491.8582734640213, 1545.590418539087, 1718.4243278501701, 1833.94498165055, 7701.961490192548, 8611.698590549331, 8832.425892317011, 8832.690855820056, 8897.68858089967, 8574.571271436434, 8666.956665216681, 8679.001914779803, 8689.0, 8689.391742578022, 2372.6678265042906, 2406.6750988616577, 2409.012304954969, 2409.855408675476, 2410.963835542468, 2048.836093112555, 2353.188150088217, 2389.880505974703, 2397.3646983549374, 2404.5311164322966, 2347.3310106619615, 2368.822338324624, 2376.762323767618, 2384.9642255366184, 2389.1996181279205, 2326.0579465316573, 2351.318117745852, 2354.2937118864274, 2359.563480756057, 2366.420227044368, 2309.099451214028, 2349.0721165139844, 2349.1543044504137, 2349.4596242864236, 2352.447174913894, 8612.440191387539, 8686.782830429227, 8799.384043116985, 8808.955955220246, 8811.0, 5185.974070129625, 5367.946320536784, 5372.838814835562, 5373.704446255454, 5384.326959130109, 3630.9273814523563, 3662.212624285765, 3702.333579955607, 3722.4602432647384, 3723.7579557328686, 1073.1575713065283, 1086.1365214654368, 1088.2708585247904, 1111.699841042922, 1114.0, 11463.484143213574, 11513.963743263146, 11590.652280431515, 11601.245919015322, 11610.24533405326, 4309.159713855768, 4408.801603927767, 4562.771861406916, 4563.133004729091, 4567.862964111082, 9035.232005279624, 9042.457452552868, 9043.276537876955, 9044.050374710692, 9046.502442365732, 5171.689698618095, 5172.689638621695, 5177.171652535651, 5177.715225662625, 5184.870378240575, 4269.338252570857, 4344.761038142869, 4412.558744125626, 4575.153596584642, 4862.100511405401, 3692.6491983262085, 3717.4052151655605, 3753.662170404622, 3794.886153415402, 3797.9430308545393, 1161.5469966712988, 1163.5752950173296, 1166.4284500594647, 1169.602335206034, 1172.5954545681727, 638.2341190571333, 824.431142511677, 895.3821862914568, 906.1482206725789, 933.3186773655269, 5109.9744501277255, 5297.947020529822, 5474.753636086386, 5726.541876649858, 5848.736806843703, 875.6847534887436, 883.0, 908.3732224764891, 914.5244472874068, 952.7189479103681, 3810.7713537187856, 3816.2558301131567, 3858.8070596470066, 3939.5075615548058, 3960.2673505401594, 10367.118794902366, 10829.566817327219, 10853.294535855144, 10864.293820901616, 10889.945550272198, 10780.407077610807, 10796.94601527003, 10805.945970270099, 10809.83785243222, 10813.56745730162, 7732.458727888994, 8081.676732930617, 8104.51372917615, 8109.959450202711, 8191.95904020488, 5865.208196893535, 5870.972579798604, 5894.2926848778425, 6061.060535616986, 6071.026916252147, 9935.403875767477, 10034.1972642187, 10072.446015468926, 10085.798284034425, 10087.142592879394, 9279.76800579991, 9697.757556061155, 10087.798244035224, 10191.980801919748, 10297.794044118926, 6639.966800165968, 7431.071116110486, 7512.661930213263, 7531.88702169457, 7540.472166948261, 8020.238077382639, 8099.959500202697, 8121.147279535564, 8171.346292296483, 8208.0, 7368.189499154982, 7381.151167615578, 7449.664765085476, 7450.962745186456, 7498.700051997859, 7140.428765698835, 7243.637818108968, 7279.0, 7345.3021962913435, 7397.963010184914, 5844.649321040752, 6807.455403567604, 6932.44540436774, 6945.930540694529, 6952.443804495527, 4095.5290141634096, 4816.566509014206, 4983.651144419856, 4989.925151122663, 5009.824656137018, 4895.632827537877, 4941.728204948762, 5049.924251136309, 5094.388673359221, 5196.0], "value": 4510.460972893047, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [266.32220997561205, 298.9491786396311, 308.9397567474342, 311.38967623458024, 311.54669955215167, 4158.625723684868, 4996.52533009364, 5278.287431196787, 5436.565074794017, 5437.918431223535, 5104.983531505062, 5176.27532145499, 5241.554467870235, 5258.237555554442, 5356.946430535693, 3548.3790336691104, 4052.9797351013203, 4085.836566537335, 4107.219628270619, 4145.523264824538, 3984.7409918355215, 4120.237756015132, 4135.875923722293, 4150.232207041692, 4167.562405947378, 4042.676585873138, 4082.9996650820444, 4089.6932730045364, 4091.3249313863175, 4103.774292413916, 3402.2004828865197, 4039.697022723293, 4040.969552764041, 4096.365063415175, 4115.053537686349, 3003.8798448062053, 3009.1423944175804, 3533.876314329012, 3579.0, 3589.569251689788, 4126.988887722498, 5182.870428239286, 5248.790048398059, 5283.28675628789, 5295.920561191585, 3522.3131489359585, 3785.9621403785886, 4581.977090114528, 4720.386349774507, 4729.503402142795, 5076.82231121909, 5123.359580052494, 5584.329880414376, 5624.943750562483, 5652.806164517076, 3568.232829941575, 3719.776813391178, 3895.552011518655, 3934.488516492838, 3965.4250133730447, 5907.675077870673, 5910.320313163998, 5916.585838991272, 5920.644761314335, 5931.466168044896, 4012.374988129812, 4021.0, 4025.6376926076796, 4028.576999415078, 4048.817803198863, 3906.4921560197563, 3993.9216411568714, 4034.11249525104, 4077.6126268005087, 4087.734297270668, 3839.6544311012144, 4610.677252592286, 4614.76926153691, 4629.800844598856, 4680.882977925579, 5684.857878553069, 5711.200431939532, 5727.971360143172, 5734.971325143348, 5762.394948530427, 1282.0833104330447, 1283.4011127466954, 1303.4004357995238, 1357.260293140241, 1365.2968721108637, 820.5445977482435, 823.5470491229919, 844.9619767110372, 855.9786005349916, 857.5712143928037, 3927.8953493599256, 4179.456670632799, 4195.937060944088, 4395.340698895191, 4417.712848664826, 4438.844640437569, 4702.741349225757, 4704.294355846582, 4723.5984941279685, 4784.679430476216, 4266.637335826489, 4268.911427585946, 4281.324172392255, 4324.880808403922, 4400.603945644907, 1971.142552989454, 2396.3410062233024, 2531.139412599725, 2531.797456203499, 2569.7044839843284, 4600.60894823937, 4911.263310503382, 4950.108980383494, 4980.2778597103215, 5024.668462857346, 4201.332154082119, 4351.151525452569, 4541.729954432803, 4716.9292460613115, 4839.879003024953, 3943.881683549498, 4020.1053644944177, 4650.395613513265, 4847.3698419206685, 4907.214845624754, 4568.862934111982, 4576.414325905853, 4652.023075154256, 4700.952990470051, 4717.481077081587, 4482.148391805546, 4484.75333856641, 4707.8587642370785, 4733.650909490779, 4747.525247475363, 4401.579131477739, 4470.530594287553, 4684.672072954861, 4874.2444921037295, 4940.554425559371, 3219.0, 3275.4104261233206, 3387.525746395556, 3392.389369913439, 3400.438927576922, 4117.011917139805, 4501.164266512076, 4745.928811067905, 4826.731436224969, 4833.728147770542, 4828.975855120702, 5101.591872650253, 5333.7066461345, 5390.733177703159, 5655.802046928337, 4792.592629626521, 4862.05189987949, 5597.552195824405, 5598.888022239451, 5715.8856822864145, 5111.233315002779, 5336.226247194213, 5478.479544443271, 5522.723863806874, 5535.39110697831, 4719.787609557509, 5178.844634660966, 5500.779968801363, 5620.381758006698, 5641.40765219646, 4184.4351012614125, 4547.8635640930825, 5492.286002819608, 5524.088525393265, 5537.612367134262], "value": 3838.7094662169466, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.6501749125438, 718.6155406857329, 760.3764912771526, 794.451828238515, 810.7486679129469, 15151.469698560553, 16114.516564503054, 16139.515814525554, 16139.596510087255, 16176.11031393273, 14565.927170364133, 14662.486812961548, 14722.41110355587, 14725.484608038721, 14786.0, 666.2404858461358, 674.595242854286, 680.6562685843667, 682.4949537342355, 828.602270909962, 650.4861159683868, 812.0620683111016, 829.9792505187359, 846.2172490446343, 847.0978407995495, 829.6847198064727, 841.3941961787503, 841.6549214821894, 841.8316336732647, 841.9980223534005, 835.9540225287607, 836.4646626159263, 836.7155167243167, 838.8783626374172, 843.3084870406269, 514.1644827155872, 616.3066550131102, 617.8208319587314, 621.564904566805, 645.880512105262, 14651.780223296659, 14678.412863485448, 14678.559643210721, 14704.044237124554, 14706.4852730155, 13546.59360219195, 13960.720785584333, 13961.86038139626, 14030.859691403057, 14206.644833879132, 12264.83484069012, 12650.114491985565, 12660.0, 12745.617631470977, 13011.414486348138, 4488.573585509404, 4507.842225522091, 4553.4991150973365, 4617.884552886171, 4644.930326045112, 107644.0, 109879.0, 110377.0, 110939.44530277378, 111021.0, 10739.516721747541, 10915.945420272848, 10969.067629251604, 11048.723781905355, 11398.316101033965, 21788.237411690516, 21799.34601961912, 21825.0, 21827.12691492354, 21829.12683492674, 7650.349720273728, 7724.802655588393, 7784.190041605194, 7857.978462799913, 7994.160613135653, 4285.10012897295, 4362.258416069212, 4402.295632698753, 4524.909501809945, 4535.183666939916, 3320.833958302075, 3416.197193659488, 3422.709069729051, 3423.1613254752747, 3427.5715535558056, 576.9451902069296, 579.2238400543292, 591.7603370634899, 592.431265984651, 601.8736065426309, 511.0417966313163, 544.6051612580926, 555.7999120316683, 598.0043228025376, 610.0422336931065, 7502.332008120797, 8994.86507702385, 9019.594118264562, 9050.86423703645, 9110.59002344896, 568.7980766827799, 580.3876909860138, 598.964062156272, 610.008735804318, 611.2298503885048, 4188.4136220929095, 4365.192439398721, 4395.164918665508, 4422.557744225616, 4426.712263702849, 165656.0, 168977.0, 169270.0, 173711.0, 176877.1156144211, 91020.54489727509, 100707.98584028278, 133255.33372333075, 137811.31094344464, 141869.0, 107253.78238652853, 119026.28763411335, 120021.0, 120528.0, 122884.0, 7012.894806578008, 8294.83410331802, 8370.790730231793, 8661.350398720248, 8701.651933922825, 49660.0, 63648.36351636425, 65643.0, 65763.67118164379, 66455.66772166108, 82769.82607347931, 96132.51933740286, 102669.48665256627, 103131.0, 103804.48097759765, 10027.247956403153, 11379.317240965569, 11445.656630301104, 11907.987821035309, 11959.641210763692, 12104.152709310263, 12122.878771212176, 12217.93891030539, 12272.140950133406, 12630.242185468902, 10747.516361763895, 11044.392558409365, 11592.246503977214, 11760.88239117598, 11828.82256766132, 11634.412228581577, 11678.941605292259, 12030.699232519257, 12051.035917126785, 12252.75494490123, 11386.829197562209, 11567.826482602935, 11716.94141529287, 11855.229410088317, 11924.986376158124, 8264.108633362403, 9410.623575057194, 9570.665026724031, 9711.10285926476, 9882.604695812088, 9626.759331016501, 9768.413895166314, 9790.167835733757, 9791.951040244754, 9797.804043919225], "value": 7480.951674239874, "unit": "score"}], "name": "dromaeo_css", "value": 7169.6928322699805, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 117.0, 112.0, 113.0, 115.0, 156.0, 163.0, 114.0, 122.0], "value": 123.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 108.0, 131.0, 162.0, 109.0, 129.0, 129.0, 108.0, 161.0, 110.0], "value": 129.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 197.0, 258.0, 188.0, 183.0, 182.0, 184.0, 182.0], "value": 192.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 75.0, 76.0, 153.0, 61.0, 73.0, 81.0, 95.0, 149.0, 58.0], "value": 90.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 140.0, 100.0, 99.0, 113.0, 121.0, 69.0, 71.0, 120.0], "value": 99.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 87.0, 88.0, 84.0, 87.0, 85.0, 87.0, 84.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 97.0, 96.0, 96.0, 117.0, 159.0, 97.0, 96.0], "value": 104.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 84.0, 87.0, 84.0, 87.0, 87.0, 84.0, 87.0, 86.0], "value": 85.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 56.0, 54.0, 54.0, 55.0, 55.0, 55.0, 54.0, 53.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 66.0, 77.0, 73.0, 78.0, 72.0, 71.0, 71.0, 72.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 88.0, 86.0, 88.0, 85.0, 83.0, 85.0, 86.0, 89.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 144.0, 120.0, 160.0, 148.0, 158.0, 145.0, 137.0, 237.0, 138.0], "value": 152.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 184.0, 168.0, 170.0, 170.0, 167.0, 170.0, 169.0, 168.0, 166.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 69.0, 69.0, 69.0, 68.0, 73.0, 86.0, 65.0, 58.0], "value": 69.4, "unit": "score"}], "name": "kraken", "value": 1518.1, "alertThreshold": 2.0}]} 14:04:26 INFO - Return code: 0 14:04:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:04:26 INFO - # TBPL SUCCESS # 14:04:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:04:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:04:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:04:26 INFO - 2016-09-07 14:04:26.818 screenresolution[1965:14255] starting screenresolution argv=screenresolution get 14:04:26 INFO - 2016-09-07 14:04:26.821 screenresolution[1965:14255] Display 0: 1600x1200x32@60 14:04:26 INFO - 2016-09-07 14:04:26.827 screenresolution[1966:14258] starting screenresolution argv=screenresolution list 14:04:26 INFO - Available Modes on Display 0 14:04:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:04:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:04:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:04:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:04:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:04:27 INFO - Intel Iris: 14:04:27 INFO - Chipset Model: Intel Iris 14:04:27 INFO - Type: GPU 14:04:27 INFO - Bus: Built-In 14:04:27 INFO - VRAM (Dynamic, Max): 1536 MB 14:04:27 INFO - Vendor: Intel (0x8086) 14:04:27 INFO - Device ID: 0x0a2e 14:04:27 INFO - Revision ID: 0x0009 14:04:27 INFO - Displays: 14:04:27 INFO - Display: 14:04:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:04:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:04:27 INFO - Display Serial Number: 1600x1200 60 14:04:27 INFO - Main Display: Yes 14:04:27 INFO - Mirror: Off 14:04:27 INFO - Online: Yes 14:04:27 INFO - Rotation: Supported 14:04:27 INFO - Return code: 0 14:04:27 INFO - Running post-action listener: _resource_record_post_action 14:04:27 INFO - [mozharness: 2016-09-07 21:04:27.068857Z] Finished run-tests step (success) 14:04:27 INFO - Running post-run listener: _resource_record_post_run 14:04:27 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 729088; Write bytes: 704866816; Read time: 8; Write time: 1978 14:04:27 INFO - TinderboxPrint: CPU usage
53.6% 14:04:27 INFO - TinderboxPrint: I/O read bytes / time
729,088 / 8 14:04:27 INFO - TinderboxPrint: I/O write bytes / time
704,866,816 / 1,978 14:04:27 INFO - TinderboxPrint: CPU idle
2,251.8 (46.4%) 14:04:27 INFO - TinderboxPrint: CPU system
965.0 (19.9%) 14:04:27 INFO - TinderboxPrint: CPU user
1,632.9 (33.7%) 14:04:27 INFO - TinderboxPrint: Swap in / out
406,454,272 / 0 14:04:27 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 396139008; Write bytes: 392472576; Read time: 29590; Write time: 834 14:04:27 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 729088; Write bytes: 293667328; Read time: 8; Write time: 1095 14:04:27 INFO - Running post-run listener: _upload_blobber_files 14:04:27 INFO - Blob upload gear active. 14:04:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:04:27 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 14:04:27 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:04:27 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:04:27 INFO - (blobuploader) - INFO - Open directory for files ... 14:04:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:04:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:04:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:04:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:04:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:04:28 INFO - (blobuploader) - INFO - Done attempting. 14:04:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:04:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:04:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:04:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:04:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:04:29 INFO - (blobuploader) - INFO - Done attempting. 14:04:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:04:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:04:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:04:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:04:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:04:32 INFO - (blobuploader) - INFO - Done attempting. 14:04:32 INFO - (blobuploader) - INFO - Iteration through files over. 14:04:32 INFO - Return code: 0 14:04:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:04:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:04:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/baffeb8bab75df6c8591eef2b7e8fddd10593c761c236b8ad383947bd7c3573a3d98750ba5a86cc34d9f3ee6836dd9405fea711b196c2f3016071ac438f48d64", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70799dffb1f8a559a74c692a348e995f07f7549d8faab02992c63f1a40cf58abd4fcc97595450c9b9ea9282b25cd6f2a01b62633f90538db6094c4088ec036b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e9e04db2d7ce61ec1054c456865c3f8095bc60a0acc48aa1ec3f4ad9ba6ac22c42d30adc4daafef574b459ecd345c3f1e513da6fc901447ff815da12c76c869"} 14:04:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:04:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:04:32 INFO - Contents: 14:04:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/baffeb8bab75df6c8591eef2b7e8fddd10593c761c236b8ad383947bd7c3573a3d98750ba5a86cc34d9f3ee6836dd9405fea711b196c2f3016071ac438f48d64", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70799dffb1f8a559a74c692a348e995f07f7549d8faab02992c63f1a40cf58abd4fcc97595450c9b9ea9282b25cd6f2a01b62633f90538db6094c4088ec036b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e9e04db2d7ce61ec1054c456865c3f8095bc60a0acc48aa1ec3f4ad9ba6ac22c42d30adc4daafef574b459ecd345c3f1e513da6fc901447ff815da12c76c869"} 14:04:32 INFO - Running post-run listener: copy_logs_to_upload_dir 14:04:32 INFO - Copying logs to upload dir... 14:04:32 INFO - mkdir: /builds/slave/test/build/upload/logs 14:04:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.213019 ========= master_lag: 2.97 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-07 14:04:35.255095) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-09-07 14:04:35.259545) ========= 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.bHJdSwkhsq/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.9snY1JPPP9/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/baffeb8bab75df6c8591eef2b7e8fddd10593c761c236b8ad383947bd7c3573a3d98750ba5a86cc34d9f3ee6836dd9405fea711b196c2f3016071ac438f48d64", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70799dffb1f8a559a74c692a348e995f07f7549d8faab02992c63f1a40cf58abd4fcc97595450c9b9ea9282b25cd6f2a01b62633f90538db6094c4088ec036b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e9e04db2d7ce61ec1054c456865c3f8095bc60a0acc48aa1ec3f4ad9ba6ac22c42d30adc4daafef574b459ecd345c3f1e513da6fc901447ff815da12c76c869"} build_url:https://queue.taskcluster.net/v1/task/MXceWftITIuqnG98ghoOMw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011228 build_url: 'https://queue.taskcluster.net/v1/task/MXceWftITIuqnG98ghoOMw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/baffeb8bab75df6c8591eef2b7e8fddd10593c761c236b8ad383947bd7c3573a3d98750ba5a86cc34d9f3ee6836dd9405fea711b196c2f3016071ac438f48d64", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70799dffb1f8a559a74c692a348e995f07f7549d8faab02992c63f1a40cf58abd4fcc97595450c9b9ea9282b25cd6f2a01b62633f90538db6094c4088ec036b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e9e04db2d7ce61ec1054c456865c3f8095bc60a0acc48aa1ec3f4ad9ba6ac22c42d30adc4daafef574b459ecd345c3f1e513da6fc901447ff815da12c76c869"}' ========= master_lag: 4.49 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-09-07 14:04:39.759682) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 14:04:39.760024) ========= 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.bHJdSwkhsq/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.9snY1JPPP9/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.005164 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 14:04:39.798434) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 14:04:39.798798) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 14:04:39.802958) ========= ========= Total master_lag: 10.36 =========