builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0274 starttime: 1473706334.35 results: success (0) buildid: 20160912094815 builduid: 135d5afa23c34490929c1a836b426f45 revision: b36aa2b45076542947324127f604a1058cc66ecf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 11:52:14.353823) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 11:52:14.354269) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-09-12 11:52:14.354549) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-09-12 11:52:15.370312) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 11:52:15.370615) ========= 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.bw6vZAKdoj/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.FUR7sydwtc/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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-12 11:52:15-- 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% 41.6M=0s 2016-09-12 11:52:15 (41.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.155475 ========= master_lag: 0.16 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 11:52:15.682274) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 11:52:15.682597) ========= 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.bw6vZAKdoj/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.FUR7sydwtc/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.029950 ========= master_lag: 0.12 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 11:52:15.831210) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-09-12 11:52:15.831555) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b36aa2b45076542947324127f604a1058cc66ecf --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 b36aa2b45076542947324127f604a1058cc66ecf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bw6vZAKdoj/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.FUR7sydwtc/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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-12 11:52:15,916 truncating revision to first 12 chars 2016-09-12 11:52:15,917 Setting DEBUG logging. 2016-09-12 11:52:15,917 attempt 1/10 2016-09-12 11:52:15,917 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b36aa2b45076?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-12 11:52:16,601 unpacking tar archive at: fx-team-b36aa2b45076/testing/mozharness/ program finished with exit code 0 elapsedTime=0.963422 ========= master_lag: 4.24 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-09-12 11:52:21.034862) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 11:52:21.039216) ========= script_repo_revision: b36aa2b45076542947324127f604a1058cc66ecf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 11:52:21.039611) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 11:52:21.039881) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 11:52:21.081245) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-09-12 11:52:21.081555) ========= /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.bw6vZAKdoj/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.FUR7sydwtc/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:52:21 INFO - MultiFileLogger online at 20160912 11:52:21 in /builds/slave/test 11:52: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 11:52:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:52:21 INFO - {'append_to_log': False, 11:52:21 INFO - 'base_work_dir': '/builds/slave/test', 11:52:21 INFO - 'blob_upload_branch': 'Fx-Team', 11:52:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:52:21 INFO - 'branch': 'Fx-Team', 11:52:21 INFO - 'buildbot_json_path': 'buildprops.json', 11:52:21 INFO - 'config_files': ('talos/mac_config.py',), 11:52:21 INFO - 'default_actions': ('clobber', 11:52:21 INFO - 'read-buildbot-config', 11:52:21 INFO - 'download-and-extract', 11:52:21 INFO - 'populate-webroot', 11:52:21 INFO - 'create-virtualenv', 11:52:21 INFO - 'install', 11:52:21 INFO - 'run-tests'), 11:52:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:52:21 INFO - 'download_minidump_stackwalk': True, 11:52:21 INFO - 'download_symbols': 'ondemand', 11:52:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:52:21 INFO - 'tooltool.py': '/tools/tooltool.py', 11:52:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:52:21 INFO - '/tools/misc-python/virtualenv.py')}, 11:52:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:52:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:52:21 INFO - 'installer_path': 'installer.exe', 11:52:21 INFO - 'log_level': 'info', 11:52:21 INFO - 'log_name': 'talos', 11:52:21 INFO - 'log_to_console': True, 11:52:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:52:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:52:21 INFO - 'opt_config_files': (), 11:52:21 INFO - 'pip_index': False, 11:52:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:52:21 INFO - 'cmd': ('bash', 11:52:21 INFO - '-c', 11:52:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:52:21 INFO - 'enabled': True, 11:52:21 INFO - 'halt_on_failure': False, 11:52:21 INFO - 'name': 'check_screen_resolution'},), 11:52:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:52:21 INFO - 'cmd': ('bash', 11:52:21 INFO - '-c', 11:52:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:52:21 INFO - 'enabled': True, 11:52:21 INFO - 'halt_on_failure': False, 11:52:21 INFO - 'name': 'check_screen_resolution'},), 11:52:21 INFO - 'run_cmd_checks_enabled': True, 11:52:21 INFO - 'sps_profile': False, 11:52:21 INFO - 'sps_profile_interval': 0, 11:52:21 INFO - 'suite': 'dromaeojs', 11:52:21 INFO - 'system_bits': '32', 11:52:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:52:21 INFO - 'title': 't-yosemite-r7-0274', 11:52:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:52:21 INFO - 'use_talos_json': True, 11:52:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:52:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:52:21 INFO - 'work_dir': 'build'} 11:52:21 INFO - [mozharness: 2016-09-12 18:52:21.276240Z] Running clobber step. 11:52:21 INFO - Running pre-action listener: _resource_record_pre_action 11:52:21 INFO - Running main action method: clobber 11:52:21 INFO - rmtree: /builds/slave/test/build 11:52:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:52:23 INFO - Running post-action listener: _resource_record_post_action 11:52:23 INFO - [mozharness: 2016-09-12 18:52:23.217490Z] Finished clobber step (success) 11:52:23 INFO - [mozharness: 2016-09-12 18:52:23.217616Z] Running read-buildbot-config step. 11:52:23 INFO - Running pre-action listener: _resource_record_pre_action 11:52:23 INFO - Running main action method: read_buildbot_config 11:52:23 INFO - Using buildbot properties: 11:52:23 INFO - { 11:52:23 INFO - "project": "", 11:52:23 INFO - "product": "firefox", 11:52:23 INFO - "script_repo_revision": "production", 11:52:23 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 11:52:23 INFO - "repository": "", 11:52:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 11:52:23 INFO - "buildid": "20160912094815", 11:52:23 INFO - "pgo_build": "False", 11:52:23 INFO - "basedir": "/builds/slave/test", 11:52:23 INFO - "buildnumber": 936, 11:52:23 INFO - "slavename": "t-yosemite-r7-0274", 11:52:23 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 11:52:23 INFO - "platform": "yosemite_r7", 11:52:23 INFO - "scheduler": "tests-fx-team-macosx64-talos", 11:52:23 INFO - "branch": "fx-team", 11:52:23 INFO - "repo_path": "integration/fx-team", 11:52:23 INFO - "revision": "b36aa2b45076542947324127f604a1058cc66ecf", 11:52:23 INFO - "stage_platform": "macosx64", 11:52:23 INFO - "builduid": "135d5afa23c34490929c1a836b426f45", 11:52:23 INFO - "slavebuilddir": "test" 11:52:23 INFO - } 11:52:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/FlO45dbES7OIoX7ToNJ44w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 11:52:23 INFO - Running post-action listener: _resource_record_post_action 11:52:23 INFO - [mozharness: 2016-09-12 18:52:23.220951Z] Finished read-buildbot-config step (success) 11:52:23 INFO - [mozharness: 2016-09-12 18:52:23.221052Z] Running download-and-extract step. 11:52:23 INFO - Running pre-action listener: _resource_record_pre_action 11:52:23 INFO - Running main action method: download_and_extract 11:52:23 INFO - mkdir: /builds/slave/test/build/tests 11:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:23 INFO - trying https://queue.taskcluster.net/v1/task/FlO45dbES7OIoX7ToNJ44w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 11:52:23 INFO - Downloading https://queue.taskcluster.net/v1/task/FlO45dbES7OIoX7ToNJ44w/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 11:52:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FlO45dbES7OIoX7ToNJ44w/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 11:52:24 INFO - Downloaded 1199 bytes. 11:52:24 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:52:24 INFO - Using the following test package requirements: 11:52:24 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:52:24 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:52:24 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 11:52:24 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:52:24 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 11:52:24 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:52:24 INFO - u'jsshell-mac.zip'], 11:52:24 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:52:24 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 11:52:24 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:52:24 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:52:24 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 11:52:24 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:52:24 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 11:52:24 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:52:24 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 11:52:24 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:52:24 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 11:52:24 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:52:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FlO45dbES7OIoX7ToNJ44w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:52:25 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:52:32 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 11:52:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FlO45dbES7OIoX7ToNJ44w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:52:33 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:52:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FlO45dbES7OIoX7ToNJ44w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:52:41 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:52:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:42 INFO - trying https://queue.taskcluster.net/v1/task/FlO45dbES7OIoX7ToNJ44w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:52:42 INFO - Downloading https://queue.taskcluster.net/v1/task/FlO45dbES7OIoX7ToNJ44w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 11:52:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FlO45dbES7OIoX7ToNJ44w/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 11:52:45 INFO - Downloaded 111635260 bytes. 11:52:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FlO45dbES7OIoX7ToNJ44w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:52:45 INFO - mkdir: /builds/slave/test/properties 11:52:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:52:45 INFO - Writing to file /builds/slave/test/properties/build_url 11:52:45 INFO - Contents: 11:52:45 INFO - build_url:https://queue.taskcluster.net/v1/task/FlO45dbES7OIoX7ToNJ44w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:52:46 INFO - Running post-action listener: _resource_record_post_action 11:52:46 INFO - Running post-action listener: set_extra_try_arguments 11:52:46 INFO - [mozharness: 2016-09-12 18:52:46.992704Z] Finished download-and-extract step (success) 11:52:46 INFO - [mozharness: 2016-09-12 18:52:46.992801Z] Running populate-webroot step. 11:52:46 INFO - Running pre-action listener: _resource_record_pre_action 11:52:46 INFO - Running main action method: populate_webroot 11:52:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:52:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:52:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:52:47 INFO - '--tppagecycles', 11:52:47 INFO - '7'], 11:52:47 INFO - 'tests': ['tsvgm']}}, 11:52:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:52:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:52:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:52:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:52:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:52:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:52:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:47 INFO - 'talos_options': ['--disable-e10s'], 11:52:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:52:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:52:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:47 INFO - 'talos_options': ['--disable-e10s'], 11:52:47 INFO - 'tests': ['damp', 'tps']}, 11:52:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:47 INFO - 'tests': ['damp', 'tps']}, 11:52:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:52:47 INFO - 'tests': ['dromaeo_dom']}, 11:52:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:52:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:52:47 INFO - 'tests': ['basic_compositor_video']}, 11:52:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:52:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:52:47 INFO - 'tests': ['a11yr', 11:52:47 INFO - 'ts_paint', 11:52:47 INFO - 'tpaint', 11:52:47 INFO - 'sessionrestore', 11:52:47 INFO - 'sessionrestore_no_auto_restore', 11:52:47 INFO - 'tabpaint']}, 11:52:47 INFO - 'other-e10s': {'tests': ['a11yr', 11:52:47 INFO - 'ts_paint', 11:52:47 INFO - 'tpaint', 11:52:47 INFO - 'sessionrestore', 11:52:47 INFO - 'sessionrestore_no_auto_restore', 11:52:47 INFO - 'tabpaint']}, 11:52:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:52:47 INFO - 'ts_paint', 11:52:47 INFO - 'tpaint', 11:52:47 INFO - 'sessionrestore', 11:52:47 INFO - 'sessionrestore_no_auto_restore', 11:52:47 INFO - 'tabpaint']}, 11:52:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:52:47 INFO - 'ts_paint', 11:52:47 INFO - 'tpaint', 11:52:47 INFO - 'sessionrestore', 11:52:47 INFO - 'sessionrestore_no_auto_restore', 11:52:47 INFO - 'tabpaint']}, 11:52:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:52:47 INFO - 'tests': ['a11yr', 11:52:47 INFO - 'ts_paint', 11:52:47 INFO - 'tpaint', 11:52:47 INFO - 'sessionrestore', 11:52:47 INFO - 'sessionrestore_no_auto_restore', 11:52:47 INFO - 'tabpaint']}, 11:52:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:52:47 INFO - 'tests': ['a11yr', 11:52:47 INFO - 'ts_paint', 11:52:47 INFO - 'tpaint', 11:52:47 INFO - 'sessionrestore', 11:52:47 INFO - 'sessionrestore_no_auto_restore', 11:52:47 INFO - 'tabpaint']}, 11:52:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:52:47 INFO - 'tests': ['tsvgx', 11:52:47 INFO - 'tsvgr_opacity', 11:52:47 INFO - 'tart', 11:52:47 INFO - 'tscrollx', 11:52:47 INFO - 'cart']}, 11:52:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:52:47 INFO - 'tsvgr_opacity', 11:52:47 INFO - 'tart', 11:52:47 INFO - 'tscrollx', 11:52:47 INFO - 'cart']}, 11:52:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:47 INFO - 'talos_options': ['--disable-e10s'], 11:52:47 INFO - 'tests': ['tp5o']}, 11:52:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:47 INFO - 'tests': ['tp5o']}, 11:52:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:47 INFO - 'talos_options': ['--disable-e10s', 11:52:47 INFO - '--xperf_path', 11:52:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:52:47 INFO - 'tests': ['tp5n']}, 11:52:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:47 INFO - 'talos_options': ['--xperf_path', 11:52:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:52:47 INFO - 'tests': ['tp5n']}}, 11:52:47 INFO - 'talos.zip': {'path': '', 11:52:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:52:47 INFO - Running post-action listener: _resource_record_post_action 11:52:47 INFO - [mozharness: 2016-09-12 18:52:47.011996Z] Finished populate-webroot step (success) 11:52:47 INFO - [mozharness: 2016-09-12 18:52:47.012120Z] Running create-virtualenv step. 11:52:47 INFO - Running pre-action listener: _resource_record_pre_action 11:52:47 INFO - Running main action method: create_virtualenv 11:52:47 INFO - Creating virtualenv /builds/slave/test/build/venv 11:52:47 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 11:52:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:52:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:52:47 INFO - Using real prefix '/tools/python27' 11:52:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:52:47 INFO - Installing distribute.............................................................................................................................................................................................done. 11:52:50 INFO - Installing pip.................done. 11:52:50 INFO - Return code: 0 11:52:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:52:50 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')]} 11:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:50 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')]} 11:52:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:50 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 0x107780490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dac74d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107594d30>, '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 0x1073badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10758cb20>, '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.bw6vZAKdoj/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.FUR7sydwtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:52:50 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 11:52:50 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 11:52:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bw6vZAKdoj/Render', 11:52:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:50 INFO - 'HOME': '/Users/cltbld', 11:52:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:50 INFO - 'LOGNAME': 'cltbld', 11:52:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:50 INFO - 'MOZ_NO_REMOTE': '1', 11:52:50 INFO - 'NO_EM_RESTART': '1', 11:52:50 INFO - 'PAGER': '/bin/cat', 11:52:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:50 INFO - 'PWD': '/builds/slave/test', 11:52:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:50 INFO - 'SHELL': '/bin/bash', 11:52:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FUR7sydwtc/Listeners', 11:52:50 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:52:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:50 INFO - 'USER': 'cltbld', 11:52:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:50 INFO - 'XPC_FLAGS': '0x0', 11:52:50 INFO - 'XPC_SERVICE_NAME': '0', 11:52:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:51 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 11:52:51 INFO - Cleaning up... 11:52:51 INFO - Return code: 0 11:52:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:52: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')]} 11:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52: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')]} 11:52:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52: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', '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 0x107780490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dac74d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107594d30>, '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 0x1073badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10758cb20>, '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.bw6vZAKdoj/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.FUR7sydwtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:52: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:52: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 psutil>=3.1.1 11:52:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bw6vZAKdoj/Render', 11:52:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:51 INFO - 'HOME': '/Users/cltbld', 11:52:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:51 INFO - 'LOGNAME': 'cltbld', 11:52:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:51 INFO - 'MOZ_NO_REMOTE': '1', 11:52:51 INFO - 'NO_EM_RESTART': '1', 11:52:51 INFO - 'PAGER': '/bin/cat', 11:52:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:51 INFO - 'PWD': '/builds/slave/test', 11:52:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:51 INFO - 'SHELL': '/bin/bash', 11:52:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FUR7sydwtc/Listeners', 11:52:51 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:52:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:51 INFO - 'USER': 'cltbld', 11:52:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:51 INFO - 'XPC_FLAGS': '0x0', 11:52:51 INFO - 'XPC_SERVICE_NAME': '0', 11:52:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:51 INFO - Downloading/unpacking psutil>=3.1.1 11:52: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 11:52: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 11:52: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 11:52: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 11:52: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 11:52: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 11:52:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:52:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:52:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:52:55 INFO - Installing collected packages: psutil 11:52:55 INFO - Running setup.py install for psutil 11:52:55 INFO - building 'psutil._psutil_osx' extension 11:52:55 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 11:52:55 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 11:52:55 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 11:52:55 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 11:52:55 INFO - building 'psutil._psutil_posix' extension 11:52:55 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 11:52:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:52:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:52:55 INFO - ^ 11:52:56 INFO - 1 warning generated. 11:52:56 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 11:52:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:52:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:52:56 INFO - Successfully installed psutil 11:52:56 INFO - Cleaning up... 11:52:56 INFO - Return code: 0 11:52:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107780490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dac74d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107594d30>, '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 0x1073badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10758cb20>, '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.bw6vZAKdoj/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.FUR7sydwtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:52:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:52:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bw6vZAKdoj/Render', 11:52:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:56 INFO - 'HOME': '/Users/cltbld', 11:52:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:56 INFO - 'LOGNAME': 'cltbld', 11:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:56 INFO - 'MOZ_NO_REMOTE': '1', 11:52:56 INFO - 'NO_EM_RESTART': '1', 11:52:56 INFO - 'PAGER': '/bin/cat', 11:52:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:56 INFO - 'PWD': '/builds/slave/test', 11:52:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:56 INFO - 'SHELL': '/bin/bash', 11:52:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FUR7sydwtc/Listeners', 11:52:56 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:52:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:56 INFO - 'USER': 'cltbld', 11:52:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:56 INFO - 'XPC_FLAGS': '0x0', 11:52:56 INFO - 'XPC_SERVICE_NAME': '0', 11:52:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:52:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:52:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:52:58 INFO - Installing collected packages: mozsystemmonitor 11:52:58 INFO - Running setup.py install for mozsystemmonitor 11:52:59 INFO - Successfully installed mozsystemmonitor 11:52:59 INFO - Cleaning up... 11:52:59 INFO - Return code: 0 11:52:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:52:59 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')]} 11:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:59 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')]} 11:52:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:59 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 0x107780490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dac74d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107594d30>, '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 0x1073badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10758cb20>, '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.bw6vZAKdoj/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.FUR7sydwtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:52:59 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 11:52:59 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 11:52:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bw6vZAKdoj/Render', 11:52:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:59 INFO - 'HOME': '/Users/cltbld', 11:52:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:59 INFO - 'LOGNAME': 'cltbld', 11:52:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:59 INFO - 'MOZ_NO_REMOTE': '1', 11:52:59 INFO - 'NO_EM_RESTART': '1', 11:52:59 INFO - 'PAGER': '/bin/cat', 11:52:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:59 INFO - 'PWD': '/builds/slave/test', 11:52:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:59 INFO - 'SHELL': '/bin/bash', 11:52:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FUR7sydwtc/Listeners', 11:52:59 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:52:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:59 INFO - 'USER': 'cltbld', 11:52:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:59 INFO - 'XPC_FLAGS': '0x0', 11:52:59 INFO - 'XPC_SERVICE_NAME': '0', 11:52:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:59 INFO - Downloading/unpacking jsonschema==2.5.1 11:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:53:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:53:02 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 11:53:02 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 11:53:02 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 11:53:02 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 11:53:02 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 11:53:02 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 11:53:02 INFO - Downloading functools32-3.2.3-2.tar.gz 11:53:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:53:02 INFO - warning: no files found matching '*.txt' 11:53:02 INFO - no previously-included directories found matching 'build' 11:53:02 INFO - no previously-included directories found matching 'dist' 11:53:02 INFO - no previously-included directories found matching '.git*' 11:53:02 INFO - Installing collected packages: jsonschema, functools32 11:53:02 INFO - Running setup.py install for functools32 11:53:02 INFO - warning: no files found matching '*.txt' 11:53:02 INFO - no previously-included directories found matching 'build' 11:53:02 INFO - no previously-included directories found matching 'dist' 11:53:02 INFO - no previously-included directories found matching '.git*' 11:53:02 INFO - Successfully installed jsonschema functools32 11:53:02 INFO - Cleaning up... 11:53:02 INFO - Return code: 0 11:53:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:53: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')]} 11:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53: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')]} 11:53:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:02 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 0x107780490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dac74d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107594d30>, '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 0x1073badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10758cb20>, '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.bw6vZAKdoj/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.FUR7sydwtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:53:02 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 11:53:02 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 11:53:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bw6vZAKdoj/Render', 11:53:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:53:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:53:02 INFO - 'HOME': '/Users/cltbld', 11:53:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:53:02 INFO - 'LOGNAME': 'cltbld', 11:53:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:02 INFO - 'MOZ_NO_REMOTE': '1', 11:53:02 INFO - 'NO_EM_RESTART': '1', 11:53:02 INFO - 'PAGER': '/bin/cat', 11:53:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:53:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:02 INFO - 'PWD': '/builds/slave/test', 11:53:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:53:02 INFO - 'SHELL': '/bin/bash', 11:53:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FUR7sydwtc/Listeners', 11:53:02 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:53:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:53:02 INFO - 'USER': 'cltbld', 11:53:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:53:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:53:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:02 INFO - 'XPC_FLAGS': '0x0', 11:53:02 INFO - 'XPC_SERVICE_NAME': '0', 11:53:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:53:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:53:03 INFO - Cleaning up... 11:53:03 INFO - Return code: 0 11:53:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:53:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107780490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dac74d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107594d30>, '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 0x1073badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10758cb20>, '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.bw6vZAKdoj/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.FUR7sydwtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:53:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:53:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:53:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bw6vZAKdoj/Render', 11:53:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:53:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:53:03 INFO - 'HOME': '/Users/cltbld', 11:53:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:53:03 INFO - 'LOGNAME': 'cltbld', 11:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:03 INFO - 'MOZ_NO_REMOTE': '1', 11:53:03 INFO - 'NO_EM_RESTART': '1', 11:53:03 INFO - 'PAGER': '/bin/cat', 11:53:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:03 INFO - 'PWD': '/builds/slave/test', 11:53:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:53:03 INFO - 'SHELL': '/bin/bash', 11:53:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FUR7sydwtc/Listeners', 11:53:03 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:53:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:53:03 INFO - 'USER': 'cltbld', 11:53:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:53:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:03 INFO - 'XPC_FLAGS': '0x0', 11:53:03 INFO - 'XPC_SERVICE_NAME': '0', 11:53:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:53:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:03 INFO - Downloading/unpacking blobuploader==1.2.4 11:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:53:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:05 INFO - Downloading blobuploader-1.2.4.tar.gz 11:53:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:53:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:53:05 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 11:53:05 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 11:53:05 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 11:53:05 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 11:53:05 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 11:53:05 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 11:53:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:53:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:53:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:53:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:53:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:07 INFO - Downloading docopt-0.6.1.tar.gz 11:53:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:53:07 INFO - Installing collected packages: blobuploader, requests, docopt 11:53:07 INFO - Running setup.py install for blobuploader 11:53:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:53:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:53:07 INFO - Running setup.py install for requests 11:53:07 INFO - Running setup.py install for docopt 11:53:07 INFO - Successfully installed blobuploader requests docopt 11:53:07 INFO - Cleaning up... 11:53:08 INFO - Return code: 0 11:53:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:53:08 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')]} 11:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:08 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')]} 11:53:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:08 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 0x107780490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dac74d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107594d30>, '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 0x1073badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10758cb20>, '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.bw6vZAKdoj/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.FUR7sydwtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:53:08 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 11:53:08 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 11:53:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bw6vZAKdoj/Render', 11:53:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:53:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:53:08 INFO - 'HOME': '/Users/cltbld', 11:53:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:53:08 INFO - 'LOGNAME': 'cltbld', 11:53:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:08 INFO - 'MOZ_NO_REMOTE': '1', 11:53:08 INFO - 'NO_EM_RESTART': '1', 11:53:08 INFO - 'PAGER': '/bin/cat', 11:53:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:53:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:08 INFO - 'PWD': '/builds/slave/test', 11:53:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:53:08 INFO - 'SHELL': '/bin/bash', 11:53:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FUR7sydwtc/Listeners', 11:53:08 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:53:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:53:08 INFO - 'USER': 'cltbld', 11:53:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:53:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:53:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:08 INFO - 'XPC_FLAGS': '0x0', 11:53:08 INFO - 'XPC_SERVICE_NAME': '0', 11:53:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:53:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:53:08 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-JWRo64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:53:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:53:08 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-7E2hVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:53:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:53:08 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-JqnklW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:53:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:53:08 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-F3aU6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:53:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:53:08 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-HpKQqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:53:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:53:08 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-sqjbkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:53:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:53:08 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-sJU5xW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:53:09 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-tXXAWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:53:09 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-JXbX09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:53:09 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-i7bVfM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:53:09 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-SCfnPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:53:09 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-M9HfDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:53:09 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-3yVxrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:53:09 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-nESTOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:53:09 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-wY1Frn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:53:09 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-tqqhXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:53:09 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-fGc_i0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:53:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:53:10 INFO - Running setup.py install for manifestparser 11:53:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:53:10 INFO - Running setup.py install for mozcrash 11:53:10 INFO - Running setup.py install for mozdebug 11:53:10 INFO - Running setup.py install for mozdevice 11:53:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:53:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:53:10 INFO - Running setup.py install for mozfile 11:53:10 INFO - Running setup.py install for mozhttpd 11:53:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:53:10 INFO - Running setup.py install for mozinfo 11:53:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:53:11 INFO - Running setup.py install for mozInstall 11:53:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:53:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:53:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:53:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:53:11 INFO - Running setup.py install for mozleak 11:53:11 INFO - Running setup.py install for mozlog 11:53:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:53:11 INFO - Running setup.py install for moznetwork 11:53:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:53:11 INFO - Running setup.py install for mozprocess 11:53:11 INFO - Running setup.py install for mozprofile 11:53:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:53:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:53:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:53:11 INFO - Running setup.py install for mozrunner 11:53:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:53:12 INFO - Running setup.py install for mozscreenshot 11:53:12 INFO - Running setup.py install for moztest 11:53:12 INFO - Running setup.py install for mozversion 11:53:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:53:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:53:12 INFO - Cleaning up... 11:53:12 INFO - Return code: 0 11:53:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:53: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')]} 11:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53: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')]} 11:53:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:12 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 0x107780490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dac74d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107594d30>, '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 0x1073badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10758cb20>, '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.bw6vZAKdoj/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.FUR7sydwtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:53:12 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 11:53:12 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 11:53:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bw6vZAKdoj/Render', 11:53:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:53:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:53:12 INFO - 'HOME': '/Users/cltbld', 11:53:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:53:12 INFO - 'LOGNAME': 'cltbld', 11:53:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:12 INFO - 'MOZ_NO_REMOTE': '1', 11:53:12 INFO - 'NO_EM_RESTART': '1', 11:53:12 INFO - 'PAGER': '/bin/cat', 11:53:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:53:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:12 INFO - 'PWD': '/builds/slave/test', 11:53:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:53:12 INFO - 'SHELL': '/bin/bash', 11:53:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FUR7sydwtc/Listeners', 11:53:12 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:53:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:53:12 INFO - 'USER': 'cltbld', 11:53:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:53:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:53:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:12 INFO - 'XPC_FLAGS': '0x0', 11:53:12 INFO - 'XPC_SERVICE_NAME': '0', 11:53:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:53:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:53:12 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-PKJI2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:53:12 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)) 11:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:53:12 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-agPxh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:53:13 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)) 11:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:53:13 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-YrUETP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:53:13 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)) 11:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:53:13 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-CHEi_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:53:13 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)) 11:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:53:13 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-W5jL6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:53:13 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)) 11:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:53:13 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-vHvROe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:53:13 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)) 11:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:53:13 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-ZFOaON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:53:13 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)) 11:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:53:13 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-SH0paP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:53:13 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)) 11:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:53:13 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-RRSjOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:53:13 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)) 11:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:53:13 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-rIvTCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:53:13 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)) 11:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:53:13 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-Lo4gXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:53:14 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)) 11:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:53:14 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-mFDav1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:53:14 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)) 11:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:53:14 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-EdJ5ar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:53:14 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)) 11:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:53:14 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-DuTARK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:53:14 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)) 11:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:53:14 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-TdPUAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:53:14 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)) 11:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:53:14 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-aDpfqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:53:14 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)) 11:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:53:14 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-Qx17Q0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:53:14 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)) 11:53:14 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)) 11:53:14 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)) 11:53:14 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)) 11:53:14 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)) 11:53:14 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)) 11:53:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:53:14 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 11:53:14 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 11:53:14 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 11:53:14 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 11:53:14 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 11:53:14 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 11:53:17 INFO - Downloading blessings-1.6.tar.gz 11:53:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:53:17 INFO - Installing collected packages: blessings 11:53:17 INFO - Running setup.py install for blessings 11:53:17 INFO - Successfully installed blessings 11:53:17 INFO - Cleaning up... 11:53:17 INFO - Return code: 0 11:53:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:53:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:53:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:53:17 INFO - Reading from file tmpfile_stdout 11:53:17 INFO - Current package versions: 11:53:17 INFO - blessings == 1.6 11:53:17 INFO - blobuploader == 1.2.4 11:53:17 INFO - docopt == 0.6.1 11:53:17 INFO - functools32 == 3.2.3-2 11:53:17 INFO - jsonschema == 2.5.1 11:53:17 INFO - manifestparser == 1.1 11:53:17 INFO - mozInstall == 1.12 11:53:17 INFO - mozcrash == 0.17 11:53:17 INFO - mozdebug == 0.1 11:53:17 INFO - mozdevice == 0.48 11:53:17 INFO - mozfile == 1.2 11:53:17 INFO - mozhttpd == 0.7 11:53:17 INFO - mozinfo == 0.9 11:53:17 INFO - mozleak == 0.1 11:53:17 INFO - mozlog == 3.3 11:53:17 INFO - moznetwork == 0.27 11:53:17 INFO - mozprocess == 0.23 11:53:17 INFO - mozprofile == 0.28 11:53:17 INFO - mozrunner == 6.12 11:53:17 INFO - mozscreenshot == 0.1 11:53:17 INFO - mozsystemmonitor == 0.3 11:53:17 INFO - moztest == 0.7 11:53:17 INFO - mozversion == 1.4 11:53:17 INFO - psutil == 3.1.1 11:53:17 INFO - requests == 1.2.3 11:53:17 INFO - wsgiref == 0.1.2 11:53:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:53:17 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')]} 11:53:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:17 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')]} 11:53:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:17 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 0x107780490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dac74d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107594d30>, '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 0x1073badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10758cb20>, '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.bw6vZAKdoj/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.FUR7sydwtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:53:17 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 11:53:17 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 11:53:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bw6vZAKdoj/Render', 11:53:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:53:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:53:17 INFO - 'HOME': '/Users/cltbld', 11:53:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:53:17 INFO - 'LOGNAME': 'cltbld', 11:53:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:17 INFO - 'MOZ_NO_REMOTE': '1', 11:53:17 INFO - 'NO_EM_RESTART': '1', 11:53:17 INFO - 'PAGER': '/bin/cat', 11:53:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:53:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:17 INFO - 'PWD': '/builds/slave/test', 11:53:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:53:17 INFO - 'SHELL': '/bin/bash', 11:53:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FUR7sydwtc/Listeners', 11:53:17 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:53:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:53:17 INFO - 'USER': 'cltbld', 11:53:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:53:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:53:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:17 INFO - 'XPC_FLAGS': '0x0', 11:53:17 INFO - 'XPC_SERVICE_NAME': '0', 11:53:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:53:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:17 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)) 11:53:17 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)) 11:53:17 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)) 11:53:17 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)) 11:53:17 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)) 11:53:17 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)) 11:53:17 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)) 11:53:17 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)) 11:53:17 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)) 11:53:17 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)) 11:53:17 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)) 11:53:17 INFO - Cleaning up... 11:53:17 INFO - Return code: 0 11:53:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:53:17 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')]} 11:53:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:18 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 0x107780490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dac74d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107594d30>, '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 0x1073badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10758cb20>, '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.bw6vZAKdoj/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.FUR7sydwtc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:53:18 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 11:53:18 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 11:53:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bw6vZAKdoj/Render', 11:53:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:53:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:53:18 INFO - 'HOME': '/Users/cltbld', 11:53:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:53:18 INFO - 'LOGNAME': 'cltbld', 11:53:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:18 INFO - 'MOZ_NO_REMOTE': '1', 11:53:18 INFO - 'NO_EM_RESTART': '1', 11:53:18 INFO - 'PAGER': '/bin/cat', 11:53:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:53:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:18 INFO - 'PWD': '/builds/slave/test', 11:53:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:53:18 INFO - 'SHELL': '/bin/bash', 11:53:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FUR7sydwtc/Listeners', 11:53:18 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:53:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:53:18 INFO - 'USER': 'cltbld', 11:53:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:53:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:53:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:18 INFO - 'XPC_FLAGS': '0x0', 11:53:18 INFO - 'XPC_SERVICE_NAME': '0', 11:53:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:53:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:53:18 INFO - Cleaning up... 11:53:18 INFO - Return code: 0 11:53:18 INFO - Running post-action listener: _resource_record_post_action 11:53:18 INFO - Running post-action listener: _start_resource_monitoring 11:53:18 INFO - Starting resource monitoring. 11:53:18 INFO - [mozharness: 2016-09-12 18:53:18.292706Z] Finished create-virtualenv step (success) 11:53:18 INFO - [mozharness: 2016-09-12 18:53:18.293715Z] Running install step. 11:53:18 INFO - Running pre-action listener: _resource_record_pre_action 11:53:18 INFO - Running main action method: install 11:53:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:53:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:53:18 INFO - Reading from file tmpfile_stdout 11:53:18 INFO - Detecting whether we're running mozinstall >=1.0... 11:53:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:53:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:53:18 INFO - Reading from file tmpfile_stdout 11:53:18 INFO - Output received: 11:53:18 INFO - Usage: mozinstall [options] installer 11:53:18 INFO - Options: 11:53:18 INFO - -h, --help show this help message and exit 11:53:18 INFO - -d DEST, --destination=DEST 11:53:18 INFO - Directory to install application into. [default: 11:53:18 INFO - "/builds/slave/test"] 11:53:18 INFO - --app=APP Application being installed. [default: firefox] 11:53:18 INFO - mkdir: /builds/slave/test/build/application 11:53:18 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'] 11:53:18 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 11:53:53 INFO - Reading from file tmpfile_stdout 11:53:53 INFO - Output received: 11:53:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:53:53 INFO - Running post-action listener: _resource_record_post_action 11:53:53 INFO - [mozharness: 2016-09-12 18:53:53.087422Z] Finished install step (success) 11:53:53 INFO - [mozharness: 2016-09-12 18:53:53.087519Z] Running run-tests step. 11:53:53 INFO - Running pre-action listener: _resource_record_pre_action 11:53:53 INFO - Running main action method: run_tests 11:53:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:53:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:53:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:53:53 INFO - 2016-09-12 11:53:53.100 screenresolution[1640:7734] starting screenresolution argv=screenresolution get 11:53:53 INFO - 2016-09-12 11:53:53.102 screenresolution[1640:7734] Display 0: 1600x1200x32@60 11:53:53 INFO - 2016-09-12 11:53:53.107 screenresolution[1641:7737] starting screenresolution argv=screenresolution list 11:53:53 INFO - Available Modes on Display 0 11:53:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:53:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:53:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:53:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:53:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:53:53 INFO - Intel Iris: 11:53:53 INFO - Chipset Model: Intel Iris 11:53:53 INFO - Type: GPU 11:53:53 INFO - Bus: Built-In 11:53:53 INFO - VRAM (Dynamic, Max): 1536 MB 11:53:53 INFO - Vendor: Intel (0x8086) 11:53:53 INFO - Device ID: 0x0a2e 11:53:53 INFO - Revision ID: 0x0009 11:53:53 INFO - Displays: 11:53:53 INFO - Display: 11:53:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:53:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:53:53 INFO - Display Serial Number: 1600x1200 60 11:53:53 INFO - Main Display: Yes 11:53:53 INFO - Mirror: Off 11:53:53 INFO - Online: Yes 11:53:53 INFO - Rotation: Supported 11:53:53 INFO - Return code: 0 11:53:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:53:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:53:53 INFO - Python 2.7.3 11:53:53 INFO - Return code: 0 11:53:53 INFO - grabbing minidump binary from tooltool 11:53:53 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')]} 11:53:53 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 0x107594d30>, '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 0x1073badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10758cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:53:53 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 11:53:53 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 11:53:53 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 11:53:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:53:53 INFO - Return code: 0 11:53:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:53:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:53:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:53:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:53:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:53:53 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/FlO45dbES7OIoX7ToNJ44w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0274', '--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 11:53:53 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/FlO45dbES7OIoX7ToNJ44w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0274 --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 11:53:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bw6vZAKdoj/Render', 11:53:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:53:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:53:53 INFO - 'HOME': '/Users/cltbld', 11:53:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:53:53 INFO - 'LOGNAME': 'cltbld', 11:53:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:53:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:53:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:53 INFO - 'MOZ_NO_REMOTE': '1', 11:53:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:53:53 INFO - 'NO_EM_RESTART': '1', 11:53:53 INFO - 'PAGER': '/bin/cat', 11:53:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:53:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:53 INFO - 'PWD': '/builds/slave/test', 11:53:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:53:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:53:53 INFO - 'SHELL': '/bin/bash', 11:53:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FUR7sydwtc/Listeners', 11:53:53 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:53:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:53:53 INFO - 'USER': 'cltbld', 11:53:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:53:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:53:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:53 INFO - 'XPC_FLAGS': '0x0', 11:53:53 INFO - 'XPC_SERVICE_NAME': '0', 11:53:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:53:53 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/FlO45dbES7OIoX7ToNJ44w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0274', '--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 11:53:53 INFO - mozversion application_buildid: 20160912094815 11:53:53 INFO - mozversion application_changeset: b36aa2b45076542947324127f604a1058cc66ecf 11:53:53 INFO - mozversion application_display_name: Nightly 11:53:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:53:53 INFO - mozversion application_name: Firefox 11:53:53 INFO - mozversion application_remotingname: firefox 11:53:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:53:53 INFO - mozversion application_vendor: Mozilla 11:53:53 INFO - mozversion application_version: 51.0a1 11:53:53 INFO - mozversion platform_buildid: 20160912094815 11:53:53 INFO - mozversion platform_changeset: b36aa2b45076542947324127f604a1058cc66ecf 11:53:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:53:53 INFO - mozversion platform_version: 51.0a1 11:53:53 INFO - using testdate: 1473706433 11:53:53 INFO - actual date: 1473706433 11:53:53 INFO - starting webserver on 'localhost:49272' 11:53:53 INFO - SUITE-START | Running 2 tests 11:53:53 INFO - TEST-START | dromaeo_css 11:53:53 INFO - operating with platform_type : mac_ 11:53:53 INFO - Initialising browser for dromaeo_css test... 11:53:53 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmp0AuopC/profile http://localhost:49272/getInfo.html) 11:53:56 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 11:53:56 INFO - PROCESS | 1651 | colorDepth:24 11:53:56 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 11:53:56 INFO - PROCESS | 1651 | __metrics 11:53:56 INFO - PROCESS | 1651 | 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" 11:53:56 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:53:57 INFO - TEST-INFO | 1651: exit 0 11:53:57 INFO - Browser initialized. 11:53:57 INFO - Running cycle 1/1 for dromaeo_css test... 11:53:57 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmp0AuopC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:54:09 INFO - PROCESS | 1654 | RSS: Main: 232607744 11:54:09 INFO - PROCESS | 1654 | 11:55:00 INFO - PROCESS | 1654 | 1473706500497 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] 11:57:12 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 11:57:14 INFO - PROCESS | 1654 | RSS: Main: 392749056 11:57:14 INFO - PROCESS | 1654 | 12:00:17 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 12:00:17 INFO - PROCESS | 1654 | RSS: Main: 369303552 12:00:17 INFO - PROCESS | 1654 | 12:03:20 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 12:03:20 INFO - PROCESS | 1654 | RSS: Main: 326746112 12:03:20 INFO - PROCESS | 1654 | 12:06:22 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 12:06:23 INFO - PROCESS | 1654 | RSS: Main: 342274048 12:06:23 INFO - PROCESS | 1654 | 12:09:26 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 12:09:27 INFO - PROCESS | 1654 | RSS: Main: 327970816 12:09:27 INFO - PROCESS | 1654 | 12:12:30 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 12:12:31 INFO - PROCESS | 1654 | RSS: Main: 409845760 12:12:31 INFO - PROCESS | 1654 | 12:12:31 INFO - PROCESS | 1654 | __start_tp_report 12:12:31 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 12:12:31 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 12:12:31 INFO - PROCESS | 1654 | |i|pagename|runs| 12:12:31 INFO - PROCESS | 1654 | |0;dojo.html;2887.942241155177;3312.569365982423;3337.466005439129;3350.2629421527254;3399.1332210286373;5622.100463925773;5622.8313150605545;5657.12314591238;5691.630044047134;5700.344460387051;5583.888322233543;5672.17753425754;5689.772409103642;5699.829005129843;5722.54219662427;5101.974490127544;5147.845564633067;5203.245529398235;5219.295395121648;5233.581313494911;5328.813491527797;5334.733263336837;5338.172583249602;5340.786368545273;5343.492368225013;5314.867128321785;5326.786928522854;5353.785848566053;5365.624406291561;5376.8118115865955;5155.61332900034;5214.426413094557;5220.321358223445;5286.07493688603;5287.894242115175;5190.247414124969;5207.427183009866;5214.687118772886;5237.47625237477;5247.1866860636665;5653.406392328829;5699.116636921284;5708.7145642718115;5709;5719.370869204384;6135.141080248769;6168.814935551941;6169.691515424256;6211.844703882394;6213.968930155365;6177.104319873593;6183.876322473526;6190.442860142609;6262.592931459486;6292.150559674431;5783.508401785811;5791.9851610297565;5792.739326730307;5809.477147056785;5810.50610698087;6194.721237544321;6201.720922558496;6210.782622608187;6272.717727702265;6287.151234583364;6143.416375444325;6171.660558669227;6188.504919606511;6193.256809182927;6202.81391558254;6218.533609979269;6264.091706702503;6275.246970363585;6278.215223097113;6282.403171698681;6116.375363299711;6128.387161283836;6134.681512609229;6139.614800607407;6239.875202495925;6171.6297022178815;6279.466245369194;6288.088227207029;6289.779857704958;6302.873942521124;5005.399352077774;5014.799408023711;5016.9715208382195;5018.4981501849525;5056.115179843513;4430.202563538594;4437.689361744711;4449.9555004450185;4483.058557702919;4485.932711009337;5567.359753628303;5587.217789509472;5610.971945140248;5642;5725.141228815711;6276.560640755195;6281.811545653638;6282.811515654537;6288.182536270256;6290.182276304051;5495.752691128909;5647.830565083054;5657.886842263131;5674.517665998354;5679.062954612443;4522.638188944877;5337.092694241988;5396.1905714143195;5421.864453388618;5490.846922146314;6217;6260.81061546359;6311.684415779285;6321.96839015802;6323.747050118128;5205.531502164823;6257.4681152102585;6268.6552239625435;6274.247090349188;6281.842953926189;6190.380961903774;6219.284782249918;6236.781712640036;6241.906371404523;6248.250209974832;6223.844403889939;6236.594621349598;6237.844053898689;6241.21984751906;6283.37166283368;6290.899092658858;6299.685015749286;6304.590201636879;6304.810855674337;6307.432331090224;5814.505767009851;5861.413858614104;6083.66539840313;6086.1175129606845;6143.815685529441;5078.289039534394;6144.907826382519;6150.292716337678;6152.7538898445355;6162.815115546541;5999.340072591924;6001.099835024606;6003.249593800775;6004.819855404345;6017.18767966319;4934.457209707001;6061.181740464981;6105.236845394325;6147.139400484025;6163.84590385244;5915.940840591539;6005.87988240224;6023.909641355295;6068.605540639845;6087.817365479043 12:12:31 INFO - PROCESS | 1654 | |1;ext.html;512.6539585779598;520.4743209358546;526.4104203292313;533.4185737546077;549.2749570566848;23917.402064948386;30381.696183038162;31520.369592608135;31555.684443155562;31567.842160789165;32683;38547.036324091845;40066.399004014966;40701.389479157835;41905.58094419055;8211.630476628567;9477.194438472738;9499.52502374882;9632.855507167398;9660.178884794766;9498.905010949906;9596.040395960383;9597.23222142227;9610.51947402627;9615.615375384976;8646.394752367336;8813.42712723674;11716.297022178655;11906.047516198683;11943.522259109624;11873.7625247495;11946.940265298617;11947.984421324198;11948.70128246792;11966.042716582739;9296.95351523238;11721;11805.94097029518;11811.94094029533;11830.994365478944;39373.40939885904;39675.40486892699;39735.60264397348;39756.801215994026;39822.800885995675;3266.4283750343593;3269.820159891205;3308;3393.423118069934;3743.344914639927;29941.40117197644;30127.69872301271;30458.93393731209;30476.085717428512;30545.389092218033;3100.829454380008;3132.0916934089087;3280.491523813813;3317.9170520736934;3322.883699070521;499442;502563.97436025535;502745;503804;508576.45711771573;55635.443645563835;55708.72145639246;55817.441825582035;55958.72020639952;56002.43997559972;58814.705926470095;58878.41121588815;58963;58977.70511147417;59121.3142291508;15339.386424543116;15530.456434024754;15549.378024879104;15550.688986220439;15563.144027078619;12121.090918181171;12207.206531575419;12207.450664720109;12219.816702749466;12222.816657750142;13124.343782811013;13429.529966451128;14113.506027288995;14774.704505909822;14782.930389745445;5690.715464226772;6724.899126513106;6734.1918969722965;6777.830554236183;6809.557378770364;878.8637761147032;941.8116376724682;946.7491114854569;1030.4023666273542;1095.8301463273206;27679.723202768117;28146.874125035178;29255.85372073126;29832.254193644898;29996;960.841461158915;1019.4138370437029;1021.8518314844306;1052.4474650808386;1142.9314241145557;14373.568792936228;15188.392464301525;15337.649402390438;15363.923180384027;15513.612159695873;630181;638953;640932;641628;641809;48798.02403951901;49461.25808112881;55556.16665750017;56085.7195714027;56129.87740245254;1349.6693310138883;1374.9450021999207;1397.224540380099;1399.6710772968345;1401.2993503248376;29860.808616798142;30750.231244219074;30907.381852363276;30917;31307;48191;48374;48629.513704863915;48640;48727.02545949132;461527;472814;472824;474723;617521;24967.752902238884;25117;25138.24585262445;25246.621300680137;25313.620295695946;43135;43276.35085473784;43714.56285437105;43940.34089488724;44525.777371114236;26444.867775660998;34965;36119.55521779099;36267.81866090652;36513.269734604626;29438.852805735834;34711;35420.46869296911;36628.90113296606;36728;36555.81722091373;36633.900026890304;36764.26471470502;36994.8150259247;37202;30802;30991.380172396875;31832.124930283924;31880.043598692075;31984.52023219607;24082.51834963326;24421.877890610434;24559.754402456463;24862.627060593742;24952.37619059538 12:12:31 INFO - PROCESS | 1654 | |2;jquery.html;347.6575573060533;363.8235455803933;369.73748638466685;379.6924491162158;397.99204015919685;14950.77573836392;15102.471413500529;15851.603709907258;20282.695759563583;20750.48123796902;19779;20212;20811.063502142402;20850.165993360286;21125;5108.438071812097;5828.679422631764;6064.423879731418;6078.17944577483;6089.02575587906;5532.861678458031;6061.575689701723;6120.479759220471;6123.693815309239;6138.355472675372;5769.884602307951;5963.910541341862;6073.3926607339135;6080.574359794816;6080.756769729205;6027.789027384042;6069.605475644069;6070.96964515179;6076.574639775217;6079.635221886701;5021.497850214986;5220.138677118271;5257.1588545832865;5281.392639846428;5293.470652934714;20513.8974305129;25165.37086572832;25940.87029564859;25949.740502594923;26255.474890502275;2150.3226483657754;2331.370529956919;2369.7748713872156;2383.0467812874817;2747.821391609539;9132;10104;10170.847437288372;10285.691429257135;15548.61128471786;1819.4723530176234;1857.8327950484386;1869.7849747279101;1886.6226754648987;1993.5016245938516;119449;120488;121420;128014;128838.3558082213;11268.943655281671;11336.263142895685;11457.312561246184;11503.76992460163;11631.302121872715;14400.927995359956;14412.42350305997;14416.783748243784;14417.92791036038;14428.639284017983;8254.917450825415;8365.288950439159;8406.789830254293;8414.78963025917;8495.915040849512;8285.668573257002;8290.792730181794;8300.875486867702;8442.197991190827;8466.195711407407;3855.8650447234772;4189.743077076865;4456.398386217884;4493.326001099796;4551.908961820745;1636.8444997725196;1867.7758668959812;1909.2172209394082;1915.5115445561294;1928.9035548222532;751.620431681997;839.7361970234757;880.907504711996;881.2817553693719;948.7390967483859;20212.27549214499;21541.892290538446;21571.56856862854;21675.132994680353;21793.91030448471;739.2939742545856;901.5942825728448;922.1055576091181;922.4096578189964;931.8415869302235;10972.177086718528;11390.430478476042;11489.310641361379;11496.42517874103;11527.654170374903;559976;567856;569816;576348;577928;71716.64141679258;110398;117930;133277;140962;901.5987885391069;926.420986883198;954.5084281594984;959.1271942532262;960.1166926427703;15536.922315388803;19037.048147592843;19501.414957551297;19523.902380488576;19524.8047519523;56110;56272;56619.71690141523;57504;58212.70893645505;82672.58663706643;83001.58499207466;83340;84120;85842;6968.128983877015;7818.491798033338;7972.1230664628;8101.675932962851;8107.8378432429845;9872.703818885446;9945.651902183388;12493.562725304571;12976.572577016332;13200.36989827805;10576.4711764413;10710.357378557312;10905;11175.832362514731;11317.660470185578;10843.078338341329;10892.40091794959;11121.499532521218;11244.718882028015;11478.483468244116;10746.677599672023;11421.428928553372;11485.310881346812;11649.534018639159;11712.121590880888;7839.917191683256;7968.960155199187;7981.161977992228;8322.375821813268;8322.417430779788;10561.735956601147;10769.192310576895;10992.48082735886;11460.828087578526;11607.15624671927 12:12:31 INFO - PROCESS | 1654 | |3;mootools.html;1480.1637075052595;1507.6607763253269;1716.3821024431202;1842.5854182808878;1895.0524737631176;8582.957085214573;9130.497822619769;9147.31395145365;9154.176124148817;9157.221636160933;7984.405068352779;8206.220409061145;8220.260176584106;8547.316214702825;8921.821563568725;2384.6303822907435;2425.1754403502805;2431.7932975697086;2433.1605596069326;2434.3670645632174;2393.6768536247646;2411.131992482705;2423.9154327278525;2425.0057476434667;2435.9147429839873;2061.742282214723;2401.423658321999;2417.093589903786;2422.2491027781443;2427.490227052321;2127.9787202128014;2275.4766403727235;2390.641403789437;2399.592069348215;2401.087586717054;2364.5152743687504;2399.124319623325;2400.099962514057;2409.217004473553;2411.2766170148884;8863.689770858053;8955.149260820212;8980.416272942239;8983.32625053117;8997.100289971017;5200.947990520084;5360.2763626910255;5361.544268737162;5372.086745253317;5372.811951581715;3459.2562599041325;3542.3446662367546;3640.5085313482605;3686.3733165361955;3697.2605478904325;1085.5494969587612;1089.7657003744232;1093.7913605466006;1115.3976852499723;1116.0457808573633;9979.550920208609;11801.350925699084;12015.339156346397;12029.458674359676;12034.398280085961;4621.976890115595;4742.312364707098;4859.7327147006545;4860.73265970375;4861.465238823726;10117.038881306264;10126.848097278547;10130.848037279447;10141.492925353703;10156.23828212887;5364.409914909357;5369.382521010056;5369.677819330775;5371.301730774974;5376.569874410038;4651.511591282867;4802.639802014793;4888.853334399902;4892.8532144035735;4898.608111351084;3794.2411517696573;3803.220339830328;3857.7685338879755;3906.6679332257077;3911.3546264865977;1180.9055275578105;1182.2433642468827;1195.8863907928733;1196.36592605919;1202.0023380594087;810.2464707821782;859.2095272349453;893.3478560650761;941.3269512298739;942.9203561921562;5677.1768093627015;5768.480836724715;6002.819915402545;6130.908036379458;6199.907001394983;427.02424958968606;432.66684652817156;434.20973827633406;862.2929198057533;1001.3140998416109;3611.440226764803;3791.6777073949024;3794.994326503479;3972.1658451724884;4042.3734321180264;10676.62631807883;11091.33451992883;11122.99893009633;11129.499172537255;11132.165087618461;9596.568154432927;10695.304805187638;11039.72400689973;11054.723631909106;11056.281341712764;8181.3863960203225;8457.069722330538;8473.406861519758;8473.618687159093;8487.10885357041;5836.445537673914;5838.649681019152;5932.851678708067;5947.821565353046;6023.247094113236;9775.951120244637;10288.588456461957;10324.793504129726;10341.9482902585;10383.273170877967;9171.678991235542;10579.047885690325;10587.470626468492;10620.787584248426;10622.89377106219;6668.133142691419;6880.862382752217;6882.03651488782;6950.409215216561;6970.616616085961;8077.83844323122;8108.594570271581;8416.410851240355;8604;8711;7222.74720384784;7510.50734019881;7571.3564347031115;7655.425843061681;7659.463837531543;7366.447516436396;7402.29678180572;7414.184439711519;7441.1070671519765;7485.7754267372065;6990.755323563651;7111.502194846311;7112.57324560528;7115;7139.107611548557;4775.47469778331;4902.583280421204;5031.3207716959205;5047.2429135629945;5173.430922598586;4937.160682683881;4941.357623508921;5104.566111880383;5141.38303403594;5222.3472065991755 12:12:31 INFO - PROCESS | 1654 | |4;prototype.html;265.68750373156604;265.98670066496675;300.27783181448626;309.5001572460477;313.69258127035516;4559.63522918167;5307.6019298552665;5315.309009828722;5344.973275133624;5448.182772584114;4561.908761824761;4987.251912213161;5129.948700512994;5150;5346.946530534694;3506.859725610985;4007.5792041835625;4026.9798651006704;4042.474478317822;4052.7365721228025;3336.8654657416596;3612.1150318171954;4018.1361007383407;4037.5356833964174;4052.3516237402023;3730.675610158382;3863.9613603864022;4000.4999375078114;4019.4171845082446;4022.275990321741;3286.474164133751;4020.396940458925;4032.2540330039037;4034.899127521806;4059.249038927793;2915.781316401278;3485.8431370588382;3496.4930085137767;3520.436730123168;3521.8479460882368;4562.5665561771575;4857.761270875904;5107.463716309771;5143.408508021588;5163.845084647466;3531.823408829548;4228;4368.279233926399;4583.564561366665;4595.9080818383445;4881.34101896243;5126.61550383719;5490.890182196334;5492.945070549284;5508;3510.4558793387064;3835.846566137351;3840.558335791392;3842.2123464689953;3844.4233364995193;5118.97440512795;5478.260434841325;5710;5779.855503612402;5817.243758311392;3222.7099561039618;3587.94155724062;3731.4962480065387;3887.00881275273;3929.528456585228;3947.230290093404;3953.9011524711536;3954;3963.9405408918888;3980.3034468968394;4197.538270790211;4278.737772357162;4364.869053928387;4638.814447422133;4647.374233558029;4494.438195225597;5522.751476183581;5527.55779537636;5548.528375088082;5632;1317.2425855133338;1341.3099494636829;1342.1879762743595;1348.7456665301363;1352.7362164377853;853.7054716122927;885.588201486312;894.6197865907094;895.5969813583913;898.9011208767031;4606.792694328763;5001.599552125411;5149.005464699236;5160.7419629018395;5185.688858668492;4485.282354823278;4956.727379994135;4984.675996060245;5007.324011258506;5689.431056894277;4470.68114906097;4599.540045995441;4857.352742755207;5111.006707384123;5207.734635433599;2724.937274462961;2751.257160566676;2943.8822447101875;2982;3035.590195323647;5221.869453263699;5949.702514874239;5972;6014.398560143951;6034.487068599218;4897.436794768576;5059.772310246048;5906.3207731110615;5933.199018132583;5934.554908381889;5980.192673988956;5990.580659353804;5993.370696076936;6018.668973206516;6074.240719910012;5235.073694728953;5395.358133259955;5804.21643078179;5804.506616937439;5849.3858144894175;5160.793568257228;5640.915386269127;5692.373838877803;5695.914561281501;5711.7429715662065;5395.379531353945;5550.834324791839;5956.463918247206;5982.46157845796;6032.849178770391;3303.7941151480018;3511.5259439975275;3527.2945410917405;3576.678098971105;3617.8733744318824;5774.884502310014;5780.190773291826;5784.913226301524;5801.535877129735;5849.356570777126;5153.547123427238;5521.713011920239;5666.016894087036;5683;5687.374388817144;5548.944510554843;5575.135853942646;5588;5603.5797315202335;5612.971935140298;4789.790840137047;5477.685355514727;5482.17767334887;5483.232347471437;5490.890182196414;5078.669886457368;5130.435652078343;5434.592405569677;5651.626316946974;5688.857778555569;5481.671099734029;5488.670679759227;5499.835004949858;5512.39363670004;5516.641418307797 12:12:31 INFO - PROCESS | 1654 | |5;yui.html;675.5102550650779;723.1177962885281;754.4643303254688;773.6982576795051;782.9451938364317;14441.855581444182;15651.373945042198;16133.757993630075;17042.573935651613;17053.232604532794;15603.375864965388;15987.520374388787;15991.040537567755;15992;16056.59858503535;838.861587838006;856.8629019356904;857.9656813727474;857.995710021449;858.2361698088706;673.6833688166554;692.5879101934341;694.0144994108379;842.1326034184799;858.5406807358065;895.234574438852;917.9810410444402;918.6784625381097;920.4615300049479;920.5535315372039;910.0762725833284;910.2808781062952;911.4030310146865;911.7355966769629;912.6623149434752;518.7587771686146;532.5287120897995;532.7496076843877;674.3256743256743;676.2155899156954;11834.644960651194;11864.169508134433;14563.563093107117;14691;14744.189069601274;13187.670308242275;13523.120997135155;13574.389152488064;13640.181589104686;13930.164190148624;11479.368634725086;11659.125565582615;11778.528858845635;12102.821492131412;12122.636320910387;3628.584851907758;3672.761270517408;3689.24370504046;3710.888673339777;3833.3483307837732;95360;124190.37904810508;126207.73792262052;126679;127847;9676.516174191262;9939.701808945743;9961.80076398468;10242.334248273839;10864.130869530418;21851.453713657287;22892.084316627148;23119.422014449774;23143.884280578826;23241.767582324297;6041;8260.380471464665;8287.50274983503;8303.750887473385;8314.916850831536;4302.182585308844;4342.739435633872;4434.600885920283;4474.910501790011;4603.194440972817;3219.275662975812;3372.7133193678324;3372.865085396557;3375.932481350359;3378.6283508814013;656.1076935367896;664.3721683009607;672.3478226120656;672.4855485553458;684.5379368925956;517.2809794385806;620.172070286173;636.0110028905096;669.6584741781727;674.2616834566143;7952.801179970431;8254.669813207525;8340.249377556049;8531.402801803939;9331.626734930527;645.8998855177455;654.1398061549048;665.677146583916;670.9030734748721;673.7338751193251;4347.434833471629;4510.2783554631105;4555.111753208159;4571.81712731487;4626.152185483837;167271;171229;174165;176297;179666;145182;147239;148096;153597;155047;105651.47174264079;121732.97154941913;127359;128079;128447.35776321245;8694.04365519805;8770.298376130022;8890.123628281572;8961.551922403984;9003.234725048444;53168;65784;66750;67014.99477507744;67824;98666.50666746621;102289.48855255677;102888;107289.46355268174;110345;10972.780544389227;11311.208215424842;11686.766264674488;12013.339266340436;12840;11092.33445993243;14432.424348172723;14929.925350373178;15044.774328384865;15155.090694558361;12973.481060757464;13171.616980216939;13467.528636497676;13529.72940541164;13889.305534723426;11125.44372781374;13350.265735384648;13398.799018014932;14170.504032358818;14205.076670016417;12747.235165890077;12768.231768231768;12866.228026318451;12873.935630321788;13509.932450337685;10997.945010274898;11366.5510616535;11508.769824603294;11643.243189193014;11661.825072624088;9320.229156460251;11015.559377624806;11021.063209627271;11148.442577871236;11290.379029153475 12:12:31 INFO - PROCESS | 1654 | __end_tp_report 12:12:31 INFO - PROCESS | 1654 | __start_cc_report 12:12:31 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1477 12:12:31 INFO - PROCESS | 1654 | __end_cc_report 12:12:31 INFO - PROCESS | 1654 | __startTimestamp1473707551021__endTimestamp 12:12:31 INFO - PROCESS | 1654 | 12:12:31 INFO - PROCESS | 1654 | ------- Summary: start ------- 12:12:31 INFO - PROCESS | 1654 | Number of tests: 6 12:12:31 INFO - PROCESS | 1654 | 12:12:31 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5730.81 Median:6001.10 stddev:652.00 (10.9%) stddev-sans-first:614.68 12:12:31 INFO - PROCESS | 1654 | Values: 2887.9 3312.6 3337.5 3350.3 3399.1 5622.1 5622.8 5657.1 5691.6 5700.3 5583.9 5672.2 5689.8 5699.8 5722.5 5102.0 5147.8 5203.2 5219.3 5233.6 5328.8 5334.7 5338.2 5340.8 5343.5 5314.9 5326.8 5353.8 5365.6 5376.8 5155.6 5214.4 5220.3 5286.1 5287.9 5190.2 5207.4 5214.7 5237.5 5247.2 5653.4 5699.1 5708.7 5709.0 5719.4 6135.1 6168.8 6169.7 6211.8 6214.0 6177.1 6183.9 6190.4 6262.6 6292.2 5783.5 5792.0 5792.7 5809.5 5810.5 6194.7 6201.7 6210.8 6272.7 6287.2 6143.4 6171.7 6188.5 6193.3 6202.8 6218.5 6264.1 6275.2 6278.2 6282.4 6116.4 6128.4 6134.7 6139.6 6239.9 6171.6 6279.5 6288.1 6289.8 6302.9 5005.4 5014.8 5017.0 5018.5 5056.1 4430.2 4437.7 4450.0 4483.1 4485.9 5567.4 5587.2 5611.0 5642.0 5725.1 6276.6 6281.8 6282.8 6288.2 6290.2 5495.8 5647.8 5657.9 5674.5 5679.1 4522.6 5337.1 5396.2 5421.9 5490.8 6217.0 6260.8 6311.7 6322.0 6323.7 5205.5 6257.5 6268.7 6274.2 6281.8 6190.4 6219.3 6236.8 6241.9 6248.3 6223.8 6236.6 6237.8 6241.2 6283.4 6290.9 6299.7 6304.6 6304.8 6307.4 5814.5 5861.4 6083.7 6086.1 6143.8 5078.3 6144.9 6150.3 6152.8 6162.8 5999.3 6001.1 6003.2 6004.8 6017.2 4934.5 6061.2 6105.2 6147.1 6163.8 5915.9 6005.9 6023.9 6068.6 6087.8 12:12:31 INFO - PROCESS | 1654 | 12:12:31 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:67053.77 Median:34838.00 stddev:147673.54 (423.9%) stddev-sans-first:148003.29 12:12:31 INFO - PROCESS | 1654 | Values: 512.7 520.5 526.4 533.4 549.3 23917.4 30381.7 31520.4 31555.7 31567.8 32683.0 38547.0 40066.4 40701.4 41905.6 8211.6 9477.2 9499.5 9632.9 9660.2 9498.9 9596.0 9597.2 9610.5 9615.6 8646.4 8813.4 11716.3 11906.0 11943.5 11873.8 11946.9 11948.0 11948.7 11966.0 9297.0 11721.0 11805.9 11811.9 11831.0 39373.4 39675.4 39735.6 39756.8 39822.8 3266.4 3269.8 3308.0 3393.4 3743.3 29941.4 30127.7 30458.9 30476.1 30545.4 3100.8 3132.1 3280.5 3317.9 3322.9 499442.0 502564.0 502745.0 503804.0 508576.5 55635.4 55708.7 55817.4 55958.7 56002.4 58814.7 58878.4 58963.0 58977.7 59121.3 15339.4 15530.5 15549.4 15550.7 15563.1 12121.1 12207.2 12207.5 12219.8 12222.8 13124.3 13429.5 14113.5 14774.7 14782.9 5690.7 6724.9 6734.2 6777.8 6809.6 878.9 941.8 946.7 1030.4 1095.8 27679.7 28146.9 29255.9 29832.3 29996.0 960.8 1019.4 1021.9 1052.4 1142.9 14373.6 15188.4 15337.6 15363.9 15513.6 630181.0 638953.0 640932.0 641628.0 641809.0 48798.0 49461.3 55556.2 56085.7 56129.9 1349.7 1374.9 1397.2 1399.7 1401.3 29860.8 30750.2 30907.4 30917.0 31307.0 48191.0 48374.0 48629.5 48640.0 48727.0 461527.0 472814.0 472824.0 474723.0 617521.0 24967.8 25117.0 25138.2 25246.6 25313.6 43135.0 43276.4 43714.6 43940.3 44525.8 26444.9 34965.0 36119.6 36267.8 36513.3 29438.9 34711.0 35420.5 36628.9 36728.0 36555.8 36633.9 36764.3 36994.8 37202.0 30802.0 30991.4 31832.1 31880.0 31984.5 24082.5 24421.9 24559.8 24862.6 24952.4 12:12:31 INFO - PROCESS | 1654 | 12:12:31 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:34223.65 Median:1547.74 stddev:95486.91 (6169.4%) stddev-sans-first:95720.90 12:12:31 INFO - PROCESS | 1654 | Values: 347.7 363.8 369.7 379.7 398.0 14950.8 15102.5 15851.6 20282.7 20750.5 19779.0 20212.0 20811.1 20850.2 21125.0 5108.4 5828.7 6064.4 6078.2 6089.0 5532.9 6061.6 6120.5 6123.7 6138.4 5769.9 5963.9 6073.4 6080.6 6080.8 6027.8 6069.6 6071.0 6076.6 6079.6 5021.5 5220.1 5257.2 5281.4 5293.5 20513.9 25165.4 25940.9 25949.7 26255.5 2150.3 2331.4 2369.8 2383.0 2747.8 9132.0 10104.0 10170.8 10285.7 15548.6 1819.5 1857.8 1869.8 1886.6 1993.5 119449.0 120488.0 121420.0 128014.0 128838.4 11268.9 11336.3 11457.3 11503.8 11631.3 14400.9 14412.4 14416.8 14417.9 14428.6 8254.9 8365.3 8406.8 8414.8 8495.9 8285.7 8290.8 8300.9 8442.2 8466.2 3855.9 4189.7 4456.4 4493.3 4551.9 1636.8 1867.8 1909.2 1915.5 1928.9 751.6 839.7 880.9 881.3 948.7 20212.3 21541.9 21571.6 21675.1 21793.9 739.3 901.6 922.1 922.4 931.8 10972.2 11390.4 11489.3 11496.4 11527.7 559976.0 567856.0 569816.0 576348.0 577928.0 71716.6 110398.0 117930.0 133277.0 140962.0 901.6 926.4 954.5 959.1 960.1 15536.9 19037.0 19501.4 19523.9 19524.8 56110.0 56272.0 56619.7 57504.0 58212.7 82672.6 83001.6 83340.0 84120.0 85842.0 6968.1 7818.5 7972.1 8101.7 8107.8 9872.7 9945.7 12493.6 12976.6 13200.4 10576.5 10710.4 10905.0 11175.8 11317.7 10843.1 10892.4 11121.5 11244.7 11478.5 10746.7 11421.4 11485.3 11649.5 11712.1 7839.9 7969.0 7981.2 8322.4 8322.4 10561.7 10769.2 10992.5 11460.8 11607.2 12:12:31 INFO - PROCESS | 1654 | 12:12:31 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5749.75 Median:4860.23 stddev:3256.74 (67.0%) stddev-sans-first:3250.07 12:12:31 INFO - PROCESS | 1654 | Values: 1480.2 1507.7 1716.4 1842.6 1895.1 8583.0 9130.5 9147.3 9154.2 9157.2 7984.4 8206.2 8220.3 8547.3 8921.8 2384.6 2425.2 2431.8 2433.2 2434.4 2393.7 2411.1 2423.9 2425.0 2435.9 2061.7 2401.4 2417.1 2422.2 2427.5 2128.0 2275.5 2390.6 2399.6 2401.1 2364.5 2399.1 2400.1 2409.2 2411.3 8863.7 8955.1 8980.4 8983.3 8997.1 5200.9 5360.3 5361.5 5372.1 5372.8 3459.3 3542.3 3640.5 3686.4 3697.3 1085.5 1089.8 1093.8 1115.4 1116.0 9979.6 11801.4 12015.3 12029.5 12034.4 4622.0 4742.3 4859.7 4860.7 4861.5 10117.0 10126.8 10130.8 10141.5 10156.2 5364.4 5369.4 5369.7 5371.3 5376.6 4651.5 4802.6 4888.9 4892.9 4898.6 3794.2 3803.2 3857.8 3906.7 3911.4 1180.9 1182.2 1195.9 1196.4 1202.0 810.2 859.2 893.3 941.3 942.9 5677.2 5768.5 6002.8 6130.9 6199.9 427.0 432.7 434.2 862.3 1001.3 3611.4 3791.7 3795.0 3972.2 4042.4 10676.6 11091.3 11123.0 11129.5 11132.2 9596.6 10695.3 11039.7 11054.7 11056.3 8181.4 8457.1 8473.4 8473.6 8487.1 5836.4 5838.6 5932.9 5947.8 6023.2 9776.0 10288.6 10324.8 10341.9 10383.3 9171.7 10579.0 10587.5 10620.8 10622.9 6668.1 6880.9 6882.0 6950.4 6970.6 8077.8 8108.6 8416.4 8604.0 8711.0 7222.7 7510.5 7571.4 7655.4 7659.5 7366.4 7402.3 7414.2 7441.1 7485.8 6990.8 7111.5 7112.6 7115.0 7139.1 4775.5 4902.6 5031.3 5047.2 5173.4 4937.2 4941.4 5104.6 5141.4 5222.3 12:12:31 INFO - PROCESS | 1654 | 12:12:31 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4494.71 Median:5109.24 stddev:1404.41 (27.5%) stddev-sans-first:1372.01 12:12:31 INFO - PROCESS | 1654 | Values: 265.7 266.0 300.3 309.5 313.7 4559.6 5307.6 5315.3 5345.0 5448.2 4561.9 4987.3 5129.9 5150.0 5346.9 3506.9 4007.6 4027.0 4042.5 4052.7 3336.9 3612.1 4018.1 4037.5 4052.4 3730.7 3864.0 4000.5 4019.4 4022.3 3286.5 4020.4 4032.3 4034.9 4059.2 2915.8 3485.8 3496.5 3520.4 3521.8 4562.6 4857.8 5107.5 5143.4 5163.8 3531.8 4228.0 4368.3 4583.6 4595.9 4881.3 5126.6 5490.9 5492.9 5508.0 3510.5 3835.8 3840.6 3842.2 3844.4 5119.0 5478.3 5710.0 5779.9 5817.2 3222.7 3587.9 3731.5 3887.0 3929.5 3947.2 3953.9 3954.0 3963.9 3980.3 4197.5 4278.7 4364.9 4638.8 4647.4 4494.4 5522.8 5527.6 5548.5 5632.0 1317.2 1341.3 1342.2 1348.7 1352.7 853.7 885.6 894.6 895.6 898.9 4606.8 5001.6 5149.0 5160.7 5185.7 4485.3 4956.7 4984.7 5007.3 5689.4 4470.7 4599.5 4857.4 5111.0 5207.7 2724.9 2751.3 2943.9 2982.0 3035.6 5221.9 5949.7 5972.0 6014.4 6034.5 4897.4 5059.8 5906.3 5933.2 5934.6 5980.2 5990.6 5993.4 6018.7 6074.2 5235.1 5395.4 5804.2 5804.5 5849.4 5160.8 5640.9 5692.4 5695.9 5711.7 5395.4 5550.8 5956.5 5982.5 6032.8 3303.8 3511.5 3527.3 3576.7 3617.9 5774.9 5780.2 5784.9 5801.5 5849.4 5153.5 5521.7 5666.0 5683.0 5687.4 5548.9 5575.1 5588.0 5603.6 5613.0 4789.8 5477.7 5482.2 5483.2 5490.9 5078.7 5130.4 5434.6 5651.6 5688.9 5481.7 5488.7 5499.8 5512.4 5516.6 12:12:31 INFO - PROCESS | 1654 | 12:12:31 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:27049.92 Median:3372.79 stddev:45460.92 (1347.9%) stddev-sans-first:45545.32 12:12:31 INFO - PROCESS | 1654 | Values: 675.5 723.1 754.5 773.7 782.9 14441.9 15651.4 16133.8 17042.6 17053.2 15603.4 15987.5 15991.0 15992.0 16056.6 838.9 856.9 858.0 858.0 858.2 673.7 692.6 694.0 842.1 858.5 895.2 918.0 918.7 920.5 920.6 910.1 910.3 911.4 911.7 912.7 518.8 532.5 532.7 674.3 676.2 11834.6 11864.2 14563.6 14691.0 14744.2 13187.7 13523.1 13574.4 13640.2 13930.2 11479.4 11659.1 11778.5 12102.8 12122.6 3628.6 3672.8 3689.2 3710.9 3833.3 95360.0 124190.4 126207.7 126679.0 127847.0 9676.5 9939.7 9961.8 10242.3 10864.1 21851.5 22892.1 23119.4 23143.9 23241.8 6041.0 8260.4 8287.5 8303.8 8314.9 4302.2 4342.7 4434.6 4474.9 4603.2 3219.3 3372.7 3372.9 3375.9 3378.6 656.1 664.4 672.3 672.5 684.5 517.3 620.2 636.0 669.7 674.3 7952.8 8254.7 8340.2 8531.4 9331.6 645.9 654.1 665.7 670.9 673.7 4347.4 4510.3 4555.1 4571.8 4626.2 167271.0 171229.0 174165.0 176297.0 179666.0 145182.0 147239.0 148096.0 153597.0 155047.0 105651.5 121733.0 127359.0 128079.0 128447.4 8694.0 8770.3 8890.1 8961.6 9003.2 53168.0 65784.0 66750.0 67015.0 67824.0 98666.5 102289.5 102888.0 107289.5 110345.0 10972.8 11311.2 11686.8 12013.3 12840.0 11092.3 14432.4 14929.9 15044.8 15155.1 12973.5 13171.6 13467.5 13529.7 13889.3 11125.4 13350.3 13398.8 14170.5 14205.1 12747.2 12768.2 12866.2 12873.9 13509.9 10997.9 11366.6 11508.8 11643.2 11661.8 9320.2 11015.6 11021.1 11148.4 11290.4 12:12:31 INFO - PROCESS | 1654 | -------- Summary: end -------- 12:12:31 INFO - PROCESS | 1654 | 12:12:31 INFO - TEST-INFO | 1654: exit 0 12:12:31 INFO - TEST-OK | dromaeo_css | took 1117916ms 12:12:31 INFO - TEST-START | kraken 12:12:31 INFO - operating with platform_type : mac_ 12:12:31 INFO - Initialising browser for kraken test... 12:12:31 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpbEfPov/profile http://localhost:49272/getInfo.html) 12:12:34 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 12:12:34 INFO - PROCESS | 1661 | colorDepth:24 12:12:34 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 12:12:34 INFO - PROCESS | 1661 | __metrics 12:12:34 INFO - PROCESS | 1661 | 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" 12:12:34 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:12:34 INFO - TEST-INFO | 1661: exit 0 12:12:34 INFO - Browser initialized. 12:12:34 INFO - Running cycle 1/1 for kraken test... 12:12:34 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpbEfPov/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:12:46 INFO - PROCESS | 1662 | RSS: Main: 232435712 12:12:46 INFO - PROCESS | 1662 | 12:12:49 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 12:12:50 INFO - PROCESS | 1662 | RSS: Main: 291483648 12:12:50 INFO - PROCESS | 1662 | 12:12:52 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 12:12:53 INFO - PROCESS | 1662 | RSS: Main: 287473664 12:12:53 INFO - PROCESS | 1662 | 12:12:57 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 12:12:57 INFO - PROCESS | 1662 | RSS: Main: 374464512 12:12:57 INFO - PROCESS | 1662 | 12:12:59 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 12:13:00 INFO - PROCESS | 1662 | RSS: Main: 279977984 12:13:00 INFO - PROCESS | 1662 | 12:13:02 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:13:03 INFO - PROCESS | 1662 | RSS: Main: 284561408 12:13:03 INFO - PROCESS | 1662 | 12:13:05 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 12:13:06 INFO - PROCESS | 1662 | RSS: Main: 322482176 12:13:06 INFO - PROCESS | 1662 | 12:13:09 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 12:13:10 INFO - PROCESS | 1662 | RSS: Main: 324194304 12:13:10 INFO - PROCESS | 1662 | 12:13:13 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 12:13:13 INFO - PROCESS | 1662 | RSS: Main: 322605056 12:13:13 INFO - PROCESS | 1662 | 12:13:15 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:13:15 INFO - PROCESS | 1662 | RSS: Main: 298934272 12:13:15 INFO - PROCESS | 1662 | 12:13:17 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:13:18 INFO - PROCESS | 1662 | RSS: Main: 304128000 12:13:18 INFO - PROCESS | 1662 | 12:13:20 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:13:21 INFO - PROCESS | 1662 | RSS: Main: 302530560 12:13:21 INFO - PROCESS | 1662 | 12:13:24 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:13:24 INFO - PROCESS | 1662 | RSS: Main: 318971904 12:13:24 INFO - PROCESS | 1662 | 12:13:27 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:13:28 INFO - PROCESS | 1662 | RSS: Main: 315052032 12:13:28 INFO - PROCESS | 1662 | 12:13:30 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 12:13:30 INFO - PROCESS | 1662 | RSS: Main: 358842368 12:13:30 INFO - PROCESS | 1662 | 12:13:30 INFO - PROCESS | 1662 | __start_tp_report 12:13:30 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 12:13:30 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 12:13:30 INFO - PROCESS | 1662 | |i|pagename|runs| 12:13:30 INFO - PROCESS | 1662 | |0;ai-astar;113;111;117;113;114;113;113;114;113;124 12:13:30 INFO - PROCESS | 1662 | |1;audio-beat-detection;146;108;129;159;106;124;130;135;197;137 12:13:30 INFO - PROCESS | 1662 | |2;audio-dft;184;184;183;184;182;184;185;180;182;183 12:13:30 INFO - PROCESS | 1662 | |3;audio-fft;78;72;80;180;82;82;57;62;137;59 12:13:30 INFO - PROCESS | 1662 | |4;audio-oscillator;82;85;117;72;91;112;153;96;70;118 12:13:30 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;90;87;91;88;91;74;117;71;117;72 12:13:30 INFO - PROCESS | 1662 | |6;imaging-darkroom;94;96;107;151;151;96;95;95;94;96 12:13:30 INFO - PROCESS | 1662 | |7;imaging-desaturate;84;86;87;85;87;84;84;86;85;86 12:13:30 INFO - PROCESS | 1662 | |8;json-parse-financial;52;53;54;54;55;54;53;55;54;53 12:13:30 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;64;65;63;76;74;76;69;70;69;70 12:13:30 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;83;88;86;93;104;105;102;85;86;85 12:13:30 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;128;163;128;132;145;152;153;138;190;154 12:13:30 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;156;169;168;166;166;179;168;169;167;169 12:13:30 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;67;66;67;68;66;64;65;66;65;67 12:13:30 INFO - PROCESS | 1662 | __end_tp_report 12:13:30 INFO - PROCESS | 1662 | __start_cc_report 12:13:30 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2019 12:13:30 INFO - PROCESS | 1662 | __end_cc_report 12:13:30 INFO - PROCESS | 1662 | __startTimestamp1473707610292__endTimestamp 12:13:30 INFO - PROCESS | 1662 | 12:13:30 INFO - PROCESS | 1662 | ------- Summary: start ------- 12:13:30 INFO - PROCESS | 1662 | Number of tests: 14 12:13:30 INFO - PROCESS | 1662 | 12:13:30 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:114.50 Median:113.50 stddev:3.66 (3.2%) stddev-sans-first:3.84 12:13:30 INFO - PROCESS | 1662 | Values: 113.0 111.0 117.0 113.0 114.0 113.0 113.0 114.0 113.0 124.0 12:13:30 INFO - PROCESS | 1662 | 12:13:30 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:137.10 Median:136.00 stddev:26.36 (19.4%) stddev-sans-first:27.76 12:13:30 INFO - PROCESS | 1662 | Values: 146.0 108.0 129.0 159.0 106.0 124.0 130.0 135.0 197.0 137.0 12:13:30 INFO - PROCESS | 1662 | 12:13:30 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:183.10 Median:184.00 stddev:1.45 (0.8%) stddev-sans-first:1.50 12:13:30 INFO - PROCESS | 1662 | Values: 184.0 184.0 183.0 184.0 182.0 184.0 185.0 180.0 182.0 183.0 12:13:30 INFO - PROCESS | 1662 | 12:13:30 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:88.90 Median:75.00 stddev:39.20 (52.3%) stddev-sans-first:41.37 12:13:30 INFO - PROCESS | 1662 | Values: 78.0 72.0 80.0 180.0 82.0 82.0 57.0 62.0 137.0 59.0 12:13:30 INFO - PROCESS | 1662 | 12:13:30 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:99.60 Median:77.00 stddev:25.59 (33.2%) stddev-sans-first:26.34 12:13:30 INFO - PROCESS | 1662 | Values: 82.0 85.0 117.0 72.0 91.0 112.0 153.0 96.0 70.0 118.0 12:13:30 INFO - PROCESS | 1662 | 12:13:30 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:87.50 stddev:16.38 (18.7%) stddev-sans-first:17.37 12:13:30 INFO - PROCESS | 1662 | Values: 90.0 87.0 91.0 88.0 91.0 74.0 117.0 71.0 117.0 72.0 12:13:30 INFO - PROCESS | 1662 | 12:13:30 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:107.50 Median:95.00 stddev:23.23 (24.5%) stddev-sans-first:24.12 12:13:30 INFO - PROCESS | 1662 | Values: 94.0 96.0 107.0 151.0 151.0 96.0 95.0 95.0 94.0 96.0 12:13:30 INFO - PROCESS | 1662 | 12:13:30 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:86.00 stddev:1.17 (1.4%) stddev-sans-first:1.13 12:13:30 INFO - PROCESS | 1662 | Values: 84.0 86.0 87.0 85.0 87.0 84.0 84.0 86.0 85.0 86.0 12:13:30 INFO - PROCESS | 1662 | 12:13:30 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:0.95 (1.8%) stddev-sans-first:0.78 12:13:30 INFO - PROCESS | 1662 | Values: 52.0 53.0 54.0 54.0 55.0 54.0 53.0 55.0 54.0 53.0 12:13:30 INFO - PROCESS | 1662 | 12:13:30 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:70.00 stddev:4.70 (6.7%) stddev-sans-first:4.52 12:13:30 INFO - PROCESS | 1662 | Values: 64.0 65.0 63.0 76.0 74.0 76.0 69.0 70.0 69.0 70.0 12:13:30 INFO - PROCESS | 1662 | 12:13:30 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:91.70 Median:85.50 stddev:8.69 (10.2%) stddev-sans-first:8.63 12:13:30 INFO - PROCESS | 1662 | Values: 83.0 88.0 86.0 93.0 104.0 105.0 102.0 85.0 86.0 85.0 12:13:30 INFO - PROCESS | 1662 | 12:13:30 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:148.30 Median:152.50 stddev:18.94 (12.4%) stddev-sans-first:18.62 12:13:30 INFO - PROCESS | 1662 | Values: 128.0 163.0 128.0 132.0 145.0 152.0 153.0 138.0 190.0 154.0 12:13:30 INFO - PROCESS | 1662 | 12:13:30 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.70 Median:168.50 stddev:5.54 (3.3%) stddev-sans-first:3.94 12:13:30 INFO - PROCESS | 1662 | Values: 156.0 169.0 168.0 166.0 166.0 179.0 168.0 169.0 167.0 169.0 12:13:30 INFO - PROCESS | 1662 | 12:13:30 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.50 stddev:1.20 (1.8%) stddev-sans-first:1.22 12:13:30 INFO - PROCESS | 1662 | Values: 67.0 66.0 67.0 68.0 66.0 64.0 65.0 66.0 65.0 67.0 12:13:30 INFO - PROCESS | 1662 | -------- Summary: end -------- 12:13:30 INFO - PROCESS | 1662 | 12:13:31 INFO - TEST-INFO | 1662: exit 0 12:13:31 INFO - TEST-OK | kraken | took 59542ms 12:13:31 INFO - SUITE-END | took 1177s 12:13:31 INFO - Completed test suite (00:19:37) 12:13:31 INFO - javascript benchmark 12:13:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2887.942241155177, 3312.569365982423, 3337.466005439129, 3350.2629421527254, 3399.1332210286373, 5622.100463925773, 5622.8313150605545, 5657.12314591238, 5691.630044047134, 5700.344460387051, 5583.888322233543, 5672.17753425754, 5689.772409103642, 5699.829005129843, 5722.54219662427, 5101.974490127544, 5147.845564633067, 5203.245529398235, 5219.295395121648, 5233.581313494911, 5328.813491527797, 5334.733263336837, 5338.172583249602, 5340.786368545273, 5343.492368225013, 5314.867128321785, 5326.786928522854, 5353.785848566053, 5365.624406291561, 5376.8118115865955, 5155.61332900034, 5214.426413094557, 5220.321358223445, 5286.07493688603, 5287.894242115175, 5190.247414124969, 5207.427183009866, 5214.687118772886, 5237.47625237477, 5247.1866860636665, 5653.406392328829, 5699.116636921284, 5708.7145642718115, 5709.0, 5719.370869204384, 6135.141080248769, 6168.814935551941, 6169.691515424256, 6211.844703882394, 6213.968930155365, 6177.104319873593, 6183.876322473526, 6190.442860142609, 6262.592931459486, 6292.150559674431, 5783.508401785811, 5791.9851610297565, 5792.739326730307, 5809.477147056785, 5810.50610698087, 6194.721237544321, 6201.720922558496, 6210.782622608187, 6272.717727702265, 6287.151234583364, 6143.416375444325, 6171.660558669227, 6188.504919606511, 6193.256809182927, 6202.81391558254, 6218.533609979269, 6264.091706702503, 6275.246970363585, 6278.215223097113, 6282.403171698681, 6116.375363299711, 6128.387161283836, 6134.681512609229, 6139.614800607407, 6239.875202495925, 6171.6297022178815, 6279.466245369194, 6288.088227207029, 6289.779857704958, 6302.873942521124, 5005.399352077774, 5014.799408023711, 5016.9715208382195, 5018.4981501849525, 5056.115179843513, 4430.202563538594, 4437.689361744711, 4449.9555004450185, 4483.058557702919, 4485.932711009337, 5567.359753628303, 5587.217789509472, 5610.971945140248, 5642.0, 5725.141228815711, 6276.560640755195, 6281.811545653638, 6282.811515654537, 6288.182536270256, 6290.182276304051, 5495.752691128909, 5647.830565083054, 5657.886842263131, 5674.517665998354, 5679.062954612443, 4522.638188944877, 5337.092694241988, 5396.1905714143195, 5421.864453388618, 5490.846922146314, 6217.0, 6260.81061546359, 6311.684415779285, 6321.96839015802, 6323.747050118128, 5205.531502164823, 6257.4681152102585, 6268.6552239625435, 6274.247090349188, 6281.842953926189, 6190.380961903774, 6219.284782249918, 6236.781712640036, 6241.906371404523, 6248.250209974832, 6223.844403889939, 6236.594621349598, 6237.844053898689, 6241.21984751906, 6283.37166283368, 6290.899092658858, 6299.685015749286, 6304.590201636879, 6304.810855674337, 6307.432331090224, 5814.505767009851, 5861.413858614104, 6083.66539840313, 6086.1175129606845, 6143.815685529441, 5078.289039534394, 6144.907826382519, 6150.292716337678, 6152.7538898445355, 6162.815115546541, 5999.340072591924, 6001.099835024606, 6003.249593800775, 6004.819855404345, 6017.18767966319, 4934.457209707001, 6061.181740464981, 6105.236845394325, 6147.139400484025, 6163.84590385244, 5915.940840591539, 6005.87988240224, 6023.909641355295, 6068.605540639845, 6087.817365479043], "value": 5688.6517887803875, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.6539585779598, 520.4743209358546, 526.4104203292313, 533.4185737546077, 549.2749570566848, 23917.402064948386, 30381.696183038162, 31520.369592608135, 31555.684443155562, 31567.842160789165, 32683.0, 38547.036324091845, 40066.399004014966, 40701.389479157835, 41905.58094419055, 8211.630476628567, 9477.194438472738, 9499.52502374882, 9632.855507167398, 9660.178884794766, 9498.905010949906, 9596.040395960383, 9597.23222142227, 9610.51947402627, 9615.615375384976, 8646.394752367336, 8813.42712723674, 11716.297022178655, 11906.047516198683, 11943.522259109624, 11873.7625247495, 11946.940265298617, 11947.984421324198, 11948.70128246792, 11966.042716582739, 9296.95351523238, 11721.0, 11805.94097029518, 11811.94094029533, 11830.994365478944, 39373.40939885904, 39675.40486892699, 39735.60264397348, 39756.801215994026, 39822.800885995675, 3266.4283750343593, 3269.820159891205, 3308.0, 3393.423118069934, 3743.344914639927, 29941.40117197644, 30127.69872301271, 30458.93393731209, 30476.085717428512, 30545.389092218033, 3100.829454380008, 3132.0916934089087, 3280.491523813813, 3317.9170520736934, 3322.883699070521, 499442.0, 502563.97436025535, 502745.0, 503804.0, 508576.45711771573, 55635.443645563835, 55708.72145639246, 55817.441825582035, 55958.72020639952, 56002.43997559972, 58814.705926470095, 58878.41121588815, 58963.0, 58977.70511147417, 59121.3142291508, 15339.386424543116, 15530.456434024754, 15549.378024879104, 15550.688986220439, 15563.144027078619, 12121.090918181171, 12207.206531575419, 12207.450664720109, 12219.816702749466, 12222.816657750142, 13124.343782811013, 13429.529966451128, 14113.506027288995, 14774.704505909822, 14782.930389745445, 5690.715464226772, 6724.899126513106, 6734.1918969722965, 6777.830554236183, 6809.557378770364, 878.8637761147032, 941.8116376724682, 946.7491114854569, 1030.4023666273542, 1095.8301463273206, 27679.723202768117, 28146.874125035178, 29255.85372073126, 29832.254193644898, 29996.0, 960.841461158915, 1019.4138370437029, 1021.8518314844306, 1052.4474650808386, 1142.9314241145557, 14373.568792936228, 15188.392464301525, 15337.649402390438, 15363.923180384027, 15513.612159695873, 630181.0, 638953.0, 640932.0, 641628.0, 641809.0, 48798.02403951901, 49461.25808112881, 55556.16665750017, 56085.7195714027, 56129.87740245254, 1349.6693310138883, 1374.9450021999207, 1397.224540380099, 1399.6710772968345, 1401.2993503248376, 29860.808616798142, 30750.231244219074, 30907.381852363276, 30917.0, 31307.0, 48191.0, 48374.0, 48629.513704863915, 48640.0, 48727.02545949132, 461527.0, 472814.0, 472824.0, 474723.0, 617521.0, 24967.752902238884, 25117.0, 25138.24585262445, 25246.621300680137, 25313.620295695946, 43135.0, 43276.35085473784, 43714.56285437105, 43940.34089488724, 44525.777371114236, 26444.867775660998, 34965.0, 36119.55521779099, 36267.81866090652, 36513.269734604626, 29438.852805735834, 34711.0, 35420.46869296911, 36628.90113296606, 36728.0, 36555.81722091373, 36633.900026890304, 36764.26471470502, 36994.8150259247, 37202.0, 30802.0, 30991.380172396875, 31832.124930283924, 31880.043598692075, 31984.52023219607, 24082.51834963326, 24421.877890610434, 24559.754402456463, 24862.627060593742, 24952.37619059538], "value": 19245.08865397964, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.6575573060533, 363.8235455803933, 369.73748638466685, 379.6924491162158, 397.99204015919685, 14950.77573836392, 15102.471413500529, 15851.603709907258, 20282.695759563583, 20750.48123796902, 19779.0, 20212.0, 20811.063502142402, 20850.165993360286, 21125.0, 5108.438071812097, 5828.679422631764, 6064.423879731418, 6078.17944577483, 6089.02575587906, 5532.861678458031, 6061.575689701723, 6120.479759220471, 6123.693815309239, 6138.355472675372, 5769.884602307951, 5963.910541341862, 6073.3926607339135, 6080.574359794816, 6080.756769729205, 6027.789027384042, 6069.605475644069, 6070.96964515179, 6076.574639775217, 6079.635221886701, 5021.497850214986, 5220.138677118271, 5257.1588545832865, 5281.392639846428, 5293.470652934714, 20513.8974305129, 25165.37086572832, 25940.87029564859, 25949.740502594923, 26255.474890502275, 2150.3226483657754, 2331.370529956919, 2369.7748713872156, 2383.0467812874817, 2747.821391609539, 9132.0, 10104.0, 10170.847437288372, 10285.691429257135, 15548.61128471786, 1819.4723530176234, 1857.8327950484386, 1869.7849747279101, 1886.6226754648987, 1993.5016245938516, 119449.0, 120488.0, 121420.0, 128014.0, 128838.3558082213, 11268.943655281671, 11336.263142895685, 11457.312561246184, 11503.76992460163, 11631.302121872715, 14400.927995359956, 14412.42350305997, 14416.783748243784, 14417.92791036038, 14428.639284017983, 8254.917450825415, 8365.288950439159, 8406.789830254293, 8414.78963025917, 8495.915040849512, 8285.668573257002, 8290.792730181794, 8300.875486867702, 8442.197991190827, 8466.195711407407, 3855.8650447234772, 4189.743077076865, 4456.398386217884, 4493.326001099796, 4551.908961820745, 1636.8444997725196, 1867.7758668959812, 1909.2172209394082, 1915.5115445561294, 1928.9035548222532, 751.620431681997, 839.7361970234757, 880.907504711996, 881.2817553693719, 948.7390967483859, 20212.27549214499, 21541.892290538446, 21571.56856862854, 21675.132994680353, 21793.91030448471, 739.2939742545856, 901.5942825728448, 922.1055576091181, 922.4096578189964, 931.8415869302235, 10972.177086718528, 11390.430478476042, 11489.310641361379, 11496.42517874103, 11527.654170374903, 559976.0, 567856.0, 569816.0, 576348.0, 577928.0, 71716.64141679258, 110398.0, 117930.0, 133277.0, 140962.0, 901.5987885391069, 926.420986883198, 954.5084281594984, 959.1271942532262, 960.1166926427703, 15536.922315388803, 19037.048147592843, 19501.414957551297, 19523.902380488576, 19524.8047519523, 56110.0, 56272.0, 56619.71690141523, 57504.0, 58212.70893645505, 82672.58663706643, 83001.58499207466, 83340.0, 84120.0, 85842.0, 6968.128983877015, 7818.491798033338, 7972.1230664628, 8101.675932962851, 8107.8378432429845, 9872.703818885446, 9945.651902183388, 12493.562725304571, 12976.572577016332, 13200.36989827805, 10576.4711764413, 10710.357378557312, 10905.0, 11175.832362514731, 11317.660470185578, 10843.078338341329, 10892.40091794959, 11121.499532521218, 11244.718882028015, 11478.483468244116, 10746.677599672023, 11421.428928553372, 11485.310881346812, 11649.534018639159, 11712.121590880888, 7839.917191683256, 7968.960155199187, 7981.161977992228, 8322.375821813268, 8322.417430779788, 10561.735956601147, 10769.192310576895, 10992.48082735886, 11460.828087578526, 11607.15624671927], "value": 9474.319740627308, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1480.1637075052595, 1507.6607763253269, 1716.3821024431202, 1842.5854182808878, 1895.0524737631176, 8582.957085214573, 9130.497822619769, 9147.31395145365, 9154.176124148817, 9157.221636160933, 7984.405068352779, 8206.220409061145, 8220.260176584106, 8547.316214702825, 8921.821563568725, 2384.6303822907435, 2425.1754403502805, 2431.7932975697086, 2433.1605596069326, 2434.3670645632174, 2393.6768536247646, 2411.131992482705, 2423.9154327278525, 2425.0057476434667, 2435.9147429839873, 2061.742282214723, 2401.423658321999, 2417.093589903786, 2422.2491027781443, 2427.490227052321, 2127.9787202128014, 2275.4766403727235, 2390.641403789437, 2399.592069348215, 2401.087586717054, 2364.5152743687504, 2399.124319623325, 2400.099962514057, 2409.217004473553, 2411.2766170148884, 8863.689770858053, 8955.149260820212, 8980.416272942239, 8983.32625053117, 8997.100289971017, 5200.947990520084, 5360.2763626910255, 5361.544268737162, 5372.086745253317, 5372.811951581715, 3459.2562599041325, 3542.3446662367546, 3640.5085313482605, 3686.3733165361955, 3697.2605478904325, 1085.5494969587612, 1089.7657003744232, 1093.7913605466006, 1115.3976852499723, 1116.0457808573633, 9979.550920208609, 11801.350925699084, 12015.339156346397, 12029.458674359676, 12034.398280085961, 4621.976890115595, 4742.312364707098, 4859.7327147006545, 4860.73265970375, 4861.465238823726, 10117.038881306264, 10126.848097278547, 10130.848037279447, 10141.492925353703, 10156.23828212887, 5364.409914909357, 5369.382521010056, 5369.677819330775, 5371.301730774974, 5376.569874410038, 4651.511591282867, 4802.639802014793, 4888.853334399902, 4892.8532144035735, 4898.608111351084, 3794.2411517696573, 3803.220339830328, 3857.7685338879755, 3906.6679332257077, 3911.3546264865977, 1180.9055275578105, 1182.2433642468827, 1195.8863907928733, 1196.36592605919, 1202.0023380594087, 810.2464707821782, 859.2095272349453, 893.3478560650761, 941.3269512298739, 942.9203561921562, 5677.1768093627015, 5768.480836724715, 6002.819915402545, 6130.908036379458, 6199.907001394983, 427.02424958968606, 432.66684652817156, 434.20973827633406, 862.2929198057533, 1001.3140998416109, 3611.440226764803, 3791.6777073949024, 3794.994326503479, 3972.1658451724884, 4042.3734321180264, 10676.62631807883, 11091.33451992883, 11122.99893009633, 11129.499172537255, 11132.165087618461, 9596.568154432927, 10695.304805187638, 11039.72400689973, 11054.723631909106, 11056.281341712764, 8181.3863960203225, 8457.069722330538, 8473.406861519758, 8473.618687159093, 8487.10885357041, 5836.445537673914, 5838.649681019152, 5932.851678708067, 5947.821565353046, 6023.247094113236, 9775.951120244637, 10288.588456461957, 10324.793504129726, 10341.9482902585, 10383.273170877967, 9171.678991235542, 10579.047885690325, 10587.470626468492, 10620.787584248426, 10622.89377106219, 6668.133142691419, 6880.862382752217, 6882.03651488782, 6950.409215216561, 6970.616616085961, 8077.83844323122, 8108.594570271581, 8416.410851240355, 8604.0, 8711.0, 7222.74720384784, 7510.50734019881, 7571.3564347031115, 7655.425843061681, 7659.463837531543, 7366.447516436396, 7402.29678180572, 7414.184439711519, 7441.1070671519765, 7485.7754267372065, 6990.755323563651, 7111.502194846311, 7112.57324560528, 7115.0, 7139.107611548557, 4775.47469778331, 4902.583280421204, 5031.3207716959205, 5047.2429135629945, 5173.430922598586, 4937.160682683881, 4941.357623508921, 5104.566111880383, 5141.38303403594, 5222.3472065991755], "value": 4569.430328212621, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.68750373156604, 265.98670066496675, 300.27783181448626, 309.5001572460477, 313.69258127035516, 4559.63522918167, 5307.6019298552665, 5315.309009828722, 5344.973275133624, 5448.182772584114, 4561.908761824761, 4987.251912213161, 5129.948700512994, 5150.0, 5346.946530534694, 3506.859725610985, 4007.5792041835625, 4026.9798651006704, 4042.474478317822, 4052.7365721228025, 3336.8654657416596, 3612.1150318171954, 4018.1361007383407, 4037.5356833964174, 4052.3516237402023, 3730.675610158382, 3863.9613603864022, 4000.4999375078114, 4019.4171845082446, 4022.275990321741, 3286.474164133751, 4020.396940458925, 4032.2540330039037, 4034.899127521806, 4059.249038927793, 2915.781316401278, 3485.8431370588382, 3496.4930085137767, 3520.436730123168, 3521.8479460882368, 4562.5665561771575, 4857.761270875904, 5107.463716309771, 5143.408508021588, 5163.845084647466, 3531.823408829548, 4228.0, 4368.279233926399, 4583.564561366665, 4595.9080818383445, 4881.34101896243, 5126.61550383719, 5490.890182196334, 5492.945070549284, 5508.0, 3510.4558793387064, 3835.846566137351, 3840.558335791392, 3842.2123464689953, 3844.4233364995193, 5118.97440512795, 5478.260434841325, 5710.0, 5779.855503612402, 5817.243758311392, 3222.7099561039618, 3587.94155724062, 3731.4962480065387, 3887.00881275273, 3929.528456585228, 3947.230290093404, 3953.9011524711536, 3954.0, 3963.9405408918888, 3980.3034468968394, 4197.538270790211, 4278.737772357162, 4364.869053928387, 4638.814447422133, 4647.374233558029, 4494.438195225597, 5522.751476183581, 5527.55779537636, 5548.528375088082, 5632.0, 1317.2425855133338, 1341.3099494636829, 1342.1879762743595, 1348.7456665301363, 1352.7362164377853, 853.7054716122927, 885.588201486312, 894.6197865907094, 895.5969813583913, 898.9011208767031, 4606.792694328763, 5001.599552125411, 5149.005464699236, 5160.7419629018395, 5185.688858668492, 4485.282354823278, 4956.727379994135, 4984.675996060245, 5007.324011258506, 5689.431056894277, 4470.68114906097, 4599.540045995441, 4857.352742755207, 5111.006707384123, 5207.734635433599, 2724.937274462961, 2751.257160566676, 2943.8822447101875, 2982.0, 3035.590195323647, 5221.869453263699, 5949.702514874239, 5972.0, 6014.398560143951, 6034.487068599218, 4897.436794768576, 5059.772310246048, 5906.3207731110615, 5933.199018132583, 5934.554908381889, 5980.192673988956, 5990.580659353804, 5993.370696076936, 6018.668973206516, 6074.240719910012, 5235.073694728953, 5395.358133259955, 5804.21643078179, 5804.506616937439, 5849.3858144894175, 5160.793568257228, 5640.915386269127, 5692.373838877803, 5695.914561281501, 5711.7429715662065, 5395.379531353945, 5550.834324791839, 5956.463918247206, 5982.46157845796, 6032.849178770391, 3303.7941151480018, 3511.5259439975275, 3527.2945410917405, 3576.678098971105, 3617.8733744318824, 5774.884502310014, 5780.190773291826, 5784.913226301524, 5801.535877129735, 5849.356570777126, 5153.547123427238, 5521.713011920239, 5666.016894087036, 5683.0, 5687.374388817144, 5548.944510554843, 5575.135853942646, 5588.0, 5603.5797315202335, 5612.971935140298, 4789.790840137047, 5477.685355514727, 5482.17767334887, 5483.232347471437, 5490.890182196414, 5078.669886457368, 5130.435652078343, 5434.592405569677, 5651.626316946974, 5688.857778555569, 5481.671099734029, 5488.670679759227, 5499.835004949858, 5512.39363670004, 5516.641418307797], "value": 4045.665034665765, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.5102550650779, 723.1177962885281, 754.4643303254688, 773.6982576795051, 782.9451938364317, 14441.855581444182, 15651.373945042198, 16133.757993630075, 17042.573935651613, 17053.232604532794, 15603.375864965388, 15987.520374388787, 15991.040537567755, 15992.0, 16056.59858503535, 838.861587838006, 856.8629019356904, 857.9656813727474, 857.995710021449, 858.2361698088706, 673.6833688166554, 692.5879101934341, 694.0144994108379, 842.1326034184799, 858.5406807358065, 895.234574438852, 917.9810410444402, 918.6784625381097, 920.4615300049479, 920.5535315372039, 910.0762725833284, 910.2808781062952, 911.4030310146865, 911.7355966769629, 912.6623149434752, 518.7587771686146, 532.5287120897995, 532.7496076843877, 674.3256743256743, 676.2155899156954, 11834.644960651194, 11864.169508134433, 14563.563093107117, 14691.0, 14744.189069601274, 13187.670308242275, 13523.120997135155, 13574.389152488064, 13640.181589104686, 13930.164190148624, 11479.368634725086, 11659.125565582615, 11778.528858845635, 12102.821492131412, 12122.636320910387, 3628.584851907758, 3672.761270517408, 3689.24370504046, 3710.888673339777, 3833.3483307837732, 95360.0, 124190.37904810508, 126207.73792262052, 126679.0, 127847.0, 9676.516174191262, 9939.701808945743, 9961.80076398468, 10242.334248273839, 10864.130869530418, 21851.453713657287, 22892.084316627148, 23119.422014449774, 23143.884280578826, 23241.767582324297, 6041.0, 8260.380471464665, 8287.50274983503, 8303.750887473385, 8314.916850831536, 4302.182585308844, 4342.739435633872, 4434.600885920283, 4474.910501790011, 4603.194440972817, 3219.275662975812, 3372.7133193678324, 3372.865085396557, 3375.932481350359, 3378.6283508814013, 656.1076935367896, 664.3721683009607, 672.3478226120656, 672.4855485553458, 684.5379368925956, 517.2809794385806, 620.172070286173, 636.0110028905096, 669.6584741781727, 674.2616834566143, 7952.801179970431, 8254.669813207525, 8340.249377556049, 8531.402801803939, 9331.626734930527, 645.8998855177455, 654.1398061549048, 665.677146583916, 670.9030734748721, 673.7338751193251, 4347.434833471629, 4510.2783554631105, 4555.111753208159, 4571.81712731487, 4626.152185483837, 167271.0, 171229.0, 174165.0, 176297.0, 179666.0, 145182.0, 147239.0, 148096.0, 153597.0, 155047.0, 105651.47174264079, 121732.97154941913, 127359.0, 128079.0, 128447.35776321245, 8694.04365519805, 8770.298376130022, 8890.123628281572, 8961.551922403984, 9003.234725048444, 53168.0, 65784.0, 66750.0, 67014.99477507744, 67824.0, 98666.50666746621, 102289.48855255677, 102888.0, 107289.46355268174, 110345.0, 10972.780544389227, 11311.208215424842, 11686.766264674488, 12013.339266340436, 12840.0, 11092.33445993243, 14432.424348172723, 14929.925350373178, 15044.774328384865, 15155.090694558361, 12973.481060757464, 13171.616980216939, 13467.528636497676, 13529.72940541164, 13889.305534723426, 11125.44372781374, 13350.265735384648, 13398.799018014932, 14170.504032358818, 14205.076670016417, 12747.235165890077, 12768.231768231768, 12866.228026318451, 12873.935630321788, 13509.932450337685, 10997.945010274898, 11366.5510616535, 11508.769824603294, 11643.243189193014, 11661.825072624088, 9320.229156460251, 11015.559377624806, 11021.063209627271, 11148.442577871236, 11290.379029153475], "value": 7826.986606997769, "unit": "score"}], "name": "dromaeo_css", "value": 7290.010851627795, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 117.0, 113.0, 114.0, 113.0, 113.0, 114.0, 113.0, 124.0], "value": 114.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 108.0, 129.0, 159.0, 106.0, 124.0, 130.0, 135.0, 197.0, 137.0], "value": 137.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 183.0, 184.0, 182.0, 184.0, 185.0, 180.0, 182.0, 183.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 80.0, 180.0, 82.0, 82.0, 57.0, 62.0, 137.0, 59.0], "value": 88.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 117.0, 72.0, 91.0, 112.0, 153.0, 96.0, 70.0, 118.0], "value": 99.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 88.0, 91.0, 74.0, 117.0, 71.0, 117.0, 72.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 107.0, 151.0, 151.0, 96.0, 95.0, 95.0, 94.0, 96.0], "value": 107.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 87.0, 85.0, 87.0, 84.0, 84.0, 86.0, 85.0, 86.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 54.0, 54.0, 55.0, 54.0, 53.0, 55.0, 54.0, 53.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 63.0, 76.0, 74.0, 76.0, 69.0, 70.0, 69.0, 70.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 88.0, 86.0, 93.0, 104.0, 105.0, 102.0, 85.0, 86.0, 85.0], "value": 91.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 163.0, 128.0, 132.0, 145.0, 152.0, 153.0, 138.0, 190.0, 154.0], "value": 148.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 169.0, 168.0, 166.0, 166.0, 179.0, 168.0, 169.0, 167.0, 169.0], "value": 167.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 68.0, 66.0, 64.0, 65.0, 66.0, 65.0, 67.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1503.0, "alertThreshold": 2.0}]} 12:13:31 INFO - Return code: 0 12:13:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:13:31 INFO - # TBPL SUCCESS # 12:13:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:13:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:13:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:13:31 INFO - 2016-09-12 12:13:31.607 screenresolution[1666:9967] starting screenresolution argv=screenresolution get 12:13:31 INFO - 2016-09-12 12:13:31.610 screenresolution[1666:9967] Display 0: 1600x1200x32@60 12:13:31 INFO - 2016-09-12 12:13:31.616 screenresolution[1667:9971] starting screenresolution argv=screenresolution list 12:13:31 INFO - Available Modes on Display 0 12:13:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:13:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:13:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:13:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:13:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:13:31 INFO - Intel Iris: 12:13:31 INFO - Chipset Model: Intel Iris 12:13:31 INFO - Type: GPU 12:13:31 INFO - Bus: Built-In 12:13:31 INFO - VRAM (Dynamic, Max): 1536 MB 12:13:31 INFO - Vendor: Intel (0x8086) 12:13:31 INFO - Device ID: 0x0a2e 12:13:31 INFO - Revision ID: 0x0009 12:13:31 INFO - Displays: 12:13:31 INFO - Display: 12:13:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:13:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:13:31 INFO - Display Serial Number: 1600x1200 60 12:13:31 INFO - Main Display: Yes 12:13:31 INFO - Mirror: Off 12:13:31 INFO - Online: Yes 12:13:31 INFO - Rotation: Supported 12:13:31 INFO - Return code: 0 12:13:31 INFO - Running post-action listener: _resource_record_post_action 12:13:31 INFO - [mozharness: 2016-09-12 19:13:31.851762Z] Finished run-tests step (success) 12:13:31 INFO - Running post-run listener: _resource_record_post_run 12:13:31 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 31923200; Write bytes: 705866240; Read time: 194; Write time: 2038 12:13:31 INFO - TinderboxPrint: CPU usage
53.3% 12:13:31 INFO - TinderboxPrint: I/O read bytes / time
31,923,200 / 194 12:13:31 INFO - TinderboxPrint: I/O write bytes / time
705,866,240 / 2,038 12:13:31 INFO - TinderboxPrint: CPU idle
2,264.8 (46.7%) 12:13:31 INFO - TinderboxPrint: CPU system
966.8 (19.9%) 12:13:31 INFO - TinderboxPrint: CPU user
1,622.2 (33.4%) 12:13:31 INFO - TinderboxPrint: Swap in / out
447,971,328 / 0 12:13:31 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 390263296; Write bytes: 385431552; Read time: 29249; Write time: 881 12:13:31 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 30997504; Write bytes: 296280576; Read time: 182; Write time: 1099 12:13:32 INFO - Running post-run listener: _upload_blobber_files 12:13:32 INFO - Blob upload gear active. 12:13:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:13:32 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 12:13:32 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'] 12:13:32 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 12:13:32 INFO - (blobuploader) - INFO - Open directory for files ... 12:13:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:13:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:13:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:13:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:13:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:13:34 INFO - (blobuploader) - INFO - Done attempting. 12:13:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:13:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:13:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:13:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:13:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:13:35 INFO - (blobuploader) - INFO - Done attempting. 12:13:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:13:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:13:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:13:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:13:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:13:37 INFO - (blobuploader) - INFO - Done attempting. 12:13:37 INFO - (blobuploader) - INFO - Iteration through files over. 12:13:37 INFO - Return code: 0 12:13:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:13:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:13:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/50b471c892f25b3c4fca0788476bd315aedef9ec19cd1a51bf3a7a519f0cf0027c6c4254c2d1b516df6b790c6a576548f9c80c868978db7218269a88c30387ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eaf78068b68d709a27a0fbd873d522b472952fa1c6bf84efc7db7e627d3570894032abb0841fd386c7381abe2d782b987b672e0e1335919db8078fcaec4d0c3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/018b720958bb4ec831e742bf3aad50f1a19ef051165552f777b4774275581b95597c46646be8162cecdb522ada60020eb86443544213513bc054cf192252c5af"} 12:13:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:13:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:13:37 INFO - Contents: 12:13:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/50b471c892f25b3c4fca0788476bd315aedef9ec19cd1a51bf3a7a519f0cf0027c6c4254c2d1b516df6b790c6a576548f9c80c868978db7218269a88c30387ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eaf78068b68d709a27a0fbd873d522b472952fa1c6bf84efc7db7e627d3570894032abb0841fd386c7381abe2d782b987b672e0e1335919db8078fcaec4d0c3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/018b720958bb4ec831e742bf3aad50f1a19ef051165552f777b4774275581b95597c46646be8162cecdb522ada60020eb86443544213513bc054cf192252c5af"} 12:13:37 INFO - Running post-run listener: copy_logs_to_upload_dir 12:13:37 INFO - Copying logs to upload dir... 12:13:37 INFO - mkdir: /builds/slave/test/build/upload/logs 12:13:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.733302 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-09-12 12:13:37.859000) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 12:13:37.862511) ========= 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.bw6vZAKdoj/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.FUR7sydwtc/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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/50b471c892f25b3c4fca0788476bd315aedef9ec19cd1a51bf3a7a519f0cf0027c6c4254c2d1b516df6b790c6a576548f9c80c868978db7218269a88c30387ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eaf78068b68d709a27a0fbd873d522b472952fa1c6bf84efc7db7e627d3570894032abb0841fd386c7381abe2d782b987b672e0e1335919db8078fcaec4d0c3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/018b720958bb4ec831e742bf3aad50f1a19ef051165552f777b4774275581b95597c46646be8162cecdb522ada60020eb86443544213513bc054cf192252c5af"} build_url:https://queue.taskcluster.net/v1/task/FlO45dbES7OIoX7ToNJ44w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011187 build_url: 'https://queue.taskcluster.net/v1/task/FlO45dbES7OIoX7ToNJ44w/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/50b471c892f25b3c4fca0788476bd315aedef9ec19cd1a51bf3a7a519f0cf0027c6c4254c2d1b516df6b790c6a576548f9c80c868978db7218269a88c30387ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eaf78068b68d709a27a0fbd873d522b472952fa1c6bf84efc7db7e627d3570894032abb0841fd386c7381abe2d782b987b672e0e1335919db8078fcaec4d0c3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/018b720958bb4ec831e742bf3aad50f1a19ef051165552f777b4774275581b95597c46646be8162cecdb522ada60020eb86443544213513bc054cf192252c5af"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 12:13:37.900354) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 12:13:37.901074) ========= 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.bw6vZAKdoj/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.FUR7sydwtc/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.005431 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 12:13:37.954308) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 12:13:37.955007) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 12:13:37.955813) ========= ========= Total master_lag: 4.63 =========