builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0323 starttime: 1483571624.29 results: success (0) buildid: 20170104125439 builduid: f637577825ff4df79c23378309951649 revision: 1672ba92485f35fad11519b18fb9f0136aa09c4d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 15:13:44.290788) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 15:13:44.291221) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 15:13:44.291498) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 15:13:44.316354) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 15:13:44.316766) ========= 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.QvGA9s14DV/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.tX5U1Ekpkh/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-04 15:13:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 149M=0s 2017-01-04 15:13:44 (149 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.151515 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 15:13:44.495170) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 15:13:44.495672) ========= 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.QvGA9s14DV/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.tX5U1Ekpkh/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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.021068 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 15:13:44.591708) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-01-04 15:13:44.592093) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1672ba92485f35fad11519b18fb9f0136aa09c4d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1672ba92485f35fad11519b18fb9f0136aa09c4d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QvGA9s14DV/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.tX5U1Ekpkh/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-04 15:13:44,676 truncating revision to first 12 chars 2017-01-04 15:13:44,676 Setting DEBUG logging. 2017-01-04 15:13:44,676 attempt 1/10 2017-01-04 15:13:44,676 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/1672ba92485f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-04 15:13:45,795 unpacking tar archive at: mozilla-beta-1672ba92485f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.388406 ========= master_lag: 2.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-01-04 15:13:48.091179) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 15:13:48.091451) ========= script_repo_revision: 1672ba92485f35fad11519b18fb9f0136aa09c4d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 15:13:48.091822) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 15:13:48.092080) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 15:13:48.198925) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-01-04 15:13:48.199187) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QvGA9s14DV/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.tX5U1Ekpkh/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:13:48 INFO - MultiFileLogger online at 20170104 15:13:48 in /builds/slave/test 15:13:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:13:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:13:48 INFO - {'append_to_log': False, 15:13:48 INFO - 'base_work_dir': '/builds/slave/test', 15:13:48 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:13:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:13:48 INFO - 'branch': 'Mozilla-Beta', 15:13:48 INFO - 'buildbot_json_path': 'buildprops.json', 15:13:48 INFO - 'config_files': ('talos/mac_config.py',), 15:13:48 INFO - 'default_actions': ('clobber', 15:13:48 INFO - 'read-buildbot-config', 15:13:48 INFO - 'download-and-extract', 15:13:48 INFO - 'populate-webroot', 15:13:48 INFO - 'create-virtualenv', 15:13:48 INFO - 'install', 15:13:48 INFO - 'run-tests'), 15:13:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:13:48 INFO - 'download_minidump_stackwalk': True, 15:13:48 INFO - 'download_symbols': 'ondemand', 15:13:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:13:48 INFO - 'tooltool.py': '/tools/tooltool.py', 15:13:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:13:48 INFO - '/tools/misc-python/virtualenv.py')}, 15:13:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:13:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:13:48 INFO - 'installer_path': 'installer.exe', 15:13:48 INFO - 'log_level': 'info', 15:13:48 INFO - 'log_name': 'talos', 15:13:48 INFO - 'log_to_console': True, 15:13:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:13:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:13:48 INFO - 'opt_config_files': (), 15:13:48 INFO - 'pip_index': False, 15:13:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:13:48 INFO - 'cmd': ('bash', 15:13:48 INFO - '-c', 15:13:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:13:48 INFO - 'enabled': True, 15:13:48 INFO - 'halt_on_failure': False, 15:13:48 INFO - 'name': 'check_screen_resolution'},), 15:13:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:13:48 INFO - 'cmd': ('bash', 15:13:48 INFO - '-c', 15:13:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:13:48 INFO - 'enabled': True, 15:13:48 INFO - 'halt_on_failure': False, 15:13:48 INFO - 'name': 'check_screen_resolution'},), 15:13:48 INFO - 'run_cmd_checks_enabled': True, 15:13:48 INFO - 'sps_profile': False, 15:13:48 INFO - 'sps_profile_interval': 0, 15:13:48 INFO - 'suite': 'dromaeojs', 15:13:48 INFO - 'system_bits': '32', 15:13:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:13:48 INFO - 'title': 't-yosemite-r7-0323', 15:13:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:13:48 INFO - 'use_talos_json': True, 15:13:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:13:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:13:48 INFO - 'work_dir': 'build'} 15:13:48 INFO - [mozharness: 2017-01-04 23:13:48.394046Z] Running clobber step. 15:13:48 INFO - Running pre-action listener: _resource_record_pre_action 15:13:48 INFO - Running main action method: clobber 15:13:48 INFO - rmtree: /builds/slave/test/build 15:13:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:13:50 INFO - Running post-action listener: _resource_record_post_action 15:13:50 INFO - [mozharness: 2017-01-04 23:13:50.060011Z] Finished clobber step (success) 15:13:50 INFO - [mozharness: 2017-01-04 23:13:50.060232Z] Running read-buildbot-config step. 15:13:50 INFO - Running pre-action listener: _resource_record_pre_action 15:13:50 INFO - Running main action method: read_buildbot_config 15:13:50 INFO - Using buildbot properties: 15:13:50 INFO - { 15:13:50 INFO - "project": "", 15:13:50 INFO - "product": "firefox", 15:13:50 INFO - "script_repo_revision": "production", 15:13:50 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 15:13:50 INFO - "repository": "", 15:13:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 15:13:50 INFO - "buildid": "20170104125439", 15:13:50 INFO - "pgo_build": "False", 15:13:50 INFO - "basedir": "/builds/slave/test", 15:13:50 INFO - "buildnumber": 4, 15:13:50 INFO - "slavename": "t-yosemite-r7-0323", 15:13:50 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 15:13:50 INFO - "platform": "yosemite_r7", 15:13:50 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 15:13:50 INFO - "branch": "mozilla-beta", 15:13:50 INFO - "repo_path": "releases/mozilla-beta", 15:13:50 INFO - "revision": "1672ba92485f35fad11519b18fb9f0136aa09c4d", 15:13:50 INFO - "stage_platform": "macosx64", 15:13:50 INFO - "builduid": "f637577825ff4df79c23378309951649", 15:13:50 INFO - "slavebuilddir": "test" 15:13:50 INFO - } 15:13:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.dmg. 15:13:50 INFO - Running post-action listener: _resource_record_post_action 15:13:50 INFO - [mozharness: 2017-01-04 23:13:50.065037Z] Finished read-buildbot-config step (success) 15:13:50 INFO - [mozharness: 2017-01-04 23:13:50.065174Z] Running download-and-extract step. 15:13:50 INFO - Running pre-action listener: _resource_record_pre_action 15:13:50 INFO - Running main action method: download_and_extract 15:13:50 INFO - mkdir: /builds/slave/test/build/tests 15:13: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')]} 15:13:50 INFO - trying https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json 15:13:50 INFO - Downloading https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 15:13:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json'}, attempt #1 15:13:51 INFO - Downloaded 1165 bytes. 15:13:51 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 15:13:51 INFO - Using the following test package requirements: 15:13:51 INFO - {u'common': [u'firefox-51.0.en-US.mac.common.tests.zip'], 15:13:51 INFO - u'cppunittest': [u'firefox-51.0.en-US.mac.common.tests.zip', 15:13:51 INFO - u'firefox-51.0.en-US.mac.cppunittest.tests.zip'], 15:13:51 INFO - u'gtest': [u'firefox-51.0.en-US.mac.common.tests.zip', 15:13:51 INFO - u'firefox-51.0.en-US.mac.gtest.tests.zip'], 15:13:51 INFO - u'jittest': [u'firefox-51.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:13:51 INFO - u'mochitest': [u'firefox-51.0.en-US.mac.common.tests.zip', 15:13:51 INFO - u'firefox-51.0.en-US.mac.mochitest.tests.zip'], 15:13:51 INFO - u'mozbase': [u'firefox-51.0.en-US.mac.common.tests.zip'], 15:13:51 INFO - u'reftest': [u'firefox-51.0.en-US.mac.common.tests.zip', 15:13:51 INFO - u'firefox-51.0.en-US.mac.reftest.tests.zip'], 15:13:51 INFO - u'talos': [u'firefox-51.0.en-US.mac.common.tests.zip', 15:13:51 INFO - u'firefox-51.0.en-US.mac.talos.tests.zip'], 15:13:51 INFO - u'web-platform': [u'firefox-51.0.en-US.mac.common.tests.zip', 15:13:51 INFO - u'firefox-51.0.en-US.mac.web-platform.tests.zip'], 15:13:51 INFO - u'xpcshell': [u'firefox-51.0.en-US.mac.common.tests.zip', 15:13:51 INFO - u'firefox-51.0.en-US.mac.xpcshell.tests.zip']} 15:13:51 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip'] for test suite category: common 15:13:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 15:13:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 15:13:51 INFO - Fetch https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 15:13:52 INFO - Expected file size: 16234519 15:13:58 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip', u'firefox-51.0.en-US.mac.talos.tests.zip'] for test suite category: talos 15:13:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 15:13:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 15:13:58 INFO - Fetch https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 15:13:59 INFO - Expected file size: 16234519 15:14:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip 15:14:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip'}, attempt #1 15:14:05 INFO - Fetch https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip into memory 15:14:06 INFO - Expected file size: 14414089 15:14:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:06 INFO - trying https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.dmg 15:14:06 INFO - Downloading https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0.en-US.mac.dmg 15:14:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg'}, attempt #1 15:14:09 INFO - Downloaded 86095938 bytes. 15:14:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.dmg 15:14:09 INFO - mkdir: /builds/slave/test/properties 15:14:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:14:09 INFO - Writing to file /builds/slave/test/properties/build_url 15:14:09 INFO - Contents: 15:14:09 INFO - build_url:https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.dmg 15:14:09 INFO - Running post-action listener: _resource_record_post_action 15:14:09 INFO - Running post-action listener: set_extra_try_arguments 15:14:09 INFO - [mozharness: 2017-01-04 23:14:09.816085Z] Finished download-and-extract step (success) 15:14:09 INFO - [mozharness: 2017-01-04 23:14:09.816237Z] Running populate-webroot step. 15:14:09 INFO - Running pre-action listener: _resource_record_pre_action 15:14:09 INFO - Running main action method: populate_webroot 15:14:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:14:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:14:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:14:09 INFO - '--tppagecycles', 15:14:09 INFO - '7'], 15:14:09 INFO - 'tests': ['tsvgm']}}, 15:14:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:14:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:14:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:14:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:14:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:14:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:14:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:14:09 INFO - 'talos_options': ['--disable-e10s'], 15:14:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:14:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:14:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:14:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:14:09 INFO - 'talos_options': ['--disable-e10s'], 15:14:09 INFO - 'tests': ['damp', 'tps']}, 15:14:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:14:09 INFO - 'tests': ['damp', 'tps']}, 15:14:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:14:09 INFO - 'tests': ['dromaeo_dom']}, 15:14:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:14:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:14:09 INFO - 'tests': ['basic_compositor_video']}, 15:14:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:14:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:14:09 INFO - 'tests': ['a11yr', 15:14:09 INFO - 'ts_paint', 15:14:09 INFO - 'tpaint', 15:14:09 INFO - 'sessionrestore', 15:14:09 INFO - 'sessionrestore_no_auto_restore', 15:14:09 INFO - 'tabpaint']}, 15:14:09 INFO - 'other-e10s': {'tests': ['a11yr', 15:14:09 INFO - 'ts_paint', 15:14:09 INFO - 'tpaint', 15:14:09 INFO - 'sessionrestore', 15:14:09 INFO - 'sessionrestore_no_auto_restore', 15:14:09 INFO - 'tabpaint']}, 15:14:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:14:09 INFO - 'ts_paint', 15:14:09 INFO - 'tpaint', 15:14:09 INFO - 'sessionrestore', 15:14:09 INFO - 'sessionrestore_no_auto_restore', 15:14:09 INFO - 'tabpaint']}, 15:14:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:14:09 INFO - 'ts_paint', 15:14:09 INFO - 'tpaint', 15:14:09 INFO - 'sessionrestore', 15:14:09 INFO - 'sessionrestore_no_auto_restore', 15:14:09 INFO - 'tabpaint']}, 15:14:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:14:09 INFO - 'tests': ['a11yr', 15:14:09 INFO - 'ts_paint', 15:14:09 INFO - 'tpaint', 15:14:09 INFO - 'sessionrestore', 15:14:09 INFO - 'sessionrestore_no_auto_restore', 15:14:09 INFO - 'tabpaint']}, 15:14:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:14:09 INFO - 'tests': ['a11yr', 15:14:09 INFO - 'ts_paint', 15:14:09 INFO - 'tpaint', 15:14:09 INFO - 'sessionrestore', 15:14:09 INFO - 'sessionrestore_no_auto_restore', 15:14:09 INFO - 'tabpaint']}, 15:14:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:14:09 INFO - 'tests': ['tsvgx', 15:14:09 INFO - 'tsvgr_opacity', 15:14:09 INFO - 'tart', 15:14:09 INFO - 'tscrollx', 15:14:09 INFO - 'cart']}, 15:14:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:14:09 INFO - 'tsvgr_opacity', 15:14:09 INFO - 'tart', 15:14:09 INFO - 'tscrollx', 15:14:09 INFO - 'cart']}, 15:14:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:14:09 INFO - 'talos_options': ['--disable-e10s'], 15:14:09 INFO - 'tests': ['tp5o']}, 15:14:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:14:09 INFO - 'tests': ['tp5o']}, 15:14:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:14:09 INFO - 'talos_options': ['--disable-e10s', 15:14:09 INFO - '--xperf_path', 15:14:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:14:09 INFO - 'tests': ['tp5n']}, 15:14:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:14:09 INFO - 'talos_options': ['--xperf_path', 15:14:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:14:09 INFO - 'tests': ['tp5n']}}, 15:14:09 INFO - 'talos.zip': {'path': '', 15:14:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:14:09 INFO - Running post-action listener: _resource_record_post_action 15:14:09 INFO - [mozharness: 2017-01-04 23:14:09.835089Z] Finished populate-webroot step (success) 15:14:09 INFO - [mozharness: 2017-01-04 23:14:09.835172Z] Running create-virtualenv step. 15:14:09 INFO - Running pre-action listener: _resource_record_pre_action 15:14:09 INFO - Running main action method: create_virtualenv 15:14:09 INFO - Creating virtualenv /builds/slave/test/build/venv 15:14:09 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:14:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:14:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:14:09 INFO - Using real prefix '/tools/python27' 15:14:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:14:10 INFO - Installing distribute.............................................................................................................................................................................................done. 15:14:13 INFO - Installing pip.................done. 15:14:13 INFO - Return code: 0 15:14:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102344490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a1258a8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102158d30>, '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 0x101fbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102150b20>, '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.QvGA9s14DV/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.tX5U1Ekpkh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 15:14:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:14:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:14:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QvGA9s14DV/Render', 15:14:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:13 INFO - 'HOME': '/Users/cltbld', 15:14:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:13 INFO - 'LOGNAME': 'cltbld', 15:14:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:13 INFO - 'MOZ_NO_REMOTE': '1', 15:14:13 INFO - 'NO_EM_RESTART': '1', 15:14:13 INFO - 'PAGER': '/bin/cat', 15:14:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:13 INFO - 'PWD': '/builds/slave/test', 15:14:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:13 INFO - 'SHELL': '/bin/bash', 15:14:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tX5U1Ekpkh/Listeners', 15:14:13 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 15:14:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:13 INFO - 'USER': 'cltbld', 15:14:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:13 INFO - 'XPC_FLAGS': '0x0', 15:14:13 INFO - 'XPC_SERVICE_NAME': '0', 15:14:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:13 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 15:14:13 INFO - Cleaning up... 15:14:13 INFO - Return code: 0 15:14:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102344490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a1258a8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102158d30>, '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 0x101fbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102150b20>, '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.QvGA9s14DV/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.tX5U1Ekpkh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 15:14:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:14:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:14:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QvGA9s14DV/Render', 15:14:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:13 INFO - 'HOME': '/Users/cltbld', 15:14:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:13 INFO - 'LOGNAME': 'cltbld', 15:14:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:13 INFO - 'MOZ_NO_REMOTE': '1', 15:14:13 INFO - 'NO_EM_RESTART': '1', 15:14:13 INFO - 'PAGER': '/bin/cat', 15:14:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:13 INFO - 'PWD': '/builds/slave/test', 15:14:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:13 INFO - 'SHELL': '/bin/bash', 15:14:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tX5U1Ekpkh/Listeners', 15:14:13 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 15:14:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:13 INFO - 'USER': 'cltbld', 15:14:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:13 INFO - 'XPC_FLAGS': '0x0', 15:14:13 INFO - 'XPC_SERVICE_NAME': '0', 15:14:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:14 INFO - Downloading/unpacking psutil>=3.1.1 15:14: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 15:14: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 15:14: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 15:14: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 15:14: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 15:14: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 15:14:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:14:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:14:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:14:17 INFO - Installing collected packages: psutil 15:14:17 INFO - Running setup.py install for psutil 15:14:18 INFO - building 'psutil._psutil_osx' extension 15:14:18 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 15:14:18 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 15:14:18 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 15:14:18 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 15:14:18 INFO - building 'psutil._psutil_posix' extension 15:14:18 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 15:14:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:14:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:14:18 INFO - ^ 15:14:18 INFO - 1 warning generated. 15:14:18 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 15:14:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:14:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:14:18 INFO - Successfully installed psutil 15:14:18 INFO - Cleaning up... 15:14:18 INFO - Return code: 0 15:14:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:14: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')]} 15:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14: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')]} 15:14:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102344490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a1258a8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102158d30>, '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 0x101fbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102150b20>, '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.QvGA9s14DV/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.tX5U1Ekpkh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 15:14: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:14: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 mozsystemmonitor==0.3 15:14:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QvGA9s14DV/Render', 15:14:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:18 INFO - 'HOME': '/Users/cltbld', 15:14:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:18 INFO - 'LOGNAME': 'cltbld', 15:14:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:18 INFO - 'MOZ_NO_REMOTE': '1', 15:14:18 INFO - 'NO_EM_RESTART': '1', 15:14:18 INFO - 'PAGER': '/bin/cat', 15:14:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:18 INFO - 'PWD': '/builds/slave/test', 15:14:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:18 INFO - 'SHELL': '/bin/bash', 15:14:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tX5U1Ekpkh/Listeners', 15:14:18 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 15:14:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:18 INFO - 'USER': 'cltbld', 15:14:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:18 INFO - 'XPC_FLAGS': '0x0', 15:14:18 INFO - 'XPC_SERVICE_NAME': '0', 15:14:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:14:19 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 15:14:19 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 15:14:19 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 15:14:19 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 15:14:19 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 15:14:19 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 15:14:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:14:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:14:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:14:21 INFO - Installing collected packages: mozsystemmonitor 15:14:21 INFO - Running setup.py install for mozsystemmonitor 15:14:21 INFO - Successfully installed mozsystemmonitor 15:14:21 INFO - Cleaning up... 15:14:22 INFO - Return code: 0 15:14:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:14:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:22 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 0x102344490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a1258a8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102158d30>, '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 0x101fbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102150b20>, '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.QvGA9s14DV/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.tX5U1Ekpkh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 15:14:22 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 15:14:22 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 15:14:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QvGA9s14DV/Render', 15:14:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:22 INFO - 'HOME': '/Users/cltbld', 15:14:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:22 INFO - 'LOGNAME': 'cltbld', 15:14:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:22 INFO - 'MOZ_NO_REMOTE': '1', 15:14:22 INFO - 'NO_EM_RESTART': '1', 15:14:22 INFO - 'PAGER': '/bin/cat', 15:14:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:22 INFO - 'PWD': '/builds/slave/test', 15:14:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:22 INFO - 'SHELL': '/bin/bash', 15:14:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tX5U1Ekpkh/Listeners', 15:14:22 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 15:14:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:22 INFO - 'USER': 'cltbld', 15:14:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:22 INFO - 'XPC_FLAGS': '0x0', 15:14:22 INFO - 'XPC_SERVICE_NAME': '0', 15:14:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:22 INFO - Downloading/unpacking jsonschema==2.5.1 15:14:22 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 15:14:22 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 15:14:22 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 15:14:22 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 15:14:22 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 15:14:22 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 15:14:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:14:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:14:24 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 15:14:24 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 15:14:24 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 15:14:24 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 15:14:24 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 15:14:24 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 15:14:25 INFO - Downloading functools32-3.2.3-2.tar.gz 15:14:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:14:25 INFO - warning: no files found matching '*.txt' 15:14:25 INFO - no previously-included directories found matching 'build' 15:14:25 INFO - no previously-included directories found matching 'dist' 15:14:25 INFO - no previously-included directories found matching '.git*' 15:14:25 INFO - Installing collected packages: jsonschema, functools32 15:14:25 INFO - Running setup.py install for functools32 15:14:25 INFO - warning: no files found matching '*.txt' 15:14:25 INFO - no previously-included directories found matching 'build' 15:14:25 INFO - no previously-included directories found matching 'dist' 15:14:25 INFO - no previously-included directories found matching '.git*' 15:14:25 INFO - Successfully installed jsonschema functools32 15:14:25 INFO - Cleaning up... 15:14:25 INFO - Return code: 0 15:14:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:25 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 0x102344490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a1258a8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102158d30>, '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 0x101fbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102150b20>, '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.QvGA9s14DV/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.tX5U1Ekpkh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 15:14:25 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 15:14:25 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 15:14:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QvGA9s14DV/Render', 15:14:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:25 INFO - 'HOME': '/Users/cltbld', 15:14:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:25 INFO - 'LOGNAME': 'cltbld', 15:14:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:25 INFO - 'MOZ_NO_REMOTE': '1', 15:14:25 INFO - 'NO_EM_RESTART': '1', 15:14:25 INFO - 'PAGER': '/bin/cat', 15:14:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:25 INFO - 'PWD': '/builds/slave/test', 15:14:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:25 INFO - 'SHELL': '/bin/bash', 15:14:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tX5U1Ekpkh/Listeners', 15:14:25 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 15:14:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:25 INFO - 'USER': 'cltbld', 15:14:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:25 INFO - 'XPC_FLAGS': '0x0', 15:14:25 INFO - 'XPC_SERVICE_NAME': '0', 15:14:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:14:25 INFO - Cleaning up... 15:14:25 INFO - Return code: 0 15:14:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:25 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 0x102344490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a1258a8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102158d30>, '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 0x101fbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102150b20>, '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.QvGA9s14DV/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.tX5U1Ekpkh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 15:14:25 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 15:14:25 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 15:14:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QvGA9s14DV/Render', 15:14:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:25 INFO - 'HOME': '/Users/cltbld', 15:14:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:25 INFO - 'LOGNAME': 'cltbld', 15:14:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:25 INFO - 'MOZ_NO_REMOTE': '1', 15:14:25 INFO - 'NO_EM_RESTART': '1', 15:14:25 INFO - 'PAGER': '/bin/cat', 15:14:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:25 INFO - 'PWD': '/builds/slave/test', 15:14:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:25 INFO - 'SHELL': '/bin/bash', 15:14:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tX5U1Ekpkh/Listeners', 15:14:25 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 15:14:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:25 INFO - 'USER': 'cltbld', 15:14:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:25 INFO - 'XPC_FLAGS': '0x0', 15:14:25 INFO - 'XPC_SERVICE_NAME': '0', 15:14:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:26 INFO - Downloading/unpacking blobuploader==1.2.4 15:14:26 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 15:14:26 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 15:14:26 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 15:14:26 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 15:14:26 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 15:14:26 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 15:14:28 INFO - Downloading blobuploader-1.2.4.tar.gz 15:14:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:14:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:14:28 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 15:14:28 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 15:14:28 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 15:14:28 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 15:14:28 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 15:14:28 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 15:14:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:14:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:14:29 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 15:14:29 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 15:14:29 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 15:14:29 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 15:14:29 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 15:14:29 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 15:14:30 INFO - Downloading docopt-0.6.1.tar.gz 15:14:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:14:30 INFO - Installing collected packages: blobuploader, requests, docopt 15:14:30 INFO - Running setup.py install for blobuploader 15:14:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:14:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:14:30 INFO - Running setup.py install for requests 15:14:30 INFO - Running setup.py install for docopt 15:14:30 INFO - Successfully installed blobuploader requests docopt 15:14:30 INFO - Cleaning up... 15:14:31 INFO - Return code: 0 15:14:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:14:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:31 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 0x102344490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a1258a8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102158d30>, '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 0x101fbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102150b20>, '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.QvGA9s14DV/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.tX5U1Ekpkh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 15:14:31 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 15:14:31 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 15:14:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QvGA9s14DV/Render', 15:14:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:31 INFO - 'HOME': '/Users/cltbld', 15:14:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:31 INFO - 'LOGNAME': 'cltbld', 15:14:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:31 INFO - 'MOZ_NO_REMOTE': '1', 15:14:31 INFO - 'NO_EM_RESTART': '1', 15:14:31 INFO - 'PAGER': '/bin/cat', 15:14:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:31 INFO - 'PWD': '/builds/slave/test', 15:14:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:31 INFO - 'SHELL': '/bin/bash', 15:14:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tX5U1Ekpkh/Listeners', 15:14:31 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 15:14:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:31 INFO - 'USER': 'cltbld', 15:14:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:31 INFO - 'XPC_FLAGS': '0x0', 15:14:31 INFO - 'XPC_SERVICE_NAME': '0', 15:14:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:14:31 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-sNZHHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:14:31 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-ZMX88Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:14:31 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-ShqE3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:14:31 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-7IcMdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:14:31 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-pgygbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:14:31 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-vylouB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:14:31 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-cddEK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:14:32 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-gna4cr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:14:32 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-PsDlTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:14:32 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-A7r8FG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:14:32 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-0aSltP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:14:32 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-jgLpi6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:14:32 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-W3AGt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:14:32 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-KVo73s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:32 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-KnAjh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:14:32 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-8s_Cbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:14:33 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-QO6j5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:14:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:14:33 INFO - Running setup.py install for manifestparser 15:14:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:14:33 INFO - Running setup.py install for mozcrash 15:14:33 INFO - Running setup.py install for mozdebug 15:14:33 INFO - Running setup.py install for mozdevice 15:14:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:14:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:14:33 INFO - Running setup.py install for mozfile 15:14:33 INFO - Running setup.py install for mozhttpd 15:14:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:14:33 INFO - Running setup.py install for mozinfo 15:14:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:14:34 INFO - Running setup.py install for mozInstall 15:14:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:14:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:14:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:14:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:14:34 INFO - Running setup.py install for mozleak 15:14:34 INFO - Running setup.py install for mozlog 15:14:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:14:34 INFO - Running setup.py install for moznetwork 15:14:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:14:34 INFO - Running setup.py install for mozprocess 15:14:34 INFO - Running setup.py install for mozprofile 15:14:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:14:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:14:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:14:35 INFO - Running setup.py install for mozrunner 15:14:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:14:35 INFO - Running setup.py install for mozscreenshot 15:14:35 INFO - Running setup.py install for moztest 15:14:35 INFO - Running setup.py install for mozversion 15:14:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:14:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:14:35 INFO - Cleaning up... 15:14:35 INFO - Return code: 0 15:14:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:14:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:35 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 0x102344490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a1258a8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102158d30>, '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 0x101fbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102150b20>, '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.QvGA9s14DV/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.tX5U1Ekpkh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 15:14:35 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 15:14:35 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 15:14:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QvGA9s14DV/Render', 15:14:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:35 INFO - 'HOME': '/Users/cltbld', 15:14:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:35 INFO - 'LOGNAME': 'cltbld', 15:14:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:35 INFO - 'MOZ_NO_REMOTE': '1', 15:14:35 INFO - 'NO_EM_RESTART': '1', 15:14:35 INFO - 'PAGER': '/bin/cat', 15:14:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:35 INFO - 'PWD': '/builds/slave/test', 15:14:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:35 INFO - 'SHELL': '/bin/bash', 15:14:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tX5U1Ekpkh/Listeners', 15:14:35 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 15:14:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:35 INFO - 'USER': 'cltbld', 15:14:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:35 INFO - 'XPC_FLAGS': '0x0', 15:14:35 INFO - 'XPC_SERVICE_NAME': '0', 15:14:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:14:35 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-HqRbgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:14:36 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)) 15:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:14:36 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-8uGHwP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:14:36 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)) 15:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:14:36 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-OPmT7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:14:36 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)) 15:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:14:36 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-shfrb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:14:36 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)) 15:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:14:36 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-gfVmfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:14:36 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)) 15:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:14:36 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-4Eu650-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:14:36 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)) 15:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:14:36 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-Bmn6jK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:14:36 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)) 15:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:14:36 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-lbCx_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:14:36 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)) 15:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:14:36 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-KeGlj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:14:36 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)) 15:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:14:36 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-UoJR9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:14:37 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-qhoDR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:14:37 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)) 15:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:14:37 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-yxqoo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:14:37 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)) 15:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:14:37 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-oXxUpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:14:37 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)) 15:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:14:37 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-vfYBbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:14:37 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)) 15:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:37 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-HBSagc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:37 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)) 15:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:14:37 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-Yx2gXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:14:37 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)) 15:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:14:37 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-bVChGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:14:37 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)) 15:14:37 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)) 15:14:37 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)) 15:14:37 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)) 15:14:37 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)) 15:14:37 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)) 15:14:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:14:37 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 15:14:37 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 15:14:37 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 15:14:37 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 15:14:37 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 15:14:37 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 15:14:40 INFO - Downloading blessings-1.6.tar.gz 15:14:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:14:40 INFO - Installing collected packages: blessings 15:14:40 INFO - Running setup.py install for blessings 15:14:40 INFO - Successfully installed blessings 15:14:40 INFO - Cleaning up... 15:14:40 INFO - Return code: 0 15:14:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:14:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:14:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:14:40 INFO - Reading from file tmpfile_stdout 15:14:40 INFO - Current package versions: 15:14:40 INFO - blessings == 1.6 15:14:40 INFO - blobuploader == 1.2.4 15:14:40 INFO - docopt == 0.6.1 15:14:40 INFO - functools32 == 3.2.3-2 15:14:40 INFO - jsonschema == 2.5.1 15:14:40 INFO - manifestparser == 1.1 15:14:40 INFO - mozInstall == 1.12 15:14:40 INFO - mozcrash == 0.17 15:14:40 INFO - mozdebug == 0.1 15:14:40 INFO - mozdevice == 0.48 15:14:40 INFO - mozfile == 1.2 15:14:40 INFO - mozhttpd == 0.7 15:14:40 INFO - mozinfo == 0.9 15:14:40 INFO - mozleak == 0.1 15:14:40 INFO - mozlog == 3.4 15:14:40 INFO - moznetwork == 0.27 15:14:40 INFO - mozprocess == 0.23 15:14:40 INFO - mozprofile == 0.28 15:14:40 INFO - mozrunner == 6.12 15:14:40 INFO - mozscreenshot == 0.1 15:14:40 INFO - mozsystemmonitor == 0.3 15:14:40 INFO - moztest == 0.7 15:14:40 INFO - mozversion == 1.4 15:14:40 INFO - psutil == 3.1.1 15:14:40 INFO - requests == 1.2.3 15:14:40 INFO - wsgiref == 0.1.2 15:14:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:14:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:40 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 0x102344490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a1258a8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102158d30>, '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 0x101fbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102150b20>, '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.QvGA9s14DV/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.tX5U1Ekpkh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 15:14:40 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 15:14:40 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 15:14:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QvGA9s14DV/Render', 15:14:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:40 INFO - 'HOME': '/Users/cltbld', 15:14:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:40 INFO - 'LOGNAME': 'cltbld', 15:14:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:40 INFO - 'MOZ_NO_REMOTE': '1', 15:14:40 INFO - 'NO_EM_RESTART': '1', 15:14:40 INFO - 'PAGER': '/bin/cat', 15:14:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:40 INFO - 'PWD': '/builds/slave/test', 15:14:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:40 INFO - 'SHELL': '/bin/bash', 15:14:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tX5U1Ekpkh/Listeners', 15:14:40 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 15:14:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:40 INFO - 'USER': 'cltbld', 15:14:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:40 INFO - 'XPC_FLAGS': '0x0', 15:14:40 INFO - 'XPC_SERVICE_NAME': '0', 15:14:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:41 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)) 15:14:41 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)) 15:14:41 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)) 15:14:41 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)) 15:14:41 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)) 15:14:41 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)) 15:14:41 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)) 15:14:41 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)) 15:14:41 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)) 15:14:41 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)) 15:14:41 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)) 15:14:41 INFO - Cleaning up... 15:14:41 INFO - Return code: 0 15:14:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:41 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 0x102344490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a1258a8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102158d30>, '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 0x101fbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102150b20>, '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.QvGA9s14DV/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.tX5U1Ekpkh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 15:14:41 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 15:14:41 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 15:14:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QvGA9s14DV/Render', 15:14:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:41 INFO - 'HOME': '/Users/cltbld', 15:14:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:41 INFO - 'LOGNAME': 'cltbld', 15:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:41 INFO - 'MOZ_NO_REMOTE': '1', 15:14:41 INFO - 'NO_EM_RESTART': '1', 15:14:41 INFO - 'PAGER': '/bin/cat', 15:14:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:41 INFO - 'PWD': '/builds/slave/test', 15:14:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:41 INFO - 'SHELL': '/bin/bash', 15:14:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tX5U1Ekpkh/Listeners', 15:14:41 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 15:14:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:41 INFO - 'USER': 'cltbld', 15:14:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:41 INFO - 'XPC_FLAGS': '0x0', 15:14:41 INFO - 'XPC_SERVICE_NAME': '0', 15:14:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:14:41 INFO - Cleaning up... 15:14:41 INFO - Return code: 0 15:14:41 INFO - Running post-action listener: _resource_record_post_action 15:14:41 INFO - Running post-action listener: _start_resource_monitoring 15:14:41 INFO - Starting resource monitoring. 15:14:41 INFO - [mozharness: 2017-01-04 23:14:41.550481Z] Finished create-virtualenv step (success) 15:14:41 INFO - [mozharness: 2017-01-04 23:14:41.551454Z] Running install step. 15:14:41 INFO - Running pre-action listener: _resource_record_pre_action 15:14:41 INFO - Running main action method: install 15:14:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:14:41 INFO - Reading from file tmpfile_stdout 15:14:41 INFO - Detecting whether we're running mozinstall >=1.0... 15:14:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:14:41 INFO - Reading from file tmpfile_stdout 15:14:41 INFO - Output received: 15:14:41 INFO - Usage: mozinstall [options] installer 15:14:41 INFO - Options: 15:14:41 INFO - -h, --help show this help message and exit 15:14:41 INFO - -d DEST, --destination=DEST 15:14:41 INFO - Directory to install application into. [default: 15:14:41 INFO - "/builds/slave/test"] 15:14:41 INFO - --app=APP Application being installed. [default: firefox] 15:14:41 INFO - mkdir: /builds/slave/test/build/application 15:14:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.mac.dmg --destination /builds/slave/test/build/application 15:15:03 INFO - Reading from file tmpfile_stdout 15:15:03 INFO - Output received: 15:15:03 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:15:03 INFO - Running post-action listener: _resource_record_post_action 15:15:03 INFO - [mozharness: 2017-01-04 23:15:03.887740Z] Finished install step (success) 15:15:03 INFO - [mozharness: 2017-01-04 23:15:03.887883Z] Running run-tests step. 15:15:03 INFO - Running pre-action listener: _resource_record_pre_action 15:15:03 INFO - Running main action method: run_tests 15:15:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:15:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:15:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:15:03 INFO - 2017-01-04 15:15:03.902 screenresolution[1897:17649] starting screenresolution argv=screenresolution get 15:15:03 INFO - 2017-01-04 15:15:03.904 screenresolution[1897:17649] Display 0: 1600x1200x32@60 15:15:03 INFO - 2017-01-04 15:15:03.910 screenresolution[1898:17651] starting screenresolution argv=screenresolution list 15:15:03 INFO - Available Modes on Display 0 15:15:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:15:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:15:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:15:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:15:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:15:04 INFO - Intel Iris: 15:15:04 INFO - Chipset Model: Intel Iris 15:15:04 INFO - Type: GPU 15:15:04 INFO - Bus: Built-In 15:15:04 INFO - VRAM (Dynamic, Max): 1536 MB 15:15:04 INFO - Vendor: Intel (0x8086) 15:15:04 INFO - Device ID: 0x0a2e 15:15:04 INFO - Revision ID: 0x0009 15:15:04 INFO - Displays: 15:15:04 INFO - Display: 15:15:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:15:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:15:04 INFO - Display Serial Number: 1600x1200 60 15:15:04 INFO - Main Display: Yes 15:15:04 INFO - Mirror: Off 15:15:04 INFO - Online: Yes 15:15:04 INFO - Rotation: Supported 15:15:04 INFO - Return code: 0 15:15:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:15:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:15:04 INFO - Python 2.7.3 15:15:04 INFO - Return code: 0 15:15:04 INFO - grabbing minidump binary from tooltool 15:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:04 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 0x102158d30>, '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 0x101fbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102150b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:15:04 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 15:15:04 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 15:15:04 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 15:15:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:15:04 INFO - Return code: 0 15:15:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:15:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:15:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:15:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:15:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:15:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0323', '--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 15:15:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0323 --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 15:15:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QvGA9s14DV/Render', 15:15:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:15:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:15:04 INFO - 'HOME': '/Users/cltbld', 15:15:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:15:04 INFO - 'LOGNAME': 'cltbld', 15:15:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:15:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:15:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:04 INFO - 'MOZ_NO_REMOTE': '1', 15:15:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:15:04 INFO - 'NO_EM_RESTART': '1', 15:15:04 INFO - 'PAGER': '/bin/cat', 15:15:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:15:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:04 INFO - 'PWD': '/builds/slave/test', 15:15:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:15:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:15:04 INFO - 'SHELL': '/bin/bash', 15:15:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tX5U1Ekpkh/Listeners', 15:15:04 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 15:15:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:15:04 INFO - 'USER': 'cltbld', 15:15:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:15:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:15:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:04 INFO - 'XPC_FLAGS': '0x0', 15:15:04 INFO - 'XPC_SERVICE_NAME': '0', 15:15:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:15:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0323', '--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 15:15:04 INFO - mozversion application_buildid: 20170104125439 15:15:04 INFO - mozversion application_changeset: 1672ba92485f35fad11519b18fb9f0136aa09c4d 15:15:04 INFO - mozversion application_display_name: Firefox 15:15:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:15:04 INFO - mozversion application_name: Firefox 15:15:04 INFO - mozversion application_remotingname: firefox 15:15:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:15:04 INFO - mozversion application_vendor: Mozilla 15:15:04 INFO - mozversion application_version: 51.0 15:15:04 INFO - mozversion platform_buildid: 20170104125439 15:15:04 INFO - mozversion platform_changeset: 1672ba92485f35fad11519b18fb9f0136aa09c4d 15:15:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:15:04 INFO - mozversion platform_version: 51.0 15:15:04 INFO - using testdate: 1483571704 15:15:04 INFO - actual date: 1483571704 15:15:04 INFO - starting webserver on 'localhost:52656' 15:15:04 INFO - SUITE-START | Running 2 tests 15:15:04 INFO - TEST-START | dromaeo_css 15:15:04 INFO - operating with platform_type : mac_ 15:15:04 INFO - Initialising browser for dromaeo_css test... 15:15:04 INFO - TEST-INFO | started process 1908 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmp2LV37P/profile http://localhost:52656/getInfo.html) 15:15:06 INFO - PROCESS | 1908 | __metrics Screen width/height:1600/1200 15:15:06 INFO - PROCESS | 1908 | colorDepth:24 15:15:06 INFO - PROCESS | 1908 | Browser inner width/height: 1024/689 15:15:06 INFO - PROCESS | 1908 | __metrics 15:15:07 INFO - PROCESS | 1908 | 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" 15:15:07 INFO - PROCESS | 1908 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:15:07 INFO - TEST-INFO | 1908: exit 0 15:15:07 INFO - Browser initialized. 15:15:07 INFO - Running cycle 1/1 for dromaeo_css test... 15:15:07 INFO - TEST-INFO | started process 1911 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmp2LV37P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:15:19 INFO - PROCESS | 1911 | RSS: Main: 168468480 15:15:19 INFO - PROCESS | 1911 | 15:16:10 INFO - PROCESS | 1911 | 1483571770942 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] 15:18:21 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:52656/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52656/tests/dromaeo/cssquery-ext.html) 15:18:22 INFO - PROCESS | 1911 | RSS: Main: 314363904 15:18:22 INFO - PROCESS | 1911 | 15:21:25 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:52656/tests/dromaeo/cssquery-ext.html (next: http://localhost:52656/tests/dromaeo/cssquery-jquery.html) 15:21:26 INFO - PROCESS | 1911 | RSS: Main: 300339200 15:21:26 INFO - PROCESS | 1911 | 15:24:28 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:52656/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52656/tests/dromaeo/cssquery-mootools.html) 15:24:29 INFO - PROCESS | 1911 | RSS: Main: 275914752 15:24:29 INFO - PROCESS | 1911 | 15:27:31 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:52656/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52656/tests/dromaeo/cssquery-prototype.html) 15:27:31 INFO - PROCESS | 1911 | RSS: Main: 268513280 15:27:31 INFO - PROCESS | 1911 | 15:30:35 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:52656/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52656/tests/dromaeo/cssquery-yui.html) 15:30:35 INFO - PROCESS | 1911 | RSS: Main: 262078464 15:30:35 INFO - PROCESS | 1911 | 15:33:38 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:52656/tests/dromaeo/cssquery-yui.html (next: http://localhost:52656/tests/dromaeo/cssquery-dojo.html) 15:33:38 INFO - PROCESS | 1911 | RSS: Main: 363511808 15:33:38 INFO - PROCESS | 1911 | 15:33:38 INFO - PROCESS | 1911 | __start_tp_report 15:33:38 INFO - PROCESS | 1911 | _x_x_mozilla_page_load 15:33:38 INFO - PROCESS | 1911 | _x_x_mozilla_page_load_details 15:33:38 INFO - PROCESS | 1911 | |i|pagename|runs| 15:33:38 INFO - PROCESS | 1911 | |0;dojo.html;3433.965660343396;3440.948385774213;3460.6885380315784;3460.948085778712;3526;5722.7424765885535;5750.1949727038145;5780.988327042772;5799.188113664085;5802.564807639428;5729.512991395726;5792.884142317151;5797.072468405056;5803.941960580393;5808.186853840466;5215.947840521603;5248.21276808478;5266.262723218752;5276.498732620395;5297.735113244342;5178.896422071556;5184.922226166611;5315.096433606277;5329.520343169114;5331.813386531472;5193.68837869729;5238.711870847102;5245.842624721265;5257.684538927658;5269.8946021079555;5284.524392804647;5285.947140528584;5340.519353258186;5340.946590534084;5368.946310536884;5247.149800709656;5253.553447956928;5286.497782710637;5291.1534154535475;5335.119705248668;5615.129654903497;5666.46168613981;5695.77216911323;5697.658140511582;5701.14482827575;6177.752889884399;6265.154204182422;6267.62394256346;6274.466670333027;6281.811545653638;5268;6347.523935704794;6347.52393570484;6389.329120442333;6391.712372943182;5710.057840456319;5781.566382521328;5783.913241301384;5844.211031510692;5876.4123587641325;6348.523860710465;6356.713947872357;6357.205349331333;6368.904466433008;6370.649614271259;5801.9129713054335;6221.626702397871;6242.625442473466;6252.09344645033;6258.217722784652;6154.19995400604;6196.597221180609;6238.6880655967925;6250.15622890913;6372.55392122547;5574.247476590608;5947.970260148758;6259.937400626027;6278.340774218733;6290.433860952537;6203.2556093268195;6243.094751261133;6290.433860952537;6295.779647712308;6326.177596912464;4896.094222568836;4948.703077815343;4955.083309587738;5004.024215277986;5036.697798132123;4484.439445069366;4514.052049069666;4527.592516673515;4532.4334458192725;4547.545245475426;5759.136129580596;5769.076947688433;5798.710064496759;5808.30300363959;5811.680357580289;5207.2449494823795;6314.684265786692;6343.080253363329;6343.207099112611;6368.808935731936;5566.749496272678;5637.915431268534;5674.687892165971;5740.655560666373;5761.740721667535;5386.895686384281;5418.241446197536;5419.403865574784;5435.076037073707;5464.781408743605;6298.779542715892;6314.179156709598;6329.208848893888;6338.017607270788;6346.587471814318;6231.719572619334;6351.491880649444;6354.93645063562;6363.14097596837;6376.713047912763;6291.96854015727;6312.43188113072;6316.778912738032;6340.270868850142;6346.90479642815;6267.529935254783;6299.811005669837;6309.779157729457;6315.084312774714;6324.304326523987;6247.937520624736;6357.777477788255;6378.011408231731;6378.6810659467765;6402.6158430494315;5134.845954621367;5825;6063.060225665029;6221.813345599639;6231.189945307081;6054.636721796706;6074.757009719562;6165.630062196283;6166.969165154145;6171.783987560414;5894.793682221101;5943.227380440515;6024.849378765391;6031.638101713911;6035.577509574287;6004.309504407049;6050.2437195350585;6139.201903752483;6178.62928224308;6207.193064901534;5934.287885453773;5960.04639257734;5976.820695379146;6054.878902422015;6068.423499767515 15:33:38 INFO - PROCESS | 1911 | |1;ext.html;476.35216106095714;496.30269471392705;539.8920215956807;541.9268398766168;546.9124940009597;31561.526577101362;32257.19357016073;32268.515972260437;32317.5152372714;33811.66188338116;34987;35540.82229588849;35566.64433355666;35617.2876542469;43397.34903976435;9692.176165025963;10016.699499015005;10027.799444011116;10029.949850250776;10041.397516149054;9804.509774511282;9901.900980990169;9903.702888913343;9915.157211637019;9919.702408927707;9254.86117708235;9468.384555003902;9682.46746428946;9725.708228753148;9732.610695572168;12000.339981301026;12288.078394120477;12297.93851030748;12470.127091103626;12482.937585312015;11999.760004799855;12077.698057548543;12082.15424920256;12101.152919295653;12106.455209515592;41204.587954120376;41574.58425415768;41588.79205603953;41644.791776040925;41754.582454175375;3382.5433566468705;3447.5345828313107;3493.6855682988653;3702.129999450127;3923.1761330120707;32277.677223227704;32390.352192956245;32593;32770.344593108006;32903.83548082244;2951.9557206641916;3285.342931413727;3314.767966242364;3414.436617958034;3424.2466657335344;520141;520255;520583.39708301594;521336;522158;35065.649343506746;60302.69848650816;60564.69717651384;60648.696756515936;60831;36180.27639447196;36711.63288367136;37364.879053628436;37380;37446.06384840401;15912.443064492687;16038.599035024217;16067.437639682556;16076.276567554489;16099.919500402422;13544.729105417837;13673.86326136726;13674.111182773087;13689.520866769615;13707.725845483035;8099.230573095547;8204.876926846102;8274.8345033099;8294.543800090933;8336;2885.639295088114;3208.53476245943;3229.047431007811;3229.515572664119;3309.8014119152926;998.8651532043082;1052.2423854824515;1112.630097891486;1130.6325444230658;1180.533689192764;24742.134025309027;30030.84984575107;30714.846425768174;31462.32570231556;32051.878184263438;1085.495244711213;1089.0688461365419;1097.5061222449929;1243;1272.662744372742;16411;16471.83528164703;16495.670086598202;16549.50351489457;16683.582910427336;715551;718578;727890;728691;731044;61491.692541537006;61901.976371535704;62180;62302.065469018;62334.0649890252;1008.8385858262643;1323.616151316127;1389.2289779172656;1415.6814716688662;1434.8206474190727;31630.683693162773;32794;32853.50719739254;32999.50500742539;33368.49947250842;49826.50173498219;50410;50476.49523504865;50700;50742.74628626833;589315;592244;592576;601876;691104;22441.775582244623;25208.739563022144;25727.871360643076;28823.42353152883;28858.42283154367;47642.285365718846;49267.76830579264;49546.50453495563;50495.74752126216;50587.2411913829;34413;39684.015799209345;40429;41088.97277568085;42217.57782422259;40596;40830.183396332504;41125.588744112174;42066.57933420627;42378.576214237466;38201.4269785959;38647.42028869513;39120.80439597784;39419.60580394274;39627.60372396239;34717.82641086778;35254.82372588121;35513;36544.45183322199;36593;26276.868615657564;26349.473010540067;26409;26687.599686005113;26738.331541710835 15:33:38 INFO - PROCESS | 1911 | |2;jquery.html;383.22779599107804;383.75439718580105;385.723280730197;394.1565050791306;415.2670536503073;14347.28263586819;20108.195672173097;21123.894380528116;21390.786092139075;21407.892960535195;16241.675166496692;18033.549161270974;20004.399868003984;20581.38255852327;21058.262960796375;5723.427657234285;6008.489278411318;6014.037753959367;6020.6688632125215;6025.397460253984;5833.766649334021;5898.380670029649;6077.604955677889;6168.506519478454;6215.440610345068;6101.0848372744;6108.81673549792;6111.358307377705;6111.724972376253;6122.326544080148;5710.600257981943;5798.188253644493;6035.185249991239;6045.12345709874;6076.878462430749;5248.685078895241;5263.578913686896;5266.605004624668;5271.182966640138;5271.920921186185;25007.874960625264;25454.727263636745;25615.615765763527;25683.486330273478;26248.86875565629;2287.2223444028946;2910.272431892027;3010.9247268818235;3101.705337992887;3137.654857965622;15587.688246235126;15686.294116764773;15716.842831571652;15853.9694919831;15922.283497242652;1846.5752876838403;1902.8382587479941;1931.2854243929696;1950.6488832010232;1970.3891793544046;119144;125181;126480;127244.36377818145;128147;7739.883901741478;9804.215662746963;9813.950930245446;10121.746956326151;10228.59085636581;12579.874201258053;12650.367481626066;12670.98632109429;12673.239605623692;12690.238585684703;7741.651625676858;8067.426851763849;8166.346692264605;8204.548749818698;8209.876851847228;8663.826723465496;8680.350733360641;8690.695825646199;8796.824063518694;8909.910900891038;4555.316702494652;4558.840440584564;4560.543945605479;4565.3836732040745;4645.651576131803;1725.7411388291605;1819.89990550521;1843.8340549350623;1915.6551820672134;1931.6329897319465;900.7342833864026;970.2286682087757;978.3493976505545;982.0719420148034;991.108002797488;17930.103494825205;20416.591668166555;21364.252251171136;21475.892620536797;21478.570428591654;731.4002517935241;825.2160447574828;862.7929296968682;947.9335049591818;975.9707208783748;9118.544072796334;10704.715434147796;11206.327620342805;11231.989120979151;11245.98786109254;626192;627736;633450;635220;637796;138741;140621.78134655976;140962;141894.87157692644;143687;997.2620261006948;1005.7586179316911;1010.242318261304;1011.3527342500805;1022.3712416863575;17550.648987020442;18175.909120454315;18853.340133095826;18927;18930.242790288234;57429;59360;60109;60376.49058773566;60454.09318860133;63555.6822215886;84268.15731842603;86581.56709216414;86647.56676216831;87495.68756468785;7876.684932602631;7959.124496305286;8196.508209507449;8328.042275138436;8332.375071869756;10876.673699789018;12717.618471445872;12851.742965140831;13093.869061309266;13252.668683282609;7979.720709775363;11200.607978720705;11252.943735281271;11470.311781293149;11601.941990289995;11032;11042.834357484804;11089.722756931142;11124.833127503256;11125.054370378284;11045.558177672803;11582.478788454371;11713;11791.29252244868;11821.408929553316;8089.433739638169;8151.225633564802;8151.918480815353;8252.958735206286;8274.503529788231;8272.420930535045;9943.558184063415;11127.777444451227;11317.830232546683;11548.94225528867 15:33:38 INFO - PROCESS | 1911 | |3;mootools.html;1510.2750679673757;1533.9539813805584;1739.4694618141461;1910.7516022917018;1910.9617807643847;8617.396782225247;9178.082191780826;9178.128077832613;9187.77030574236;9196;7983.361331093513;8260.628271727772;8276.91723082769;8649.481031138135;8882.64469421224;2379.702537182852;2410.1203060882826;2410.4094496848297;2410.7227668818045;2412.264259400882;2382.1781485387514;2391.760823917603;2417.782399584037;2418.322869596516;2423.2245681381964;2396.628522579003;2404.759524047599;2411.686480757508;2418.2503423938633;2420.6490058941445;2373.5490256851317;2387.880605969703;2393.0308225168747;2395.413123784684;2395.6286775549643;2370.3126093433057;2384.332386931662;2389.2235023617395;2389.4862604539935;2390.3307074019303;9006.279497640173;9075.54622268884;9104.453732776055;9130.17828395441;9137.314701397356;5424.213489044106;5601.551875849922;5606.579506537026;5608.383077861432;5625.071863142576;3522.3131489359585;3616.3309787689363;3636.7454278200535;3694.0949467380665;3695.8891233263043;1094.6715985204407;1096.5175322857997;1104.2987702808775;1114.414932160614;1124.2860783402516;11766.529338826436;11790.99776518997;11870.821937670942;11899.524019039229;11935;4183.226103170923;4512.300593408027;4617.607503362252;4623.537646235417;4758.95241047592;9354.625814967461;9382.014888436752;9382.5308734563;9389.906100938902;9400.24798016157;5455.727213639302;5456.727163641802;5460.399356070829;5462.754176062087;5468.671879687232;4608.147492713859;4769.523047695203;4965.106280869406;5077.009983053269;5129.179331306973;3874.6319099685484;3879.495665563515;3953.703472239536;3973;3986.7807270599815;1082.5669732107062;1089.6131873185063;1175.9353235572125;1190.2441949362044;1192.934388608635;824.6041899888087;877.4647465046316;904.7738065483629;949.4967667136428;954.7660823098386;5230.293910322058;5881.794137205177;6003.939960600425;6165.568410211242;6313.96843015782;450.84583466325654;451.2937253198734;461.76911544227886;463.2657238277368;466.48453458928077;3538.911527211789;3982.4623675803973;4047.696422768245;4056.229316429869;4174;10439.112675422673;10940.726481838017;10943.398113103696;10965.287256328473;10966.287191332538;10277.280590358752;10523.158147348193;10802.783944321069;10903.073238774792;11002.174836887265;8275.420720549624;8334.958130233721;8415.49507029568;8526.63573828197;8535.615897284637;5492.642978206404;5848.742520358143;6136.907946380718;6199.411055949678;6348.047792830927;8934.642614295355;9523.857142142735;10062.993700629879;10312.432816195182;10423.166146708094;10361.844572331573;10436.060754532129;10485;10533.262671612914;10622.73443163927;6373.74505019794;7531.548107113591;7596.202398748273;7610.315071643578;7668.041494813148;8450.830983380421;8520.3183745299;8523.275521580736;8533.018702849004;8555.957220213859;7645.655945482578;7694.769156925301;7701.268379503938;7917.44577879566;7927.762167134995;7532.209118042527;7637.427192960661;7653.119891212359;7667.386609071149;7668.80827979305;7124.465665075037;7252.601106939169;7256.129264488295;7262.491625586158;7277.781666550012;4346.956530434655;5027.396712394536;5119.948800512097;5223.764930578209;5385.38068122171;4483.484399293992;5166.612504062286;5177.171652535576;5194.4805519447755;5211.843644690665 15:33:38 INFO - PROCESS | 1911 | |4;prototype.html;305.52795930287704;308.2432627898508;314.9826759528225;320.0686003729149;320.6584986988858;5223.36220208794;5290;5350.7057111858885;5388.91916621251;5400.972995135019;5218.686878787275;5288.15389537674;5327.520523152915;5413.458654134594;5428.37573679027;3690.926181476369;3901.317269477844;4153.6677065834665;4173.373993900924;4173.4365860608805;3823.770573765583;4062.9796851015703;4150.066235097109;4151.8962025949295;4166.729162604436;3236.385086833494;3474.391981403257;3477.9130521737034;4046.433499310099;4083.7549747015128;3707;3940.940885886714;4065.2276067547073;4070.5522392536795;4085.44846445729;2949.587057811908;3533.2756784859043;3558.3061303045915;3563.1448452371505;3581.982090089533;4239.512456067561;4819.614430845559;5121.974390128063;5218.791248350061;5258.474152584749;4640.814367425299;4646.4424269087585;4667.599720083995;4669.579737823612;4681;5626.859328516779;5631.774729010835;5653.745581448845;5671.574631902624;5672.914906276409;3254.23525471514;3453.930921381558;3733.7199710021587;3854.8843534693724;3875.9612403876163;4830.468648448667;5842.503387212092;5909.9704501477645;5918.40815918409;5919.911201331984;3379.7803142795638;3832.865849695247;3925.666318362971;3927.155661532727;3941.921161576752;3734.7012239020814;3958.2281455115976;3986.62461450799;4019.879403617896;4041.3937909313836;3664.4686520454384;4038.5355684096116;4637.6840559000475;4641.797159959617;4670.953290467051;4731.4795372509;4786.755443584693;5195.6622819516615;5661.745221465044;5718.914216286759;1070.6252811516063;1122.7080958950569;1326.9715970123232;1332.8467226269104;1343.5566263133142;747.0102114698046;781.4217479065564;879.8328317619632;922.4557511068501;934.780326623243;4350.32569951658;5370.9731451342495;5375.919361209585;5392.272043274186;5412.944475827176;4785;5528.612997090165;5634;5796.362400135981;5853.502452291603;4927.334809800703;4954.702717836942;5287.920681189786;5289;5293.735313234323;2872.6983666715114;3003.0480187878056;3049.4968330225265;3053.9694603053686;3054.4196602645425;5424.698072462581;5759.366469688331;5763.567732420001;5983.790567330123;6008.579399442085;5749.626274292157;5868.799200299887;5912.970435147883;5917.733701983421;5921.644701317935;4933.679310844784;5600.551955843523;5927.881442371215;5945.970270148621;5959.225300710967;4809.470958194665;5023.598112151092;5345.41200467956;5758.2514273144225;5797.246357973437;4984.853483698697;5645;5688.687122208318;5732.885342293047;5733.283339582552;5493.560515158858;5644.774209031592;5658.801941932013;5942.168096466585;5964.701764911824;3382.644822293722;3464.636213197679;3881.281962836771;3910.7485604606254;3935.567087620302;4904.950950490592;5810.111713692982;5831.825045248649;5840.328362238397;5883.911741323969;5370.275012873369;5482.780688772564;5569.526590239875;5609.635373700697;5617.949448451368;5182.32629758129;5288.8148914789335;5327.334083239595;5575.9926426487245;5589.80435684749;4592.9540704593865;4625.502228796412;4708.952910470988;5064.987002599421;5301.522862942199;4594.517575654508;4664.79008444614;5202.621305354009;5664.716764161858;5710.2862142232225;4919.745464906569;5022.924656130234;5025.623078269217;5110.897782044264;5303.257543943928 15:33:38 INFO - PROCESS | 1911 | |5;yui.html;743.8586668532981;767.8306794107995;791.1100012485953;796.211750367136;843.0220943705303;15809.446669366574;16019.279132439038;16773.916130419348;16798.748018779726;16819.49541513755;14914;15290.92963492556;15312.387504499835;15315.617109572266;15315.693686126298;706.6784613001098;749.8312879602087;903.0427746588637;906.4923642760042;908.1281969309438;720.5352547606791;878.767126711422;888.9733308000771;899.1682693508511;903.9728808135734;950.6435086842434;953.2897990996718;954.6086104697075;955.7658373698454;959.1703176752129;944.6221511395497;946.1059298962483;948.6015873333216;949.544218774985;950.771814764455;710.6695386645183;710.9608971506565;711.2212127720138;711.8540699156661;712.152538479208;11618.59334923282;13873.58379248624;13941.302934853318;13987.65030874226;14034.789478157836;14855.777163342558;14902.627434314121;15074.547763567001;15107.39570417182;15122.243887805676;11868;12118.939405303005;12198.14612977092;12217.755644887053;12257.26456412618;3814.141818090935;3840.6543411093135;3841.5966323535904;4048.67610591152;4100.159467309194;121390.50724781773;125247;125435;126704.36647816794;128210.35894820467;11258.21192516516;11583.942080289544;11636.243644163102;11729.706757331134;11839.11206659504;23596.7640323598;23693;23823.404414889766;23881.52236955286;23895.880520597286;7229.819254518679;8117.350611951029;8279.592469280236;8436;8550.486970781772;4078.7552746835286;4481.2381895077915;4522.29904364824;4611.8847028823875;4815.6388270879825;3665.450182472597;3819.8854034379015;3820.9808950955066;3826.579076301605;3832.7508711933633;663.7975417497613;672.3175976383974;675.6047712088385;692.0864458914285;692.4218277738145;592.2596754057428;620.6307247187916;646.9708863101172;704.2183176673888;712.4122598855964;9175.632974681072;9996.600135994624;10017.248706347053;10038.698839034842;10223.335483193569;665.517499812632;682.8224661588023;704.6476761619191;720.3336913355125;725.1906493299252;3964.643182113566;4568.497465278817;4755.453122890912;4814.807407703653;4816.903661926812;105658;143357;144085.27957360147;149713;171541;138328;139654.3017284907;144646;145523;150475.24762376118;118107;120541;121763;123615.38192308981;125609.7439025598;8790.868136978079;9181.357304988587;9406.95296523513;9444.622215111318;9473.289503287144;61352.377147422376;62660.686696568046;66059;67386.66306668436;68954.65522672555;92941.53529232311;99565.50217248868;99993.50003250228;102677.459838101;105247.4737626307;11699.94142583397;11964.341961192218;12238.510459581517;12274.938625306817;12814.55149069778;13228.272445015231;13672.384742686405;13854.584362469142;13957.720845583235;14313.141211527342;12369.814452783035;12432.502699892264;12707.745845083231;12733.93633031866;13298.135621184592;12535.185212961129;12777.36113194318;12783.233006019296;13040.478380865039;13300.667483312995;12207.633770986884;12237.75524489523;12359;12518.937405312916;12555.986563224578;9803;10243.59025638996;10489.423081730272;11177.82032503654;11221.775564488828;8685.478871267744;10816.296940698829;10912.945435272773;11094.611688590861;11098.722531936766 15:33:38 INFO - PROCESS | 1911 | __end_tp_report 15:33:38 INFO - PROCESS | 1911 | __start_cc_report 15:33:38 INFO - PROCESS | 1911 | _x_x_mozilla_cycle_collect,1274 15:33:38 INFO - PROCESS | 1911 | __end_cc_report 15:33:38 INFO - PROCESS | 1911 | __startTimestamp1483572818612__endTimestamp 15:33:38 INFO - PROCESS | 1911 | 15:33:38 INFO - PROCESS | 1911 | ------- Summary: start ------- 15:33:38 INFO - PROCESS | 1911 | Number of tests: 6 15:33:38 INFO - PROCESS | 1911 | 15:33:38 INFO - PROCESS | 1911 | [#0] dojo.html Cycles:165 Average:5776.75 Median:5934.29 stddev:631.72 (10.6%) stddev-sans-first:606.34 15:33:38 INFO - PROCESS | 1911 | Values: 3434.0 3440.9 3460.7 3460.9 3526.0 5722.7 5750.2 5781.0 5799.2 5802.6 5729.5 5792.9 5797.1 5803.9 5808.2 5215.9 5248.2 5266.3 5276.5 5297.7 5178.9 5184.9 5315.1 5329.5 5331.8 5193.7 5238.7 5245.8 5257.7 5269.9 5284.5 5285.9 5340.5 5340.9 5368.9 5247.1 5253.6 5286.5 5291.2 5335.1 5615.1 5666.5 5695.8 5697.7 5701.1 6177.8 6265.2 6267.6 6274.5 6281.8 5268.0 6347.5 6347.5 6389.3 6391.7 5710.1 5781.6 5783.9 5844.2 5876.4 6348.5 6356.7 6357.2 6368.9 6370.6 5801.9 6221.6 6242.6 6252.1 6258.2 6154.2 6196.6 6238.7 6250.2 6372.6 5574.2 5948.0 6259.9 6278.3 6290.4 6203.3 6243.1 6290.4 6295.8 6326.2 4896.1 4948.7 4955.1 5004.0 5036.7 4484.4 4514.1 4527.6 4532.4 4547.5 5759.1 5769.1 5798.7 5808.3 5811.7 5207.2 6314.7 6343.1 6343.2 6368.8 5566.7 5637.9 5674.7 5740.7 5761.7 5386.9 5418.2 5419.4 5435.1 5464.8 6298.8 6314.2 6329.2 6338.0 6346.6 6231.7 6351.5 6354.9 6363.1 6376.7 6292.0 6312.4 6316.8 6340.3 6346.9 6267.5 6299.8 6309.8 6315.1 6324.3 6247.9 6357.8 6378.0 6378.7 6402.6 5134.8 5825.0 6063.1 6221.8 6231.2 6054.6 6074.8 6165.6 6167.0 6171.8 5894.8 5943.2 6024.8 6031.6 6035.6 6004.3 6050.2 6139.2 6178.6 6207.2 5934.3 5960.0 5976.8 6054.9 6068.4 15:33:38 INFO - PROCESS | 1911 | 15:33:38 INFO - PROCESS | 1911 | [#1] ext.html Cycles:180 Average:73613.21 Median:35383.91 stddev:167820.68 (474.3%) stddev-sans-first:168201.62 15:33:38 INFO - PROCESS | 1911 | Values: 476.4 496.3 539.9 541.9 546.9 31561.5 32257.2 32268.5 32317.5 33811.7 34987.0 35540.8 35566.6 35617.3 43397.3 9692.2 10016.7 10027.8 10029.9 10041.4 9804.5 9901.9 9903.7 9915.2 9919.7 9254.9 9468.4 9682.5 9725.7 9732.6 12000.3 12288.1 12297.9 12470.1 12482.9 11999.8 12077.7 12082.2 12101.2 12106.5 41204.6 41574.6 41588.8 41644.8 41754.6 3382.5 3447.5 3493.7 3702.1 3923.2 32277.7 32390.4 32593.0 32770.3 32903.8 2952.0 3285.3 3314.8 3414.4 3424.2 520141.0 520255.0 520583.4 521336.0 522158.0 35065.6 60302.7 60564.7 60648.7 60831.0 36180.3 36711.6 37364.9 37380.0 37446.1 15912.4 16038.6 16067.4 16076.3 16099.9 13544.7 13673.9 13674.1 13689.5 13707.7 8099.2 8204.9 8274.8 8294.5 8336.0 2885.6 3208.5 3229.0 3229.5 3309.8 998.9 1052.2 1112.6 1130.6 1180.5 24742.1 30030.8 30714.8 31462.3 32051.9 1085.5 1089.1 1097.5 1243.0 1272.7 16411.0 16471.8 16495.7 16549.5 16683.6 715551.0 718578.0 727890.0 728691.0 731044.0 61491.7 61902.0 62180.0 62302.1 62334.1 1008.8 1323.6 1389.2 1415.7 1434.8 31630.7 32794.0 32853.5 32999.5 33368.5 49826.5 50410.0 50476.5 50700.0 50742.7 589315.0 592244.0 592576.0 601876.0 691104.0 22441.8 25208.7 25727.9 28823.4 28858.4 47642.3 49267.8 49546.5 50495.7 50587.2 34413.0 39684.0 40429.0 41089.0 42217.6 40596.0 40830.2 41125.6 42066.6 42378.6 38201.4 38647.4 39120.8 39419.6 39627.6 34717.8 35254.8 35513.0 36544.5 36593.0 26276.9 26349.5 26409.0 26687.6 26738.3 15:33:38 INFO - PROCESS | 1911 | 15:33:38 INFO - PROCESS | 1911 | [#2] jquery.html Cycles:180 Average:36810.72 Median:3119.68 stddev:105786.58 (3390.9%) stddev-sans-first:106047.97 15:33:38 INFO - PROCESS | 1911 | Values: 383.2 383.8 385.7 394.2 415.3 14347.3 20108.2 21123.9 21390.8 21407.9 16241.7 18033.5 20004.4 20581.4 21058.3 5723.4 6008.5 6014.0 6020.7 6025.4 5833.8 5898.4 6077.6 6168.5 6215.4 6101.1 6108.8 6111.4 6111.7 6122.3 5710.6 5798.2 6035.2 6045.1 6076.9 5248.7 5263.6 5266.6 5271.2 5271.9 25007.9 25454.7 25615.6 25683.5 26248.9 2287.2 2910.3 3010.9 3101.7 3137.7 15587.7 15686.3 15716.8 15854.0 15922.3 1846.6 1902.8 1931.3 1950.6 1970.4 119144.0 125181.0 126480.0 127244.4 128147.0 7739.9 9804.2 9814.0 10121.7 10228.6 12579.9 12650.4 12671.0 12673.2 12690.2 7741.7 8067.4 8166.3 8204.5 8209.9 8663.8 8680.4 8690.7 8796.8 8909.9 4555.3 4558.8 4560.5 4565.4 4645.7 1725.7 1819.9 1843.8 1915.7 1931.6 900.7 970.2 978.3 982.1 991.1 17930.1 20416.6 21364.3 21475.9 21478.6 731.4 825.2 862.8 947.9 976.0 9118.5 10704.7 11206.3 11232.0 11246.0 626192.0 627736.0 633450.0 635220.0 637796.0 138741.0 140621.8 140962.0 141894.9 143687.0 997.3 1005.8 1010.2 1011.4 1022.4 17550.6 18175.9 18853.3 18927.0 18930.2 57429.0 59360.0 60109.0 60376.5 60454.1 63555.7 84268.2 86581.6 86647.6 87495.7 7876.7 7959.1 8196.5 8328.0 8332.4 10876.7 12717.6 12851.7 13093.9 13252.7 7979.7 11200.6 11252.9 11470.3 11601.9 11032.0 11042.8 11089.7 11124.8 11125.1 11045.6 11582.5 11713.0 11791.3 11821.4 8089.4 8151.2 8151.9 8253.0 8274.5 8272.4 9943.6 11127.8 11317.8 11548.9 15:33:38 INFO - PROCESS | 1911 | 15:33:38 INFO - PROCESS | 1911 | [#3] mootools.html Cycles:180 Average:5776.81 Median:4764.24 stddev:3254.49 (68.3%) stddev-sans-first:3247.82 15:33:38 INFO - PROCESS | 1911 | Values: 1510.3 1534.0 1739.5 1910.8 1911.0 8617.4 9178.1 9178.1 9187.8 9196.0 7983.4 8260.6 8276.9 8649.5 8882.6 2379.7 2410.1 2410.4 2410.7 2412.3 2382.2 2391.8 2417.8 2418.3 2423.2 2396.6 2404.8 2411.7 2418.3 2420.6 2373.5 2387.9 2393.0 2395.4 2395.6 2370.3 2384.3 2389.2 2389.5 2390.3 9006.3 9075.5 9104.5 9130.2 9137.3 5424.2 5601.6 5606.6 5608.4 5625.1 3522.3 3616.3 3636.7 3694.1 3695.9 1094.7 1096.5 1104.3 1114.4 1124.3 11766.5 11791.0 11870.8 11899.5 11935.0 4183.2 4512.3 4617.6 4623.5 4759.0 9354.6 9382.0 9382.5 9389.9 9400.2 5455.7 5456.7 5460.4 5462.8 5468.7 4608.1 4769.5 4965.1 5077.0 5129.2 3874.6 3879.5 3953.7 3973.0 3986.8 1082.6 1089.6 1175.9 1190.2 1192.9 824.6 877.5 904.8 949.5 954.8 5230.3 5881.8 6003.9 6165.6 6314.0 450.8 451.3 461.8 463.3 466.5 3538.9 3982.5 4047.7 4056.2 4174.0 10439.1 10940.7 10943.4 10965.3 10966.3 10277.3 10523.2 10802.8 10903.1 11002.2 8275.4 8335.0 8415.5 8526.6 8535.6 5492.6 5848.7 6136.9 6199.4 6348.0 8934.6 9523.9 10063.0 10312.4 10423.2 10361.8 10436.1 10485.0 10533.3 10622.7 6373.7 7531.5 7596.2 7610.3 7668.0 8450.8 8520.3 8523.3 8533.0 8556.0 7645.7 7694.8 7701.3 7917.4 7927.8 7532.2 7637.4 7653.1 7667.4 7668.8 7124.5 7252.6 7256.1 7262.5 7277.8 4347.0 5027.4 5119.9 5223.8 5385.4 4483.5 5166.6 5177.2 5194.5 5211.8 15:33:38 INFO - PROCESS | 1911 | 15:33:38 INFO - PROCESS | 1911 | [#4] prototype.html Cycles:180 Average:4509.20 Median:5024.61 stddev:1398.32 (27.8%) stddev-sans-first:1366.18 15:33:38 INFO - PROCESS | 1911 | Values: 305.5 308.2 315.0 320.1 320.7 5223.4 5290.0 5350.7 5388.9 5401.0 5218.7 5288.2 5327.5 5413.5 5428.4 3690.9 3901.3 4153.7 4173.4 4173.4 3823.8 4063.0 4150.1 4151.9 4166.7 3236.4 3474.4 3477.9 4046.4 4083.8 3707.0 3940.9 4065.2 4070.6 4085.4 2949.6 3533.3 3558.3 3563.1 3582.0 4239.5 4819.6 5122.0 5218.8 5258.5 4640.8 4646.4 4667.6 4669.6 4681.0 5626.9 5631.8 5653.7 5671.6 5672.9 3254.2 3453.9 3733.7 3854.9 3876.0 4830.5 5842.5 5910.0 5918.4 5919.9 3379.8 3832.9 3925.7 3927.2 3941.9 3734.7 3958.2 3986.6 4019.9 4041.4 3664.5 4038.5 4637.7 4641.8 4671.0 4731.5 4786.8 5195.7 5661.7 5718.9 1070.6 1122.7 1327.0 1332.8 1343.6 747.0 781.4 879.8 922.5 934.8 4350.3 5371.0 5375.9 5392.3 5412.9 4785.0 5528.6 5634.0 5796.4 5853.5 4927.3 4954.7 5287.9 5289.0 5293.7 2872.7 3003.0 3049.5 3054.0 3054.4 5424.7 5759.4 5763.6 5983.8 6008.6 5749.6 5868.8 5913.0 5917.7 5921.6 4933.7 5600.6 5927.9 5946.0 5959.2 4809.5 5023.6 5345.4 5758.3 5797.2 4984.9 5645.0 5688.7 5732.9 5733.3 5493.6 5644.8 5658.8 5942.2 5964.7 3382.6 3464.6 3881.3 3910.7 3935.6 4905.0 5810.1 5831.8 5840.3 5883.9 5370.3 5482.8 5569.5 5609.6 5617.9 5182.3 5288.8 5327.3 5576.0 5589.8 4593.0 4625.5 4709.0 5065.0 5301.5 4594.5 4664.8 5202.6 5664.7 5710.3 4919.7 5022.9 5025.6 5110.9 5303.3 15:33:38 INFO - PROCESS | 1911 | 15:33:38 INFO - PROCESS | 1911 | [#5] yui.html Cycles:180 Average:26202.23 Median:23852.46 stddev:42564.25 (178.4%) stddev-sans-first:42640.74 15:33:38 INFO - PROCESS | 1911 | Values: 743.9 767.8 791.1 796.2 843.0 15809.4 16019.3 16773.9 16798.7 16819.5 14914.0 15290.9 15312.4 15315.6 15315.7 706.7 749.8 903.0 906.5 908.1 720.5 878.8 889.0 899.2 904.0 950.6 953.3 954.6 955.8 959.2 944.6 946.1 948.6 949.5 950.8 710.7 711.0 711.2 711.9 712.2 11618.6 13873.6 13941.3 13987.7 14034.8 14855.8 14902.6 15074.5 15107.4 15122.2 11868.0 12118.9 12198.1 12217.8 12257.3 3814.1 3840.7 3841.6 4048.7 4100.2 121390.5 125247.0 125435.0 126704.4 128210.4 11258.2 11583.9 11636.2 11729.7 11839.1 23596.8 23693.0 23823.4 23881.5 23895.9 7229.8 8117.4 8279.6 8436.0 8550.5 4078.8 4481.2 4522.3 4611.9 4815.6 3665.5 3819.9 3821.0 3826.6 3832.8 663.8 672.3 675.6 692.1 692.4 592.3 620.6 647.0 704.2 712.4 9175.6 9996.6 10017.2 10038.7 10223.3 665.5 682.8 704.6 720.3 725.2 3964.6 4568.5 4755.5 4814.8 4816.9 105658.0 143357.0 144085.3 149713.0 171541.0 138328.0 139654.3 144646.0 145523.0 150475.2 118107.0 120541.0 121763.0 123615.4 125609.7 8790.9 9181.4 9407.0 9444.6 9473.3 61352.4 62660.7 66059.0 67386.7 68954.7 92941.5 99565.5 99993.5 102677.5 105247.5 11699.9 11964.3 12238.5 12274.9 12814.6 13228.3 13672.4 13854.6 13957.7 14313.1 12369.8 12432.5 12707.7 12733.9 13298.1 12535.2 12777.4 12783.2 13040.5 13300.7 12207.6 12237.8 12359.0 12518.9 12556.0 9803.0 10243.6 10489.4 11177.8 11221.8 8685.5 10816.3 10912.9 11094.6 11098.7 15:33:38 INFO - PROCESS | 1911 | -------- Summary: end -------- 15:33:38 INFO - PROCESS | 1911 | 15:33:39 INFO - TEST-INFO | 1911: exit 0 15:33:39 INFO - TEST-OK | dromaeo_css | took 1114721ms 15:33:39 INFO - TEST-START | kraken 15:33:39 INFO - operating with platform_type : mac_ 15:33:39 INFO - Initialising browser for kraken test... 15:33:39 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmpSovOGF/profile http://localhost:52656/getInfo.html) 15:33:41 INFO - PROCESS | 1916 | __metrics Screen width/height:1600/1200 15:33:41 INFO - PROCESS | 1916 | colorDepth:24 15:33:41 INFO - PROCESS | 1916 | Browser inner width/height: 1024/689 15:33:41 INFO - PROCESS | 1916 | __metrics 15:33:41 INFO - PROCESS | 1916 | 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" 15:33:41 INFO - PROCESS | 1916 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:33:42 INFO - TEST-INFO | 1916: exit 0 15:33:42 INFO - Browser initialized. 15:33:42 INFO - Running cycle 1/1 for kraken test... 15:33:42 INFO - TEST-INFO | started process 1918 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmpSovOGF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:33:54 INFO - PROCESS | 1918 | RSS: Main: 168927232 15:33:54 INFO - PROCESS | 1918 | 15:33:56 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52656/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52656/tests/kraken/driver.html?testName=audio-beat-detection) 15:33:57 INFO - PROCESS | 1918 | RSS: Main: 218972160 15:33:57 INFO - PROCESS | 1918 | 15:34:00 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52656/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52656/tests/kraken/driver.html?testName=audio-dft) 15:34:00 INFO - PROCESS | 1918 | RSS: Main: 232951808 15:34:00 INFO - PROCESS | 1918 | 15:34:03 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52656/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52656/tests/kraken/driver.html?testName=audio-fft) 15:34:04 INFO - PROCESS | 1918 | RSS: Main: 329031680 15:34:04 INFO - PROCESS | 1918 | 15:34:06 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52656/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52656/tests/kraken/driver.html?testName=audio-oscillator) 15:34:06 INFO - PROCESS | 1918 | RSS: Main: 229634048 15:34:06 INFO - PROCESS | 1918 | 15:34:08 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52656/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52656/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:34:09 INFO - PROCESS | 1918 | RSS: Main: 229982208 15:34:09 INFO - PROCESS | 1918 | 15:34:12 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52656/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52656/tests/kraken/driver.html?testName=imaging-darkroom) 15:34:12 INFO - PROCESS | 1918 | RSS: Main: 266919936 15:34:12 INFO - PROCESS | 1918 | 15:34:15 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52656/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52656/tests/kraken/driver.html?testName=imaging-desaturate) 15:34:16 INFO - PROCESS | 1918 | RSS: Main: 268476416 15:34:16 INFO - PROCESS | 1918 | 15:34:19 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52656/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52656/tests/kraken/driver.html?testName=json-parse-financial) 15:34:20 INFO - PROCESS | 1918 | RSS: Main: 266608640 15:34:20 INFO - PROCESS | 1918 | 15:34:21 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52656/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52656/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:34:22 INFO - PROCESS | 1918 | RSS: Main: 244502528 15:34:22 INFO - PROCESS | 1918 | 15:34:24 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52656/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52656/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:34:24 INFO - PROCESS | 1918 | RSS: Main: 246681600 15:34:24 INFO - PROCESS | 1918 | 15:34:26 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52656/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52656/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:34:27 INFO - PROCESS | 1918 | RSS: Main: 253149184 15:34:27 INFO - PROCESS | 1918 | 15:34:30 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52656/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52656/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:34:31 INFO - PROCESS | 1918 | RSS: Main: 259383296 15:34:31 INFO - PROCESS | 1918 | 15:34:33 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52656/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52656/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:34:34 INFO - PROCESS | 1918 | RSS: Main: 259682304 15:34:34 INFO - PROCESS | 1918 | 15:34:36 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52656/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52656/tests/kraken/driver.html?testName=ai-astar) 15:34:36 INFO - PROCESS | 1918 | RSS: Main: 303603712 15:34:36 INFO - PROCESS | 1918 | 15:34:36 INFO - PROCESS | 1918 | __start_tp_report 15:34:36 INFO - PROCESS | 1918 | _x_x_mozilla_page_load 15:34:36 INFO - PROCESS | 1918 | _x_x_mozilla_page_load_details 15:34:36 INFO - PROCESS | 1918 | |i|pagename|runs| 15:34:36 INFO - PROCESS | 1918 | |0;ai-astar;115;111;110;111;110;111;111;110;111;111 15:34:36 INFO - PROCESS | 1918 | |1;audio-beat-detection;165;136;109;141;109;105;107;111;135;109 15:34:36 INFO - PROCESS | 1918 | |2;audio-dft;183;182;179;183;181;181;179;180;183;181 15:34:36 INFO - PROCESS | 1918 | |3;audio-fft;81;58;60;88;61;58;58;59;82;56 15:34:36 INFO - PROCESS | 1918 | |4;audio-oscillator;89;68;92;69;69;68;91;64;68;87 15:34:36 INFO - PROCESS | 1918 | |5;imaging-gaussian-blur;94;129;116;86;90;85;89;85;89;85 15:34:36 INFO - PROCESS | 1918 | |6;imaging-darkroom;136;150;95;119;148;104;94;95;94;95 15:34:36 INFO - PROCESS | 1918 | |7;imaging-desaturate;84;85;84;104;132;117;83;91;84;85 15:34:36 INFO - PROCESS | 1918 | |8;json-parse-financial;53;52;54;53;56;56;57;55;56;58 15:34:36 INFO - PROCESS | 1918 | |9;json-stringify-tinderbox;64;70;69;76;72;67;72;69;69;70 15:34:36 INFO - PROCESS | 1918 | |10;stanford-crypto-aes;84;81;85;85;84;82;86;88;99;90 15:34:36 INFO - PROCESS | 1918 | |11;stanford-crypto-ccm;149;144;139;142;138;137;145;148;151;134 15:34:36 INFO - PROCESS | 1918 | |12;stanford-crypto-pbkdf2;161;161;159;160;160;161;161;159;160;159 15:34:36 INFO - PROCESS | 1918 | |13;stanford-crypto-sha256-iterative;68;66;66;65;63;65;66;65;64;64 15:34:36 INFO - PROCESS | 1918 | __end_tp_report 15:34:36 INFO - PROCESS | 1918 | __start_cc_report 15:34:36 INFO - PROCESS | 1918 | _x_x_mozilla_cycle_collect,1863 15:34:36 INFO - PROCESS | 1918 | __end_cc_report 15:34:36 INFO - PROCESS | 1918 | __startTimestamp1483572876299__endTimestamp 15:34:36 INFO - PROCESS | 1918 | 15:34:36 INFO - PROCESS | 1918 | ------- Summary: start ------- 15:34:36 INFO - PROCESS | 1918 | Number of tests: 14 15:34:36 INFO - PROCESS | 1918 | 15:34:36 INFO - PROCESS | 1918 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.00 stddev:1.45 (1.3%) stddev-sans-first:0.50 15:34:36 INFO - PROCESS | 1918 | Values: 115.0 111.0 110.0 111.0 110.0 111.0 111.0 110.0 111.0 111.0 15:34:36 INFO - PROCESS | 1918 | 15:34:36 INFO - PROCESS | 1918 | [#1] audio-beat-detection Cycles:10 Average:122.70 Median:123.00 stddev:20.31 (16.5%) stddev-sans-first:14.68 15:34:36 INFO - PROCESS | 1918 | Values: 165.0 136.0 109.0 141.0 109.0 105.0 107.0 111.0 135.0 109.0 15:34:36 INFO - PROCESS | 1918 | 15:34:36 INFO - PROCESS | 1918 | [#2] audio-dft Cycles:10 Average:181.20 Median:181.50 stddev:1.55 (0.9%) stddev-sans-first:1.50 15:34:36 INFO - PROCESS | 1918 | Values: 183.0 182.0 179.0 183.0 181.0 181.0 179.0 180.0 183.0 181.0 15:34:36 INFO - PROCESS | 1918 | 15:34:36 INFO - PROCESS | 1918 | [#3] audio-fft Cycles:10 Average:66.10 Median:60.50 stddev:12.32 (20.4%) stddev-sans-first:11.83 15:34:36 INFO - PROCESS | 1918 | Values: 81.0 58.0 60.0 88.0 61.0 58.0 58.0 59.0 82.0 56.0 15:34:36 INFO - PROCESS | 1918 | 15:34:36 INFO - PROCESS | 1918 | [#4] audio-oscillator Cycles:10 Average:76.50 Median:78.00 stddev:11.56 (14.8%) stddev-sans-first:11.34 15:34:36 INFO - PROCESS | 1918 | Values: 89.0 68.0 92.0 69.0 69.0 68.0 91.0 64.0 68.0 87.0 15:34:36 INFO - PROCESS | 1918 | 15:34:36 INFO - PROCESS | 1918 | [#5] imaging-gaussian-blur Cycles:10 Average:94.80 Median:87.50 stddev:15.19 (17.4%) stddev-sans-first:16.10 15:34:36 INFO - PROCESS | 1918 | Values: 94.0 129.0 116.0 86.0 90.0 85.0 89.0 85.0 89.0 85.0 15:34:36 INFO - PROCESS | 1918 | 15:34:36 INFO - PROCESS | 1918 | [#6] imaging-darkroom Cycles:10 Average:113.00 Median:94.00 stddev:23.41 (24.9%) stddev-sans-first:23.31 15:34:36 INFO - PROCESS | 1918 | Values: 136.0 150.0 95.0 119.0 148.0 104.0 94.0 95.0 94.0 95.0 15:34:36 INFO - PROCESS | 1918 | 15:34:36 INFO - PROCESS | 1918 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:84.00 stddev:17.18 (20.5%) stddev-sans-first:17.77 15:34:36 INFO - PROCESS | 1918 | Values: 84.0 85.0 84.0 104.0 132.0 117.0 83.0 91.0 84.0 85.0 15:34:36 INFO - PROCESS | 1918 | 15:34:36 INFO - PROCESS | 1918 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:56.00 stddev:1.94 (3.5%) stddev-sans-first:1.92 15:34:36 INFO - PROCESS | 1918 | Values: 53.0 52.0 54.0 53.0 56.0 56.0 57.0 55.0 56.0 58.0 15:34:36 INFO - PROCESS | 1918 | 15:34:36 INFO - PROCESS | 1918 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:3.19 (4.6%) stddev-sans-first:2.60 15:34:36 INFO - PROCESS | 1918 | Values: 64.0 70.0 69.0 76.0 72.0 67.0 72.0 69.0 69.0 70.0 15:34:36 INFO - PROCESS | 1918 | 15:34:36 INFO - PROCESS | 1918 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:85.50 stddev:5.15 (6.0%) stddev-sans-first:5.39 15:34:36 INFO - PROCESS | 1918 | Values: 84.0 81.0 85.0 85.0 84.0 82.0 86.0 88.0 99.0 90.0 15:34:36 INFO - PROCESS | 1918 | 15:34:36 INFO - PROCESS | 1918 | [#11] stanford-crypto-ccm Cycles:10 Average:142.70 Median:144.50 stddev:5.66 (3.9%) stddev-sans-first:5.52 15:34:36 INFO - PROCESS | 1918 | Values: 149.0 144.0 139.0 142.0 138.0 137.0 145.0 148.0 151.0 134.0 15:34:36 INFO - PROCESS | 1918 | 15:34:36 INFO - PROCESS | 1918 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.10 Median:160.50 stddev:0.88 (0.5%) stddev-sans-first:0.87 15:34:36 INFO - PROCESS | 1918 | Values: 161.0 161.0 159.0 160.0 160.0 161.0 161.0 159.0 160.0 159.0 15:34:36 INFO - PROCESS | 1918 | 15:34:36 INFO - PROCESS | 1918 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.50 stddev:1.40 (2.1%) stddev-sans-first:1.05 15:34:36 INFO - PROCESS | 1918 | Values: 68.0 66.0 66.0 65.0 63.0 65.0 66.0 65.0 64.0 64.0 15:34:36 INFO - PROCESS | 1918 | -------- Summary: end -------- 15:34:36 INFO - PROCESS | 1918 | 15:34:37 INFO - TEST-INFO | 1918: exit 0 15:34:37 INFO - TEST-OK | kraken | took 58049ms 15:34:37 INFO - SUITE-END | took 1172s 15:34:37 INFO - Completed test suite (00:19:32) 15:34:37 INFO - javascript benchmark 15:34:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3433.965660343396, 3440.948385774213, 3460.6885380315784, 3460.948085778712, 3526.0, 5722.7424765885535, 5750.1949727038145, 5780.988327042772, 5799.188113664085, 5802.564807639428, 5729.512991395726, 5792.884142317151, 5797.072468405056, 5803.941960580393, 5808.186853840466, 5215.947840521603, 5248.21276808478, 5266.262723218752, 5276.498732620395, 5297.735113244342, 5178.896422071556, 5184.922226166611, 5315.096433606277, 5329.520343169114, 5331.813386531472, 5193.68837869729, 5238.711870847102, 5245.842624721265, 5257.684538927658, 5269.8946021079555, 5284.524392804647, 5285.947140528584, 5340.519353258186, 5340.946590534084, 5368.946310536884, 5247.149800709656, 5253.553447956928, 5286.497782710637, 5291.1534154535475, 5335.119705248668, 5615.129654903497, 5666.46168613981, 5695.77216911323, 5697.658140511582, 5701.14482827575, 6177.752889884399, 6265.154204182422, 6267.62394256346, 6274.466670333027, 6281.811545653638, 5268.0, 6347.523935704794, 6347.52393570484, 6389.329120442333, 6391.712372943182, 5710.057840456319, 5781.566382521328, 5783.913241301384, 5844.211031510692, 5876.4123587641325, 6348.523860710465, 6356.713947872357, 6357.205349331333, 6368.904466433008, 6370.649614271259, 5801.9129713054335, 6221.626702397871, 6242.625442473466, 6252.09344645033, 6258.217722784652, 6154.19995400604, 6196.597221180609, 6238.6880655967925, 6250.15622890913, 6372.55392122547, 5574.247476590608, 5947.970260148758, 6259.937400626027, 6278.340774218733, 6290.433860952537, 6203.2556093268195, 6243.094751261133, 6290.433860952537, 6295.779647712308, 6326.177596912464, 4896.094222568836, 4948.703077815343, 4955.083309587738, 5004.024215277986, 5036.697798132123, 4484.439445069366, 4514.052049069666, 4527.592516673515, 4532.4334458192725, 4547.545245475426, 5759.136129580596, 5769.076947688433, 5798.710064496759, 5808.30300363959, 5811.680357580289, 5207.2449494823795, 6314.684265786692, 6343.080253363329, 6343.207099112611, 6368.808935731936, 5566.749496272678, 5637.915431268534, 5674.687892165971, 5740.655560666373, 5761.740721667535, 5386.895686384281, 5418.241446197536, 5419.403865574784, 5435.076037073707, 5464.781408743605, 6298.779542715892, 6314.179156709598, 6329.208848893888, 6338.017607270788, 6346.587471814318, 6231.719572619334, 6351.491880649444, 6354.93645063562, 6363.14097596837, 6376.713047912763, 6291.96854015727, 6312.43188113072, 6316.778912738032, 6340.270868850142, 6346.90479642815, 6267.529935254783, 6299.811005669837, 6309.779157729457, 6315.084312774714, 6324.304326523987, 6247.937520624736, 6357.777477788255, 6378.011408231731, 6378.6810659467765, 6402.6158430494315, 5134.845954621367, 5825.0, 6063.060225665029, 6221.813345599639, 6231.189945307081, 6054.636721796706, 6074.757009719562, 6165.630062196283, 6166.969165154145, 6171.783987560414, 5894.793682221101, 5943.227380440515, 6024.849378765391, 6031.638101713911, 6035.577509574287, 6004.309504407049, 6050.2437195350585, 6139.201903752483, 6178.62928224308, 6207.193064901534, 5934.287885453773, 5960.04639257734, 5976.820695379146, 6054.878902422015, 6068.423499767515], "value": 5738.411364029775, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.35216106095714, 496.30269471392705, 539.8920215956807, 541.9268398766168, 546.9124940009597, 31561.526577101362, 32257.19357016073, 32268.515972260437, 32317.5152372714, 33811.66188338116, 34987.0, 35540.82229588849, 35566.64433355666, 35617.2876542469, 43397.34903976435, 9692.176165025963, 10016.699499015005, 10027.799444011116, 10029.949850250776, 10041.397516149054, 9804.509774511282, 9901.900980990169, 9903.702888913343, 9915.157211637019, 9919.702408927707, 9254.86117708235, 9468.384555003902, 9682.46746428946, 9725.708228753148, 9732.610695572168, 12000.339981301026, 12288.078394120477, 12297.93851030748, 12470.127091103626, 12482.937585312015, 11999.760004799855, 12077.698057548543, 12082.15424920256, 12101.152919295653, 12106.455209515592, 41204.587954120376, 41574.58425415768, 41588.79205603953, 41644.791776040925, 41754.582454175375, 3382.5433566468705, 3447.5345828313107, 3493.6855682988653, 3702.129999450127, 3923.1761330120707, 32277.677223227704, 32390.352192956245, 32593.0, 32770.344593108006, 32903.83548082244, 2951.9557206641916, 3285.342931413727, 3314.767966242364, 3414.436617958034, 3424.2466657335344, 520141.0, 520255.0, 520583.39708301594, 521336.0, 522158.0, 35065.649343506746, 60302.69848650816, 60564.69717651384, 60648.696756515936, 60831.0, 36180.27639447196, 36711.63288367136, 37364.879053628436, 37380.0, 37446.06384840401, 15912.443064492687, 16038.599035024217, 16067.437639682556, 16076.276567554489, 16099.919500402422, 13544.729105417837, 13673.86326136726, 13674.111182773087, 13689.520866769615, 13707.725845483035, 8099.230573095547, 8204.876926846102, 8274.8345033099, 8294.543800090933, 8336.0, 2885.639295088114, 3208.53476245943, 3229.047431007811, 3229.515572664119, 3309.8014119152926, 998.8651532043082, 1052.2423854824515, 1112.630097891486, 1130.6325444230658, 1180.533689192764, 24742.134025309027, 30030.84984575107, 30714.846425768174, 31462.32570231556, 32051.878184263438, 1085.495244711213, 1089.0688461365419, 1097.5061222449929, 1243.0, 1272.662744372742, 16411.0, 16471.83528164703, 16495.670086598202, 16549.50351489457, 16683.582910427336, 715551.0, 718578.0, 727890.0, 728691.0, 731044.0, 61491.692541537006, 61901.976371535704, 62180.0, 62302.065469018, 62334.0649890252, 1008.8385858262643, 1323.616151316127, 1389.2289779172656, 1415.6814716688662, 1434.8206474190727, 31630.683693162773, 32794.0, 32853.50719739254, 32999.50500742539, 33368.49947250842, 49826.50173498219, 50410.0, 50476.49523504865, 50700.0, 50742.74628626833, 589315.0, 592244.0, 592576.0, 601876.0, 691104.0, 22441.775582244623, 25208.739563022144, 25727.871360643076, 28823.42353152883, 28858.42283154367, 47642.285365718846, 49267.76830579264, 49546.50453495563, 50495.74752126216, 50587.2411913829, 34413.0, 39684.015799209345, 40429.0, 41088.97277568085, 42217.57782422259, 40596.0, 40830.183396332504, 41125.588744112174, 42066.57933420627, 42378.576214237466, 38201.4269785959, 38647.42028869513, 39120.80439597784, 39419.60580394274, 39627.60372396239, 34717.82641086778, 35254.82372588121, 35513.0, 36544.45183322199, 36593.0, 26276.868615657564, 26349.473010540067, 26409.0, 26687.599686005113, 26738.331541710835], "value": 19449.56753616122, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.22779599107804, 383.75439718580105, 385.723280730197, 394.1565050791306, 415.2670536503073, 14347.28263586819, 20108.195672173097, 21123.894380528116, 21390.786092139075, 21407.892960535195, 16241.675166496692, 18033.549161270974, 20004.399868003984, 20581.38255852327, 21058.262960796375, 5723.427657234285, 6008.489278411318, 6014.037753959367, 6020.6688632125215, 6025.397460253984, 5833.766649334021, 5898.380670029649, 6077.604955677889, 6168.506519478454, 6215.440610345068, 6101.0848372744, 6108.81673549792, 6111.358307377705, 6111.724972376253, 6122.326544080148, 5710.600257981943, 5798.188253644493, 6035.185249991239, 6045.12345709874, 6076.878462430749, 5248.685078895241, 5263.578913686896, 5266.605004624668, 5271.182966640138, 5271.920921186185, 25007.874960625264, 25454.727263636745, 25615.615765763527, 25683.486330273478, 26248.86875565629, 2287.2223444028946, 2910.272431892027, 3010.9247268818235, 3101.705337992887, 3137.654857965622, 15587.688246235126, 15686.294116764773, 15716.842831571652, 15853.9694919831, 15922.283497242652, 1846.5752876838403, 1902.8382587479941, 1931.2854243929696, 1950.6488832010232, 1970.3891793544046, 119144.0, 125181.0, 126480.0, 127244.36377818145, 128147.0, 7739.883901741478, 9804.215662746963, 9813.950930245446, 10121.746956326151, 10228.59085636581, 12579.874201258053, 12650.367481626066, 12670.98632109429, 12673.239605623692, 12690.238585684703, 7741.651625676858, 8067.426851763849, 8166.346692264605, 8204.548749818698, 8209.876851847228, 8663.826723465496, 8680.350733360641, 8690.695825646199, 8796.824063518694, 8909.910900891038, 4555.316702494652, 4558.840440584564, 4560.543945605479, 4565.3836732040745, 4645.651576131803, 1725.7411388291605, 1819.89990550521, 1843.8340549350623, 1915.6551820672134, 1931.6329897319465, 900.7342833864026, 970.2286682087757, 978.3493976505545, 982.0719420148034, 991.108002797488, 17930.103494825205, 20416.591668166555, 21364.252251171136, 21475.892620536797, 21478.570428591654, 731.4002517935241, 825.2160447574828, 862.7929296968682, 947.9335049591818, 975.9707208783748, 9118.544072796334, 10704.715434147796, 11206.327620342805, 11231.989120979151, 11245.98786109254, 626192.0, 627736.0, 633450.0, 635220.0, 637796.0, 138741.0, 140621.78134655976, 140962.0, 141894.87157692644, 143687.0, 997.2620261006948, 1005.7586179316911, 1010.242318261304, 1011.3527342500805, 1022.3712416863575, 17550.648987020442, 18175.909120454315, 18853.340133095826, 18927.0, 18930.242790288234, 57429.0, 59360.0, 60109.0, 60376.49058773566, 60454.09318860133, 63555.6822215886, 84268.15731842603, 86581.56709216414, 86647.56676216831, 87495.68756468785, 7876.684932602631, 7959.124496305286, 8196.508209507449, 8328.042275138436, 8332.375071869756, 10876.673699789018, 12717.618471445872, 12851.742965140831, 13093.869061309266, 13252.668683282609, 7979.720709775363, 11200.607978720705, 11252.943735281271, 11470.311781293149, 11601.941990289995, 11032.0, 11042.834357484804, 11089.722756931142, 11124.833127503256, 11125.054370378284, 11045.558177672803, 11582.478788454371, 11713.0, 11791.29252244868, 11821.408929553316, 8089.433739638169, 8151.225633564802, 8151.918480815353, 8252.958735206286, 8274.503529788231, 8272.420930535045, 9943.558184063415, 11127.777444451227, 11317.830232546683, 11548.94225528867], "value": 9728.076146191486, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1510.2750679673757, 1533.9539813805584, 1739.4694618141461, 1910.7516022917018, 1910.9617807643847, 8617.396782225247, 9178.082191780826, 9178.128077832613, 9187.77030574236, 9196.0, 7983.361331093513, 8260.628271727772, 8276.91723082769, 8649.481031138135, 8882.64469421224, 2379.702537182852, 2410.1203060882826, 2410.4094496848297, 2410.7227668818045, 2412.264259400882, 2382.1781485387514, 2391.760823917603, 2417.782399584037, 2418.322869596516, 2423.2245681381964, 2396.628522579003, 2404.759524047599, 2411.686480757508, 2418.2503423938633, 2420.6490058941445, 2373.5490256851317, 2387.880605969703, 2393.0308225168747, 2395.413123784684, 2395.6286775549643, 2370.3126093433057, 2384.332386931662, 2389.2235023617395, 2389.4862604539935, 2390.3307074019303, 9006.279497640173, 9075.54622268884, 9104.453732776055, 9130.17828395441, 9137.314701397356, 5424.213489044106, 5601.551875849922, 5606.579506537026, 5608.383077861432, 5625.071863142576, 3522.3131489359585, 3616.3309787689363, 3636.7454278200535, 3694.0949467380665, 3695.8891233263043, 1094.6715985204407, 1096.5175322857997, 1104.2987702808775, 1114.414932160614, 1124.2860783402516, 11766.529338826436, 11790.99776518997, 11870.821937670942, 11899.524019039229, 11935.0, 4183.226103170923, 4512.300593408027, 4617.607503362252, 4623.537646235417, 4758.95241047592, 9354.625814967461, 9382.014888436752, 9382.5308734563, 9389.906100938902, 9400.24798016157, 5455.727213639302, 5456.727163641802, 5460.399356070829, 5462.754176062087, 5468.671879687232, 4608.147492713859, 4769.523047695203, 4965.106280869406, 5077.009983053269, 5129.179331306973, 3874.6319099685484, 3879.495665563515, 3953.703472239536, 3973.0, 3986.7807270599815, 1082.5669732107062, 1089.6131873185063, 1175.9353235572125, 1190.2441949362044, 1192.934388608635, 824.6041899888087, 877.4647465046316, 904.7738065483629, 949.4967667136428, 954.7660823098386, 5230.293910322058, 5881.794137205177, 6003.939960600425, 6165.568410211242, 6313.96843015782, 450.84583466325654, 451.2937253198734, 461.76911544227886, 463.2657238277368, 466.48453458928077, 3538.911527211789, 3982.4623675803973, 4047.696422768245, 4056.229316429869, 4174.0, 10439.112675422673, 10940.726481838017, 10943.398113103696, 10965.287256328473, 10966.287191332538, 10277.280590358752, 10523.158147348193, 10802.783944321069, 10903.073238774792, 11002.174836887265, 8275.420720549624, 8334.958130233721, 8415.49507029568, 8526.63573828197, 8535.615897284637, 5492.642978206404, 5848.742520358143, 6136.907946380718, 6199.411055949678, 6348.047792830927, 8934.642614295355, 9523.857142142735, 10062.993700629879, 10312.432816195182, 10423.166146708094, 10361.844572331573, 10436.060754532129, 10485.0, 10533.262671612914, 10622.73443163927, 6373.74505019794, 7531.548107113591, 7596.202398748273, 7610.315071643578, 7668.041494813148, 8450.830983380421, 8520.3183745299, 8523.275521580736, 8533.018702849004, 8555.957220213859, 7645.655945482578, 7694.769156925301, 7701.268379503938, 7917.44577879566, 7927.762167134995, 7532.209118042527, 7637.427192960661, 7653.119891212359, 7667.386609071149, 7668.80827979305, 7124.465665075037, 7252.601106939169, 7256.129264488295, 7262.491625586158, 7277.781666550012, 4346.956530434655, 5027.396712394536, 5119.948800512097, 5223.764930578209, 5385.38068122171, 4483.484399293992, 5166.612504062286, 5177.171652535576, 5194.4805519447755, 5211.843644690665], "value": 4558.590104084802, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.52795930287704, 308.2432627898508, 314.9826759528225, 320.0686003729149, 320.6584986988858, 5223.36220208794, 5290.0, 5350.7057111858885, 5388.91916621251, 5400.972995135019, 5218.686878787275, 5288.15389537674, 5327.520523152915, 5413.458654134594, 5428.37573679027, 3690.926181476369, 3901.317269477844, 4153.6677065834665, 4173.373993900924, 4173.4365860608805, 3823.770573765583, 4062.9796851015703, 4150.066235097109, 4151.8962025949295, 4166.729162604436, 3236.385086833494, 3474.391981403257, 3477.9130521737034, 4046.433499310099, 4083.7549747015128, 3707.0, 3940.940885886714, 4065.2276067547073, 4070.5522392536795, 4085.44846445729, 2949.587057811908, 3533.2756784859043, 3558.3061303045915, 3563.1448452371505, 3581.982090089533, 4239.512456067561, 4819.614430845559, 5121.974390128063, 5218.791248350061, 5258.474152584749, 4640.814367425299, 4646.4424269087585, 4667.599720083995, 4669.579737823612, 4681.0, 5626.859328516779, 5631.774729010835, 5653.745581448845, 5671.574631902624, 5672.914906276409, 3254.23525471514, 3453.930921381558, 3733.7199710021587, 3854.8843534693724, 3875.9612403876163, 4830.468648448667, 5842.503387212092, 5909.9704501477645, 5918.40815918409, 5919.911201331984, 3379.7803142795638, 3832.865849695247, 3925.666318362971, 3927.155661532727, 3941.921161576752, 3734.7012239020814, 3958.2281455115976, 3986.62461450799, 4019.879403617896, 4041.3937909313836, 3664.4686520454384, 4038.5355684096116, 4637.6840559000475, 4641.797159959617, 4670.953290467051, 4731.4795372509, 4786.755443584693, 5195.6622819516615, 5661.745221465044, 5718.914216286759, 1070.6252811516063, 1122.7080958950569, 1326.9715970123232, 1332.8467226269104, 1343.5566263133142, 747.0102114698046, 781.4217479065564, 879.8328317619632, 922.4557511068501, 934.780326623243, 4350.32569951658, 5370.9731451342495, 5375.919361209585, 5392.272043274186, 5412.944475827176, 4785.0, 5528.612997090165, 5634.0, 5796.362400135981, 5853.502452291603, 4927.334809800703, 4954.702717836942, 5287.920681189786, 5289.0, 5293.735313234323, 2872.6983666715114, 3003.0480187878056, 3049.4968330225265, 3053.9694603053686, 3054.4196602645425, 5424.698072462581, 5759.366469688331, 5763.567732420001, 5983.790567330123, 6008.579399442085, 5749.626274292157, 5868.799200299887, 5912.970435147883, 5917.733701983421, 5921.644701317935, 4933.679310844784, 5600.551955843523, 5927.881442371215, 5945.970270148621, 5959.225300710967, 4809.470958194665, 5023.598112151092, 5345.41200467956, 5758.2514273144225, 5797.246357973437, 4984.853483698697, 5645.0, 5688.687122208318, 5732.885342293047, 5733.283339582552, 5493.560515158858, 5644.774209031592, 5658.801941932013, 5942.168096466585, 5964.701764911824, 3382.644822293722, 3464.636213197679, 3881.281962836771, 3910.7485604606254, 3935.567087620302, 4904.950950490592, 5810.111713692982, 5831.825045248649, 5840.328362238397, 5883.911741323969, 5370.275012873369, 5482.780688772564, 5569.526590239875, 5609.635373700697, 5617.949448451368, 5182.32629758129, 5288.8148914789335, 5327.334083239595, 5575.9926426487245, 5589.80435684749, 4592.9540704593865, 4625.502228796412, 4708.952910470988, 5064.987002599421, 5301.522862942199, 4594.517575654508, 4664.79008444614, 5202.621305354009, 5664.716764161858, 5710.2862142232225, 4919.745464906569, 5022.924656130234, 5025.623078269217, 5110.897782044264, 5303.257543943928], "value": 4061.944492811513, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.8586668532981, 767.8306794107995, 791.1100012485953, 796.211750367136, 843.0220943705303, 15809.446669366574, 16019.279132439038, 16773.916130419348, 16798.748018779726, 16819.49541513755, 14914.0, 15290.92963492556, 15312.387504499835, 15315.617109572266, 15315.693686126298, 706.6784613001098, 749.8312879602087, 903.0427746588637, 906.4923642760042, 908.1281969309438, 720.5352547606791, 878.767126711422, 888.9733308000771, 899.1682693508511, 903.9728808135734, 950.6435086842434, 953.2897990996718, 954.6086104697075, 955.7658373698454, 959.1703176752129, 944.6221511395497, 946.1059298962483, 948.6015873333216, 949.544218774985, 950.771814764455, 710.6695386645183, 710.9608971506565, 711.2212127720138, 711.8540699156661, 712.152538479208, 11618.59334923282, 13873.58379248624, 13941.302934853318, 13987.65030874226, 14034.789478157836, 14855.777163342558, 14902.627434314121, 15074.547763567001, 15107.39570417182, 15122.243887805676, 11868.0, 12118.939405303005, 12198.14612977092, 12217.755644887053, 12257.26456412618, 3814.141818090935, 3840.6543411093135, 3841.5966323535904, 4048.67610591152, 4100.159467309194, 121390.50724781773, 125247.0, 125435.0, 126704.36647816794, 128210.35894820467, 11258.21192516516, 11583.942080289544, 11636.243644163102, 11729.706757331134, 11839.11206659504, 23596.7640323598, 23693.0, 23823.404414889766, 23881.52236955286, 23895.880520597286, 7229.819254518679, 8117.350611951029, 8279.592469280236, 8436.0, 8550.486970781772, 4078.7552746835286, 4481.2381895077915, 4522.29904364824, 4611.8847028823875, 4815.6388270879825, 3665.450182472597, 3819.8854034379015, 3820.9808950955066, 3826.579076301605, 3832.7508711933633, 663.7975417497613, 672.3175976383974, 675.6047712088385, 692.0864458914285, 692.4218277738145, 592.2596754057428, 620.6307247187916, 646.9708863101172, 704.2183176673888, 712.4122598855964, 9175.632974681072, 9996.600135994624, 10017.248706347053, 10038.698839034842, 10223.335483193569, 665.517499812632, 682.8224661588023, 704.6476761619191, 720.3336913355125, 725.1906493299252, 3964.643182113566, 4568.497465278817, 4755.453122890912, 4814.807407703653, 4816.903661926812, 105658.0, 143357.0, 144085.27957360147, 149713.0, 171541.0, 138328.0, 139654.3017284907, 144646.0, 145523.0, 150475.24762376118, 118107.0, 120541.0, 121763.0, 123615.38192308981, 125609.7439025598, 8790.868136978079, 9181.357304988587, 9406.95296523513, 9444.622215111318, 9473.289503287144, 61352.377147422376, 62660.686696568046, 66059.0, 67386.66306668436, 68954.65522672555, 92941.53529232311, 99565.50217248868, 99993.50003250228, 102677.459838101, 105247.4737626307, 11699.94142583397, 11964.341961192218, 12238.510459581517, 12274.938625306817, 12814.55149069778, 13228.272445015231, 13672.384742686405, 13854.584362469142, 13957.720845583235, 14313.141211527342, 12369.814452783035, 12432.502699892264, 12707.745845083231, 12733.93633031866, 13298.135621184592, 12535.185212961129, 12777.36113194318, 12783.233006019296, 13040.478380865039, 13300.667483312995, 12207.633770986884, 12237.75524489523, 12359.0, 12518.937405312916, 12555.986563224578, 9803.0, 10243.59025638996, 10489.423081730272, 11177.82032503654, 11221.775564488828, 8685.478871267744, 10816.296940698829, 10912.945435272773, 11094.611688590861, 11098.722531936766], "value": 8014.649876541896, "unit": "score"}], "name": "dromaeo_css", "value": 7376.843591004664, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 111.0, 110.0, 111.0, 110.0, 111.0, 111.0, 110.0, 111.0, 111.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 136.0, 109.0, 141.0, 109.0, 105.0, 107.0, 111.0, 135.0, 109.0], "value": 122.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 179.0, 183.0, 181.0, 181.0, 179.0, 180.0, 183.0, 181.0], "value": 181.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 60.0, 88.0, 61.0, 58.0, 58.0, 59.0, 82.0, 56.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 68.0, 92.0, 69.0, 69.0, 68.0, 91.0, 64.0, 68.0, 87.0], "value": 76.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 129.0, 116.0, 86.0, 90.0, 85.0, 89.0, 85.0, 89.0, 85.0], "value": 94.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 150.0, 95.0, 119.0, 148.0, 104.0, 94.0, 95.0, 94.0, 95.0], "value": 113.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 104.0, 132.0, 117.0, 83.0, 91.0, 84.0, 85.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 54.0, 53.0, 56.0, 56.0, 57.0, 55.0, 56.0, 58.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 70.0, 69.0, 76.0, 72.0, 67.0, 72.0, 69.0, 69.0, 70.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 85.0, 85.0, 84.0, 82.0, 86.0, 88.0, 99.0, 90.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 144.0, 139.0, 142.0, 138.0, 137.0, 145.0, 148.0, 151.0, 134.0], "value": 142.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 159.0, 160.0, 160.0, 161.0, 161.0, 159.0, 160.0, 159.0], "value": 160.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 66.0, 65.0, 63.0, 65.0, 66.0, 65.0, 64.0, 64.0], "value": 65.2, "unit": "score"}], "name": "kraken", "value": 1439.5, "alertThreshold": 2.0}]} 15:34:37 INFO - Return code: 0 15:34:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:34:37 INFO - # TBPL SUCCESS # 15:34:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:34:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:34:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:34:37 INFO - 2017-01-04 15:34:37.606 screenresolution[1922:19918] starting screenresolution argv=screenresolution get 15:34:37 INFO - 2017-01-04 15:34:37.609 screenresolution[1922:19918] Display 0: 1600x1200x32@60 15:34:37 INFO - 2017-01-04 15:34:37.616 screenresolution[1923:19921] starting screenresolution argv=screenresolution list 15:34:37 INFO - Available Modes on Display 0 15:34:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:34:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:34:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:34:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:34:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:34:37 INFO - Intel Iris: 15:34:37 INFO - Chipset Model: Intel Iris 15:34:37 INFO - Type: GPU 15:34:37 INFO - Bus: Built-In 15:34:37 INFO - VRAM (Dynamic, Max): 1536 MB 15:34:37 INFO - Vendor: Intel (0x8086) 15:34:37 INFO - Device ID: 0x0a2e 15:34:37 INFO - Revision ID: 0x0009 15:34:37 INFO - Displays: 15:34:37 INFO - Display: 15:34:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:34:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:34:37 INFO - Display Serial Number: 1600x1200 60 15:34:37 INFO - Main Display: Yes 15:34:37 INFO - Mirror: Off 15:34:37 INFO - Online: Yes 15:34:37 INFO - Rotation: Supported 15:34:37 INFO - Return code: 0 15:34:37 INFO - Running post-action listener: _resource_record_post_action 15:34:37 INFO - [mozharness: 2017-01-04 23:34:37.853193Z] Finished run-tests step (success) 15:34:37 INFO - Running post-run listener: _resource_record_post_run 15:34:37 INFO - Total resource usage - Wall time: 1195s; CPU: 53.0%; Read bytes: 847872; Write bytes: 495228928; Read time: 9; Write time: 1584 15:34:37 INFO - TinderboxPrint: CPU usage
53.1% 15:34:37 INFO - TinderboxPrint: I/O read bytes / time
847,872 / 9 15:34:37 INFO - TinderboxPrint: I/O write bytes / time
495,228,928 / 1,584 15:34:37 INFO - TinderboxPrint: CPU idle
2,241.6 (46.9%) 15:34:37 INFO - TinderboxPrint: CPU system
956.0 (20.0%) 15:34:37 INFO - TinderboxPrint: CPU user
1,584.0 (33.1%) 15:34:37 INFO - TinderboxPrint: Swap in / out
196,194,304 / 0 15:34:37 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 192455168; Write bytes: 188262400; Read time: 17663; Write time: 420 15:34:37 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 802816; Write bytes: 295673856; Read time: 8; Write time: 1122 15:34:38 INFO - Running post-run listener: _upload_blobber_files 15:34:38 INFO - Blob upload gear active. 15:34:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:34:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:34:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:34:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:34:38 INFO - (blobuploader) - INFO - Open directory for files ... 15:34:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:34:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:34:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:34:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:34:40 INFO - (blobuploader) - INFO - Done attempting. 15:34:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:34:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:34:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:34:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:34:41 INFO - (blobuploader) - INFO - Done attempting. 15:34:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:34:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:34:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:34:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:34:43 INFO - (blobuploader) - INFO - Done attempting. 15:34:43 INFO - (blobuploader) - INFO - Iteration through files over. 15:34:43 INFO - Return code: 0 15:34:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:34:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:34:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3aa71c0d048473be786996b8883d642f68286b93d6cf99ee161626e5d1dd511236b848edc3b473d57dc04c89a36ace43144fab908c9962762c2495a58f90bf03", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3a42be27bd5a495f1fb84dbdc7ea1a70cf3b7e95dac26db9c829b9c7a2566f9c9473765ad48580cc360d08d7570b17a08dc6568df477f4dc1131993520d4c20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f26f3885386765d881cb4b978ed4f4f4ca629360e7dbd54a5e94e5de5e0da3896a83e25aa8243803dbca0d6feda1d1eb3d0cd65966601eb195c133901b5673f"} 15:34:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:34:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:34:43 INFO - Contents: 15:34:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3aa71c0d048473be786996b8883d642f68286b93d6cf99ee161626e5d1dd511236b848edc3b473d57dc04c89a36ace43144fab908c9962762c2495a58f90bf03", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3a42be27bd5a495f1fb84dbdc7ea1a70cf3b7e95dac26db9c829b9c7a2566f9c9473765ad48580cc360d08d7570b17a08dc6568df477f4dc1131993520d4c20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f26f3885386765d881cb4b978ed4f4f4ca629360e7dbd54a5e94e5de5e0da3896a83e25aa8243803dbca0d6feda1d1eb3d0cd65966601eb195c133901b5673f"} 15:34:43 INFO - Running post-run listener: copy_logs_to_upload_dir 15:34:43 INFO - Copying logs to upload dir... 15:34:43 INFO - mkdir: /builds/slave/test/build/upload/logs 15:34:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.080189 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-01-04 15:34:43.321496) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 15:34:43.325257) ========= 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.QvGA9s14DV/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.tX5U1Ekpkh/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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/Mozilla-Beta/sha512/3aa71c0d048473be786996b8883d642f68286b93d6cf99ee161626e5d1dd511236b848edc3b473d57dc04c89a36ace43144fab908c9962762c2495a58f90bf03", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3a42be27bd5a495f1fb84dbdc7ea1a70cf3b7e95dac26db9c829b9c7a2566f9c9473765ad48580cc360d08d7570b17a08dc6568df477f4dc1131993520d4c20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f26f3885386765d881cb4b978ed4f4f4ca629360e7dbd54a5e94e5de5e0da3896a83e25aa8243803dbca0d6feda1d1eb3d0cd65966601eb195c133901b5673f"} build_url:https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011191 build_url: 'https://queue.taskcluster.net/v1/task/CaLVx7NWSqar9wZPEcKCqA/artifacts/public/build/firefox-51.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3aa71c0d048473be786996b8883d642f68286b93d6cf99ee161626e5d1dd511236b848edc3b473d57dc04c89a36ace43144fab908c9962762c2495a58f90bf03", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3a42be27bd5a495f1fb84dbdc7ea1a70cf3b7e95dac26db9c829b9c7a2566f9c9473765ad48580cc360d08d7570b17a08dc6568df477f4dc1131993520d4c20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f26f3885386765d881cb4b978ed4f4f4ca629360e7dbd54a5e94e5de5e0da3896a83e25aa8243803dbca0d6feda1d1eb3d0cd65966601eb195c133901b5673f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 15:34:43.360403) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 15:34:43.360765) ========= 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.QvGA9s14DV/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.tX5U1Ekpkh/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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.005321 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 15:34:43.409689) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 15:34:43.410036) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 15:34:43.410387) ========= ========= Total master_lag: 2.32 =========