builder: pine_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0110 starttime: 1491873978.86 results: success (0) buildid: 20170410113323 builduid: 4862cd4d03cb4346a1bf4b763ca80c5a revision: e516c0daef83fdd6542da4d74cabde0683533450 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:18.856666) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:18.857149) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:18.857459) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:18.884424) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:18.884851) ========= 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.YIzAxkjSYc/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.w9nkTkU9PV/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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-04-10 18:26:18-- 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% 8.92M=0.001s 2017-04-10 18:26:19 (8.92 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.144622 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:19.053759) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:19.054107) ========= 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.YIzAxkjSYc/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.w9nkTkU9PV/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.031302 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:19.107759) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 18:26:19.108195) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev e516c0daef83fdd6542da4d74cabde0683533450 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev e516c0daef83fdd6542da4d74cabde0683533450 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YIzAxkjSYc/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.w9nkTkU9PV/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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-04-10 18:26:19,212 truncating revision to first 12 chars 2017-04-10 18:26:19,212 Setting DEBUG logging. 2017-04-10 18:26:19,212 attempt 1/10 2017-04-10 18:26:19,212 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/e516c0daef83?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-10 18:26:20,790 unpacking tar archive at: pine-e516c0daef83/testing/mozharness/ program finished with exit code 0 elapsedTime=1.846005 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 18:26:20.978255) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:20.978573) ========= script_repo_revision: e516c0daef83fdd6542da4d74cabde0683533450 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:20.978954) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:20.979214) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:21.003530) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-04-10 18:26:21.003935) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YIzAxkjSYc/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.w9nkTkU9PV/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 18:26:21 INFO - MultiFileLogger online at 20170410 18:26:21 in /builds/slave/test 18:26:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 18:26:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:26:21 INFO - {'append_to_log': False, 18:26:21 INFO - 'base_work_dir': '/builds/slave/test', 18:26:21 INFO - 'blob_upload_branch': 'Pine', 18:26:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:26:21 INFO - 'branch': 'Pine', 18:26:21 INFO - 'buildbot_json_path': 'buildprops.json', 18:26:21 INFO - 'config_files': ('talos/mac_config.py',), 18:26:21 INFO - 'default_actions': ('clobber', 18:26:21 INFO - 'read-buildbot-config', 18:26:21 INFO - 'download-and-extract', 18:26:21 INFO - 'populate-webroot', 18:26:21 INFO - 'create-virtualenv', 18:26:21 INFO - 'install', 18:26:21 INFO - 'run-tests'), 18:26:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:26:21 INFO - 'download_minidump_stackwalk': True, 18:26:21 INFO - 'download_symbols': 'ondemand', 18:26:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:26:21 INFO - 'tooltool.py': '/tools/tooltool.py', 18:26:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:26:21 INFO - '/tools/misc-python/virtualenv.py')}, 18:26:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:26:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:26:21 INFO - 'gecko_profile': False, 18:26:21 INFO - 'gecko_profile_interval': 0, 18:26:21 INFO - 'installer_path': 'installer.exe', 18:26:21 INFO - 'log_level': 'info', 18:26:21 INFO - 'log_name': 'talos', 18:26:21 INFO - 'log_to_console': True, 18:26:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:26:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:26:21 INFO - 'opt_config_files': (), 18:26:21 INFO - 'pip_index': False, 18:26:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:26:21 INFO - 'cmd': ('bash', 18:26:21 INFO - '-c', 18:26:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:26:21 INFO - 'enabled': True, 18:26:21 INFO - 'halt_on_failure': False, 18:26:21 INFO - 'name': 'check_screen_resolution'},), 18:26:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:26:21 INFO - 'cmd': ('bash', 18:26:21 INFO - '-c', 18:26:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:26:21 INFO - 'enabled': True, 18:26:21 INFO - 'halt_on_failure': False, 18:26:21 INFO - 'name': 'check_screen_resolution'},), 18:26:21 INFO - 'run_cmd_checks_enabled': True, 18:26:21 INFO - 'suite': 'dromaeojs', 18:26:21 INFO - 'system_bits': '32', 18:26:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:26:21 INFO - 'title': 't-yosemite-r7-0110', 18:26:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:26:21 INFO - 'use_talos_json': True, 18:26:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:26:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:26:21 INFO - 'work_dir': 'build'} 18:26:21 INFO - [mozharness: 2017-04-11 01:26:21.220195Z] Running clobber step. 18:26:21 INFO - Running pre-action listener: _resource_record_pre_action 18:26:21 INFO - Running main action method: clobber 18:26:21 INFO - rmtree: /builds/slave/test/build 18:26:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:26:23 INFO - Running post-action listener: _resource_record_post_action 18:26:23 INFO - [mozharness: 2017-04-11 01:26:23.660293Z] Finished clobber step (success) 18:26:23 INFO - [mozharness: 2017-04-11 01:26:23.660420Z] Running read-buildbot-config step. 18:26:23 INFO - Running pre-action listener: _resource_record_pre_action 18:26:23 INFO - Running main action method: read_buildbot_config 18:26:23 INFO - Using buildbot properties: 18:26:23 INFO - { 18:26:23 INFO - "project": "", 18:26:23 INFO - "product": "firefox", 18:26:23 INFO - "script_repo_revision": "default", 18:26:23 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs", 18:26:23 INFO - "repository": "", 18:26:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs", 18:26:23 INFO - "buildid": "20170410113323", 18:26:23 INFO - "pgo_build": "False", 18:26:23 INFO - "basedir": "/builds/slave/test", 18:26:23 INFO - "buildnumber": 0, 18:26:23 INFO - "slavename": "t-yosemite-r7-0110", 18:26:23 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 18:26:23 INFO - "platform": "yosemite_r7", 18:26:23 INFO - "scheduler": "tests-pine-macosx64-talos", 18:26:23 INFO - "branch": "pine", 18:26:23 INFO - "repo_path": "projects/pine", 18:26:23 INFO - "revision": "e516c0daef83fdd6542da4d74cabde0683533450", 18:26:23 INFO - "stage_platform": "macosx64", 18:26:23 INFO - "builduid": "4862cd4d03cb4346a1bf4b763ca80c5a", 18:26:23 INFO - "slavebuilddir": "test" 18:26:23 INFO - } 18:26:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 18:26:23 INFO - Running post-action listener: _resource_record_post_action 18:26:23 INFO - [mozharness: 2017-04-11 01:26:23.663769Z] Finished read-buildbot-config step (success) 18:26:23 INFO - [mozharness: 2017-04-11 01:26:23.663905Z] Running download-and-extract step. 18:26:23 INFO - Running pre-action listener: _resource_record_pre_action 18:26:23 INFO - Running main action method: download_and_extract 18:26:23 INFO - mkdir: /builds/slave/test/build/tests 18:26:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:23 INFO - trying https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:26:23 INFO - Downloading https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:26:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 18:26:24 INFO - Downloaded 1325 bytes. 18:26:24 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:26:24 INFO - Using the following test package requirements: 18:26:24 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:26:24 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:26:24 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:26:24 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:26:24 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:26:24 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:26:24 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:26:24 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:26:24 INFO - u'jsshell-mac.zip'], 18:26:24 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:26:24 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:26:24 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:26:24 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:26:24 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:26:24 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:26:24 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:26:24 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:26:24 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:26:24 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:26:24 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:26:24 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:26:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:26:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:26:24 INFO - Fetch https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:26:26 INFO - Expected file size: 52584660 18:26:26 INFO - Obtained file size: 52584660 18:26:42 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:26:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:26:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:26:42 INFO - Fetch https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:26:44 INFO - Expected file size: 52584660 18:26:44 INFO - Obtained file size: 52584660 18:26:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:26:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:26:59 INFO - Fetch https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:27:01 INFO - Expected file size: 14405596 18:27:01 INFO - Obtained file size: 14405596 18:27:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:01 INFO - trying https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:27:01 INFO - Downloading https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:27:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 18:27:03 INFO - Downloaded 70988642 bytes. 18:27:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:27:03 INFO - mkdir: /builds/slave/test/properties 18:27:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:27:03 INFO - Writing to file /builds/slave/test/properties/build_url 18:27:03 INFO - Contents: 18:27:03 INFO - build_url:https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:27:04 INFO - Running post-action listener: _resource_record_post_action 18:27:04 INFO - Running post-action listener: set_extra_try_arguments 18:27:04 INFO - [mozharness: 2017-04-11 01:27:04.183492Z] Finished download-and-extract step (success) 18:27:04 INFO - [mozharness: 2017-04-11 01:27:04.183636Z] Running populate-webroot step. 18:27:04 INFO - Running pre-action listener: _resource_record_pre_action 18:27:04 INFO - Running main action method: populate_webroot 18:27:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:27:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:27:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:27:04 INFO - '--tppagecycles', 18:27:04 INFO - '7'], 18:27:04 INFO - 'tests': ['tsvgm']}}, 18:27:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:27:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:27:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:27:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:27:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:27:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:27:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:27:04 INFO - 'talos_options': ['--disable-e10s'], 18:27:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:27:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:27:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:27:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:27:04 INFO - 'talos_options': ['--disable-e10s'], 18:27:04 INFO - 'tests': ['damp', 'tps']}, 18:27:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:27:04 INFO - 'tests': ['damp', 'tps']}, 18:27:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:27:04 INFO - 'tests': ['dromaeo_dom']}, 18:27:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:27:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:27:04 INFO - 'tests': ['basic_compositor_video']}, 18:27:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:27:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:27:04 INFO - 'tests': ['a11yr', 18:27:04 INFO - 'ts_paint', 18:27:04 INFO - 'tpaint', 18:27:04 INFO - 'sessionrestore', 18:27:04 INFO - 'sessionrestore_no_auto_restore', 18:27:04 INFO - 'tabpaint']}, 18:27:04 INFO - 'other-e10s': {'tests': ['a11yr', 18:27:04 INFO - 'ts_paint', 18:27:04 INFO - 'tpaint', 18:27:04 INFO - 'sessionrestore', 18:27:04 INFO - 'sessionrestore_no_auto_restore', 18:27:04 INFO - 'tabpaint']}, 18:27:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:27:04 INFO - 'ts_paint', 18:27:04 INFO - 'tpaint', 18:27:04 INFO - 'sessionrestore', 18:27:04 INFO - 'sessionrestore_no_auto_restore', 18:27:04 INFO - 'tabpaint']}, 18:27:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:27:04 INFO - 'ts_paint', 18:27:04 INFO - 'tpaint', 18:27:04 INFO - 'sessionrestore', 18:27:04 INFO - 'sessionrestore_no_auto_restore', 18:27:04 INFO - 'tabpaint']}, 18:27:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:27:04 INFO - 'tests': ['a11yr', 18:27:04 INFO - 'ts_paint', 18:27:04 INFO - 'tpaint', 18:27:04 INFO - 'sessionrestore', 18:27:04 INFO - 'sessionrestore_no_auto_restore', 18:27:04 INFO - 'tabpaint']}, 18:27:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:27:04 INFO - 'tests': ['a11yr', 18:27:04 INFO - 'ts_paint', 18:27:04 INFO - 'tpaint', 18:27:04 INFO - 'sessionrestore', 18:27:04 INFO - 'sessionrestore_no_auto_restore', 18:27:04 INFO - 'tabpaint']}, 18:27:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:27:04 INFO - 'tests': ['tsvgx', 18:27:04 INFO - 'tsvgr_opacity', 18:27:04 INFO - 'tart', 18:27:04 INFO - 'tscrollx', 18:27:04 INFO - 'cart', 18:27:04 INFO - 'tsvg_static']}, 18:27:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:27:04 INFO - 'tsvgr_opacity', 18:27:04 INFO - 'tart', 18:27:04 INFO - 'tscrollx', 18:27:04 INFO - 'cart', 18:27:04 INFO - 'tsvg_static']}, 18:27:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:27:04 INFO - 'talos_options': ['--disable-e10s'], 18:27:04 INFO - 'tests': ['tp5o']}, 18:27:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:27:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:27:04 INFO - 'talos_options': ['--disable-e10s', 18:27:04 INFO - '--xperf_path', 18:27:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:27:04 INFO - 'tests': ['tp5n']}, 18:27:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:27:04 INFO - 'talos_options': ['--xperf_path', 18:27:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:27:04 INFO - 'tests': ['tp5n']}}, 18:27:04 INFO - 'talos.zip': {'path': '', 18:27:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:27:04 INFO - Running post-action listener: _resource_record_post_action 18:27:04 INFO - [mozharness: 2017-04-11 01:27:04.201565Z] Finished populate-webroot step (success) 18:27:04 INFO - [mozharness: 2017-04-11 01:27:04.201688Z] Running create-virtualenv step. 18:27:04 INFO - Running pre-action listener: _resource_record_pre_action 18:27:04 INFO - Running main action method: create_virtualenv 18:27:04 INFO - Creating virtualenv /builds/slave/test/build/venv 18:27:04 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 18:27:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:27:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:27:04 INFO - Using real prefix '/tools/python27' 18:27:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:27:05 INFO - Installing distribute.............................................................................................................................................................................................done. 18:27:07 INFO - Installing pip.................done. 18:27:07 INFO - Return code: 0 18:27:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:27:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:27:08 INFO - Reading from file tmpfile_stdout 18:27:08 INFO - Output received: 18:27:08 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:27:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:08 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 0x10c9c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f930bd6ce90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ed30>, '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 0x10c6c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c793be8>, '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.YIzAxkjSYc/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.w9nkTkU9PV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 18:27:08 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 18:27:08 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 18:27:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIzAxkjSYc/Render', 18:27:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:08 INFO - 'HOME': '/Users/cltbld', 18:27:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:08 INFO - 'LOGNAME': 'cltbld', 18:27:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:08 INFO - 'MOZ_NO_REMOTE': '1', 18:27:08 INFO - 'NO_EM_RESTART': '1', 18:27:08 INFO - 'PAGER': '/bin/cat', 18:27:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:08 INFO - 'PWD': '/builds/slave/test', 18:27:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:08 INFO - 'SHELL': '/bin/bash', 18:27:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9nkTkU9PV/Listeners', 18:27:08 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 18:27:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:08 INFO - 'USER': 'cltbld', 18:27:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:08 INFO - 'XPC_FLAGS': '0x0', 18:27:08 INFO - 'XPC_SERVICE_NAME': '0', 18:27:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:08 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 18:27:08 INFO - Cleaning up... 18:27:08 INFO - Return code: 0 18:27:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:08 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 0x10c9c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f930bd6ce90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ed30>, '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 0x10c6c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c793be8>, '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.YIzAxkjSYc/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.w9nkTkU9PV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 18:27:08 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 18:27:08 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 18:27:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIzAxkjSYc/Render', 18:27:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:08 INFO - 'HOME': '/Users/cltbld', 18:27:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:08 INFO - 'LOGNAME': 'cltbld', 18:27:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:08 INFO - 'MOZ_NO_REMOTE': '1', 18:27:08 INFO - 'NO_EM_RESTART': '1', 18:27:08 INFO - 'PAGER': '/bin/cat', 18:27:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:08 INFO - 'PWD': '/builds/slave/test', 18:27:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:08 INFO - 'SHELL': '/bin/bash', 18:27:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9nkTkU9PV/Listeners', 18:27:08 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 18:27:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:08 INFO - 'USER': 'cltbld', 18:27:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:08 INFO - 'XPC_FLAGS': '0x0', 18:27:08 INFO - 'XPC_SERVICE_NAME': '0', 18:27:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:08 INFO - Downloading/unpacking psutil>=3.1.1 18:27:08 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 18:27:08 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 18:27:08 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 18:27:08 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 18:27:08 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 18:27:08 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 18:27:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:27:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:27:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:27:12 INFO - Installing collected packages: psutil 18:27:12 INFO - Running setup.py install for psutil 18:27:12 INFO - building 'psutil._psutil_osx' extension 18:27:12 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 18:27:12 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 18:27:12 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 18:27:13 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 18:27:13 INFO - building 'psutil._psutil_posix' extension 18:27:13 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 18:27:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:27:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:27:13 INFO - ^ 18:27:13 INFO - 1 warning generated. 18:27:13 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 18:27:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:27:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:27:13 INFO - Successfully installed psutil 18:27:13 INFO - Cleaning up... 18:27:13 INFO - Return code: 0 18:27:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:27: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')]} 18:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27: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')]} 18:27:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f930bd6ce90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ed30>, '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 0x10c6c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c793be8>, '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.YIzAxkjSYc/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.w9nkTkU9PV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 18:27: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:27: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 mozsystemmonitor==0.3 18:27:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIzAxkjSYc/Render', 18:27:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:13 INFO - 'HOME': '/Users/cltbld', 18:27:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:13 INFO - 'LOGNAME': 'cltbld', 18:27:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:13 INFO - 'MOZ_NO_REMOTE': '1', 18:27:13 INFO - 'NO_EM_RESTART': '1', 18:27:13 INFO - 'PAGER': '/bin/cat', 18:27:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:13 INFO - 'PWD': '/builds/slave/test', 18:27:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:13 INFO - 'SHELL': '/bin/bash', 18:27:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9nkTkU9PV/Listeners', 18:27:13 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 18:27:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:13 INFO - 'USER': 'cltbld', 18:27:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:13 INFO - 'XPC_FLAGS': '0x0', 18:27:13 INFO - 'XPC_SERVICE_NAME': '0', 18:27:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:27:13 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 18:27:13 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 18:27:13 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 18:27:13 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 18:27:13 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 18:27:13 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 18:27:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:27:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:27:16 INFO - Installing collected packages: mozsystemmonitor 18:27:16 INFO - Running setup.py install for mozsystemmonitor 18:27:16 INFO - Successfully installed mozsystemmonitor 18:27:16 INFO - Cleaning up... 18:27:16 INFO - Return code: 0 18:27:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:27:16 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')]} 18:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:17 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 0x10c9c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f930bd6ce90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ed30>, '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 0x10c6c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c793be8>, '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.YIzAxkjSYc/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.w9nkTkU9PV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 18:27:17 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 18:27:17 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 18:27:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIzAxkjSYc/Render', 18:27:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:17 INFO - 'HOME': '/Users/cltbld', 18:27:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:17 INFO - 'LOGNAME': 'cltbld', 18:27:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:17 INFO - 'MOZ_NO_REMOTE': '1', 18:27:17 INFO - 'NO_EM_RESTART': '1', 18:27:17 INFO - 'PAGER': '/bin/cat', 18:27:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:17 INFO - 'PWD': '/builds/slave/test', 18:27:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:17 INFO - 'SHELL': '/bin/bash', 18:27:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9nkTkU9PV/Listeners', 18:27:17 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 18:27:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:17 INFO - 'USER': 'cltbld', 18:27:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:17 INFO - 'XPC_FLAGS': '0x0', 18:27:17 INFO - 'XPC_SERVICE_NAME': '0', 18:27:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:17 INFO - Downloading/unpacking jsonschema==2.5.1 18:27:17 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 18:27:17 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 18:27:17 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 18:27:17 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 18:27:17 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 18:27:17 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 18:27:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:27:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:27:20 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 18:27:20 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 18:27:20 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 18:27:20 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 18:27:20 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 18:27:20 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 18:27:21 INFO - Downloading functools32-3.2.3-2.tar.gz 18:27:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:27:21 INFO - warning: no files found matching '*.txt' 18:27:21 INFO - no previously-included directories found matching 'build' 18:27:21 INFO - no previously-included directories found matching 'dist' 18:27:21 INFO - no previously-included directories found matching '.git*' 18:27:21 INFO - Installing collected packages: jsonschema, functools32 18:27:21 INFO - Running setup.py install for functools32 18:27:21 INFO - warning: no files found matching '*.txt' 18:27:21 INFO - no previously-included directories found matching 'build' 18:27:21 INFO - no previously-included directories found matching 'dist' 18:27:21 INFO - no previously-included directories found matching '.git*' 18:27:21 INFO - Successfully installed jsonschema functools32 18:27:21 INFO - Cleaning up... 18:27:21 INFO - Return code: 0 18:27:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:27:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:21 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 0x10c9c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f930bd6ce90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ed30>, '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 0x10c6c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c793be8>, '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.YIzAxkjSYc/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.w9nkTkU9PV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 18:27:21 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 18:27:21 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 18:27:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIzAxkjSYc/Render', 18:27:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:21 INFO - 'HOME': '/Users/cltbld', 18:27:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:21 INFO - 'LOGNAME': 'cltbld', 18:27:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:21 INFO - 'MOZ_NO_REMOTE': '1', 18:27:21 INFO - 'NO_EM_RESTART': '1', 18:27:21 INFO - 'PAGER': '/bin/cat', 18:27:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:21 INFO - 'PWD': '/builds/slave/test', 18:27:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:21 INFO - 'SHELL': '/bin/bash', 18:27:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9nkTkU9PV/Listeners', 18:27:21 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 18:27:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:21 INFO - 'USER': 'cltbld', 18:27:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:21 INFO - 'XPC_FLAGS': '0x0', 18:27:21 INFO - 'XPC_SERVICE_NAME': '0', 18:27:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:27:21 INFO - Cleaning up... 18:27:21 INFO - Return code: 0 18:27:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:27:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:21 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 0x10c9c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f930bd6ce90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ed30>, '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 0x10c6c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c793be8>, '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.YIzAxkjSYc/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.w9nkTkU9PV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 18:27:21 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 18:27:21 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 18:27:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIzAxkjSYc/Render', 18:27:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:21 INFO - 'HOME': '/Users/cltbld', 18:27:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:21 INFO - 'LOGNAME': 'cltbld', 18:27:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:21 INFO - 'MOZ_NO_REMOTE': '1', 18:27:21 INFO - 'NO_EM_RESTART': '1', 18:27:21 INFO - 'PAGER': '/bin/cat', 18:27:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:21 INFO - 'PWD': '/builds/slave/test', 18:27:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:21 INFO - 'SHELL': '/bin/bash', 18:27:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9nkTkU9PV/Listeners', 18:27:21 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 18:27:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:21 INFO - 'USER': 'cltbld', 18:27:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:21 INFO - 'XPC_FLAGS': '0x0', 18:27:21 INFO - 'XPC_SERVICE_NAME': '0', 18:27:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:22 INFO - Downloading/unpacking blobuploader==1.2.4 18:27: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 18:27: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 18:27: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 18:27: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 18:27: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 18:27: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 18:27:24 INFO - Downloading blobuploader-1.2.4.tar.gz 18:27:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:27:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:27: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 18:27: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 18:27: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 18:27: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 18:27: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 18:27: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 18:27:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:27:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:27:25 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 18:27:25 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 18:27:25 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 18:27:25 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 18:27:25 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 18:27:25 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 18:27:25 INFO - Downloading docopt-0.6.1.tar.gz 18:27:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:27:26 INFO - Installing collected packages: blobuploader, requests, docopt 18:27:26 INFO - Running setup.py install for blobuploader 18:27:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:27:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:27:26 INFO - Running setup.py install for requests 18:27:26 INFO - Running setup.py install for docopt 18:27:26 INFO - Successfully installed blobuploader requests docopt 18:27:26 INFO - Cleaning up... 18:27:27 INFO - Return code: 0 18:27:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:27:27 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')]} 18:27:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:27 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')]} 18:27:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:27 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 0x10c9c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f930bd6ce90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ed30>, '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 0x10c6c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c793be8>, '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.YIzAxkjSYc/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.w9nkTkU9PV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 18:27:27 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 18:27:27 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 18:27:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIzAxkjSYc/Render', 18:27:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:27 INFO - 'HOME': '/Users/cltbld', 18:27:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:27 INFO - 'LOGNAME': 'cltbld', 18:27:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:27 INFO - 'MOZ_NO_REMOTE': '1', 18:27:27 INFO - 'NO_EM_RESTART': '1', 18:27:27 INFO - 'PAGER': '/bin/cat', 18:27:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:27 INFO - 'PWD': '/builds/slave/test', 18:27:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:27 INFO - 'SHELL': '/bin/bash', 18:27:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9nkTkU9PV/Listeners', 18:27:27 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 18:27:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:27 INFO - 'USER': 'cltbld', 18:27:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:27 INFO - 'XPC_FLAGS': '0x0', 18:27:27 INFO - 'XPC_SERVICE_NAME': '0', 18:27:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:27:27 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-LeUQJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:27:27 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-0pJRq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:27:27 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-BKTv_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:27:27 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-zu4kxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:27:27 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Syhx5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:27:27 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-7zDSq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:27:27 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-yaEDly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:27:27 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-p9GwEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:27:28 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-NXLaFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:27:28 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-muXxPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:27:28 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-XWhjKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:27:28 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-_xYb5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:27:28 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-pJsPfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:27:28 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-5Ar1Hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:27:28 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-QvP_Ye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:27:28 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-TkoYvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:27:28 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-T_KSg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:27:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:27:29 INFO - Running setup.py install for manifestparser 18:27:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:27:29 INFO - Running setup.py install for mozcrash 18:27:29 INFO - Running setup.py install for mozdebug 18:27:29 INFO - Running setup.py install for mozdevice 18:27:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:27:29 INFO - Running setup.py install for mozfile 18:27:29 INFO - Running setup.py install for mozhttpd 18:27:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:27:29 INFO - Running setup.py install for mozinfo 18:27:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:27:30 INFO - Running setup.py install for mozInstall 18:27:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:27:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:27:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:27:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:27:30 INFO - Running setup.py install for mozleak 18:27:30 INFO - Running setup.py install for mozlog 18:27:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:27:30 INFO - Running setup.py install for moznetwork 18:27:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:27:30 INFO - Running setup.py install for mozprocess 18:27:30 INFO - Running setup.py install for mozprofile 18:27:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:27:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:27:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:27:30 INFO - Running setup.py install for mozrunner 18:27:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:27:31 INFO - Running setup.py install for mozscreenshot 18:27:31 INFO - Running setup.py install for moztest 18:27:31 INFO - Running setup.py install for mozversion 18:27:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:27:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:27:31 INFO - Cleaning up... 18:27:31 INFO - Return code: 0 18:27:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:27: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')]} 18:27:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27: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')]} 18:27:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:31 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 0x10c9c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f930bd6ce90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ed30>, '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 0x10c6c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c793be8>, '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.YIzAxkjSYc/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.w9nkTkU9PV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 18:27:31 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 18:27:31 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 18:27:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIzAxkjSYc/Render', 18:27:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:31 INFO - 'HOME': '/Users/cltbld', 18:27:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:31 INFO - 'LOGNAME': 'cltbld', 18:27:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:31 INFO - 'MOZ_NO_REMOTE': '1', 18:27:31 INFO - 'NO_EM_RESTART': '1', 18:27:31 INFO - 'PAGER': '/bin/cat', 18:27:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:31 INFO - 'PWD': '/builds/slave/test', 18:27:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:31 INFO - 'SHELL': '/bin/bash', 18:27:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9nkTkU9PV/Listeners', 18:27:31 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 18:27:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:31 INFO - 'USER': 'cltbld', 18:27:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:31 INFO - 'XPC_FLAGS': '0x0', 18:27:31 INFO - 'XPC_SERVICE_NAME': '0', 18:27:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:27:31 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-iAoPH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:27:31 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)) 18:27:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:27:31 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-i6k8Ox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 18:27:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:27:32 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Qipm7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:27:32 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)) 18:27:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:27:32 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-_tVpRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 18:27:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:27:32 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-v6m60V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:27:32 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)) 18:27:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:27:32 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-pL1mPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:27:32 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)) 18:27:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:27:32 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-j9tkIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:27:32 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)) 18:27:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:27:32 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-_buERk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:27:32 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)) 18:27:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:27:32 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-A5WfOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:27:32 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)) 18:27:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:27:32 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-6tYiUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:27:32 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)) 18:27:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:27:32 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-oF_Q47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:27:32 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)) 18:27:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:27:33 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-ikCOiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 18:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:27:33 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-3l5XjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:27:33 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)) 18:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:27:33 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-oHbEZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 18:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:27:33 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-sri6Pk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:27:33 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)) 18:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:27:33 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Y30f_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 18:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:27:33 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-drboFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:27:33 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)) 18:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:27:33 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)) 18:27:33 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:27:33 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:27:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:27:33 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 18:27:33 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 18:27:33 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 18:27:33 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 18:27:33 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 18:27:33 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 18:27:36 INFO - Downloading blessings-1.6.tar.gz 18:27:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:27:36 INFO - Installing collected packages: blessings 18:27:36 INFO - Running setup.py install for blessings 18:27:36 INFO - Successfully installed blessings 18:27:36 INFO - Cleaning up... 18:27:36 INFO - Return code: 0 18:27:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:27:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:27:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:27:36 INFO - Reading from file tmpfile_stdout 18:27:36 INFO - Current package versions: 18:27:36 INFO - blessings == 1.6 18:27:36 INFO - blobuploader == 1.2.4 18:27:36 INFO - docopt == 0.6.1 18:27:36 INFO - functools32 == 3.2.3-2 18:27:36 INFO - jsonschema == 2.5.1 18:27:36 INFO - manifestparser == 1.1 18:27:36 INFO - mozInstall == 1.12 18:27:36 INFO - mozcrash == 1.0 18:27:36 INFO - mozdebug == 0.1 18:27:36 INFO - mozdevice == 0.50 18:27:36 INFO - mozfile == 1.2 18:27:36 INFO - mozhttpd == 0.7 18:27:36 INFO - mozinfo == 0.9 18:27:36 INFO - mozleak == 0.1 18:27:36 INFO - mozlog == 3.4 18:27:36 INFO - moznetwork == 0.27 18:27:36 INFO - mozprocess == 0.25 18:27:36 INFO - mozprofile == 0.28 18:27:36 INFO - mozrunner == 6.13 18:27:36 INFO - mozscreenshot == 0.1 18:27:36 INFO - mozsystemmonitor == 0.3 18:27:36 INFO - moztest == 0.8 18:27:36 INFO - mozversion == 1.4 18:27:36 INFO - psutil == 3.1.1 18:27:36 INFO - requests == 1.2.3 18:27:36 INFO - wsgiref == 0.1.2 18:27:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:27:36 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')]} 18:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:36 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')]} 18:27:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:36 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 0x10c9c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f930bd6ce90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ed30>, '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 0x10c6c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c793be8>, '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.YIzAxkjSYc/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.w9nkTkU9PV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 18:27:36 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 18:27:36 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 18:27:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIzAxkjSYc/Render', 18:27:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:36 INFO - 'HOME': '/Users/cltbld', 18:27:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:36 INFO - 'LOGNAME': 'cltbld', 18:27:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:36 INFO - 'MOZ_NO_REMOTE': '1', 18:27:36 INFO - 'NO_EM_RESTART': '1', 18:27:36 INFO - 'PAGER': '/bin/cat', 18:27:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:36 INFO - 'PWD': '/builds/slave/test', 18:27:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:36 INFO - 'SHELL': '/bin/bash', 18:27:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9nkTkU9PV/Listeners', 18:27:36 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 18:27:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:36 INFO - 'USER': 'cltbld', 18:27:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:36 INFO - 'XPC_FLAGS': '0x0', 18:27:36 INFO - 'XPC_SERVICE_NAME': '0', 18:27:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:37 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)) 18:27:37 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)) 18:27:37 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)) 18:27:37 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)) 18:27:37 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)) 18:27:37 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)) 18:27:37 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)) 18:27:37 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)) 18:27:37 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)) 18:27:37 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)) 18:27:37 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)) 18:27:37 INFO - Cleaning up... 18:27:37 INFO - Return code: 0 18:27:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:27:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:37 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 0x10c9c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f930bd6ce90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c79ed30>, '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 0x10c6c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c793be8>, '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.YIzAxkjSYc/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.w9nkTkU9PV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 18:27:37 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 18:27:37 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 18:27:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIzAxkjSYc/Render', 18:27:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:37 INFO - 'HOME': '/Users/cltbld', 18:27:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:37 INFO - 'LOGNAME': 'cltbld', 18:27:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:37 INFO - 'MOZ_NO_REMOTE': '1', 18:27:37 INFO - 'NO_EM_RESTART': '1', 18:27:37 INFO - 'PAGER': '/bin/cat', 18:27:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:37 INFO - 'PWD': '/builds/slave/test', 18:27:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:37 INFO - 'SHELL': '/bin/bash', 18:27:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9nkTkU9PV/Listeners', 18:27:37 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 18:27:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:37 INFO - 'USER': 'cltbld', 18:27:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:37 INFO - 'XPC_FLAGS': '0x0', 18:27:37 INFO - 'XPC_SERVICE_NAME': '0', 18:27:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:27:37 INFO - Cleaning up... 18:27:37 INFO - Return code: 0 18:27:37 INFO - Running post-action listener: _resource_record_post_action 18:27:37 INFO - Running post-action listener: _start_resource_monitoring 18:27:37 INFO - Starting resource monitoring. 18:27:37 INFO - [mozharness: 2017-04-11 01:27:37.400763Z] Finished create-virtualenv step (success) 18:27:37 INFO - [mozharness: 2017-04-11 01:27:37.401681Z] Running install step. 18:27:37 INFO - Running pre-action listener: _resource_record_pre_action 18:27:37 INFO - Running main action method: install 18:27:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:27:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:27:37 INFO - Reading from file tmpfile_stdout 18:27:37 INFO - Detecting whether we're running mozinstall >=1.0... 18:27:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:27:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:27:37 INFO - Reading from file tmpfile_stdout 18:27:37 INFO - Output received: 18:27:37 INFO - Usage: mozinstall [options] installer 18:27:37 INFO - Options: 18:27:37 INFO - -h, --help show this help message and exit 18:27:37 INFO - -d DEST, --destination=DEST 18:27:37 INFO - Directory to install application into. [default: 18:27:37 INFO - "/builds/slave/test"] 18:27:37 INFO - --app=APP Application being installed. [default: firefox] 18:27:37 INFO - mkdir: /builds/slave/test/build/application 18:27:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:27:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:27:57 INFO - Reading from file tmpfile_stdout 18:27:57 INFO - Output received: 18:27:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:27:57 INFO - Running post-action listener: _resource_record_post_action 18:27:57 INFO - [mozharness: 2017-04-11 01:27:57.574377Z] Finished install step (success) 18:27:57 INFO - [mozharness: 2017-04-11 01:27:57.574539Z] Running run-tests step. 18:27:57 INFO - Running pre-action listener: _resource_record_pre_action 18:27:57 INFO - Running main action method: run_tests 18:27:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:27:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:27:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:27:57 INFO - 2017-04-10 18:27:57.589 screenresolution[1628:7352] starting screenresolution argv=screenresolution get 18:27:57 INFO - 2017-04-10 18:27:57.592 screenresolution[1628:7352] Display 0: 1600x1200x32@60 18:27:57 INFO - 2017-04-10 18:27:57.597 screenresolution[1629:7354] starting screenresolution argv=screenresolution list 18:27:57 INFO - Available Modes on Display 0 18:27:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:27:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:27:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:27:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:27:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:27:57 INFO - Intel Iris: 18:27:57 INFO - Chipset Model: Intel Iris 18:27:57 INFO - Type: GPU 18:27:57 INFO - Bus: Built-In 18:27:57 INFO - VRAM (Dynamic, Max): 1536 MB 18:27:57 INFO - Vendor: Intel (0x8086) 18:27:57 INFO - Device ID: 0x0a2e 18:27:57 INFO - Revision ID: 0x0009 18:27:57 INFO - Displays: 18:27:57 INFO - Display: 18:27:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:27:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:27:57 INFO - Display Serial Number: 1600x1200 60 18:27:57 INFO - Main Display: Yes 18:27:57 INFO - Mirror: Off 18:27:57 INFO - Online: Yes 18:27:57 INFO - Rotation: Supported 18:27:57 INFO - Return code: 0 18:27:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:27:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:27:57 INFO - Python 2.7.3 18:27:57 INFO - Return code: 0 18:27:57 INFO - grabbing minidump binary from tooltool 18:27:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:57 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 0x10c79ed30>, '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 0x10c6c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c793be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:27:57 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 18:27:57 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 18:27:57 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 18:27:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:27:57 INFO - Return code: 0 18:27:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:27:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:27:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:27:57 INFO - ENV: RUST_BACKTRACE is now 1 18:27:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:27:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:27:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--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 18:27:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0110 --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 18:27:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIzAxkjSYc/Render', 18:27:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:57 INFO - 'HOME': '/Users/cltbld', 18:27:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:57 INFO - 'LOGNAME': 'cltbld', 18:27:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:27:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:27:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:57 INFO - 'MOZ_NO_REMOTE': '1', 18:27:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:27:57 INFO - 'NO_EM_RESTART': '1', 18:27:57 INFO - 'PAGER': '/bin/cat', 18:27:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:57 INFO - 'PWD': '/builds/slave/test', 18:27:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:27:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:57 INFO - 'RUST_BACKTRACE': '1', 18:27:57 INFO - 'SHELL': '/bin/bash', 18:27:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w9nkTkU9PV/Listeners', 18:27:57 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 18:27:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:57 INFO - 'USER': 'cltbld', 18:27:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:57 INFO - 'XPC_FLAGS': '0x0', 18:27:57 INFO - 'XPC_SERVICE_NAME': '0', 18:27:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--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 18:27:58 INFO - mozversion application_buildid: 20170410113323 18:27:58 INFO - mozversion application_changeset: e516c0daef83fdd6542da4d74cabde0683533450 18:27:58 INFO - mozversion application_display_name: Nightly 18:27:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:27:58 INFO - mozversion application_name: Firefox 18:27:58 INFO - mozversion application_remotingname: firefox 18:27:58 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 18:27:58 INFO - mozversion application_vendor: Mozilla 18:27:58 INFO - mozversion application_version: 55.0a1 18:27:58 INFO - mozversion platform_buildid: 20170410113323 18:27:58 INFO - mozversion platform_changeset: e516c0daef83fdd6542da4d74cabde0683533450 18:27:58 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 18:27:58 INFO - mozversion platform_version: 55.0a1 18:27:58 INFO - using testdate: 1491874078 18:27:58 INFO - actual date: 1491874078 18:27:58 INFO - starting webserver on 'localhost:49243' 18:27:58 INFO - SUITE-START | Running 2 tests 18:27:58 INFO - TEST-START | dromaeo_css 18:27:58 INFO - operating with platform_type : mac_ 18:27:58 INFO - Initialising browser for dromaeo_css test... 18:27:58 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpOXugAk/profile http://localhost:49243/getInfo.html) 18:28:01 INFO - PID 1639 | __metrics Screen width/height:1600/1200 18:28:01 INFO - PID 1639 | colorDepth:24 18:28:01 INFO - PID 1639 | Browser inner width/height: 1024/689 18:28:01 INFO - PID 1639 | __metrics 18:28:01 INFO - PID 1639 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:28:01 INFO - PID 1639 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 304: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:28:02 INFO - TEST-INFO | 1639: exit 0 18:28:02 INFO - Browser initialized. 18:28:02 INFO - Running cycle 1/1 for dromaeo_css test... 18:28:02 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpOXugAk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:28:14 INFO - PID 1641 | RSS: Main: 240332800 18:28:14 INFO - PID 1641 | 18:31:20 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 18:31:21 INFO - PID 1641 | RSS: Main: 393023488 18:31:21 INFO - PID 1641 | 18:33:04 INFO - PID 1641 | ************************* 18:33:04 INFO - PID 1641 | A coding exception was thrown and uncaught in a Task. 18:33:04 INFO - PID 1641 | 18:33:04 ERROR - PID 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:33:04 INFO - PID 1641 | Full stack: 18:33:04 INFO - PID 1641 | ************************* 18:33:04 INFO - PID 1641 | ************************* 18:33:04 INFO - PID 1641 | A coding exception was thrown and uncaught in a Task. 18:33:04 INFO - PID 1641 | 18:33:04 ERROR - PID 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:33:04 INFO - PID 1641 | Full stack: 18:33:04 INFO - PID 1641 | ************************* 18:33:04 INFO - PID 1641 | ************************* 18:33:04 INFO - PID 1641 | A coding exception was thrown and uncaught in a Task. 18:33:04 INFO - PID 1641 | 18:33:04 ERROR - PID 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:33:04 INFO - PID 1641 | Full stack: 18:33:04 INFO - PID 1641 | ************************* 18:33:04 INFO - PID 1641 | ************************* 18:33:04 INFO - PID 1641 | A coding exception was thrown and uncaught in a Task. 18:33:04 INFO - PID 1641 | 18:33:04 ERROR - PID 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:33:04 INFO - PID 1641 | Full stack: 18:33:04 INFO - PID 1641 | ************************* 18:34:24 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 18:34:24 INFO - PID 1641 | RSS: Main: 373268480 18:34:24 INFO - PID 1641 | 18:37:27 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 18:37:27 INFO - PID 1641 | RSS: Main: 349908992 18:37:27 INFO - PID 1641 | 18:40:30 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 18:40:31 INFO - PID 1641 | RSS: Main: 327811072 18:40:31 INFO - PID 1641 | 18:43:34 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 18:43:35 INFO - PID 1641 | RSS: Main: 336277504 18:43:35 INFO - PID 1641 | 18:46:37 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 18:46:37 INFO - PID 1641 | RSS: Main: 378454016 18:46:37 INFO - PID 1641 | 18:46:37 INFO - PID 1641 | __start_tp_report 18:46:37 INFO - PID 1641 | _x_x_mozilla_page_load 18:46:37 INFO - PID 1641 | _x_x_mozilla_page_load_details 18:46:37 INFO - PID 1641 | |i|pagename|runs| 18:46:37 INFO - PID 1641 | |0;dojo.html;2794.9099851058086;3243.172990887324;3439.5700537432836;3500.3874321993644;3531.0466174132985;5818.3308919474275;6025.7288422021;6081.179040829492;6086.817395478142;6094.573379863411;5899.056151015828;5974.432428919256;5976.760929562824;5985.132155837412;5985.610935289204;5223.085959956992;5250.107481728115;5251.054810134175;5300.257963885059;5331.333583302087;4708.387909571768;5083.923741143886;5342.091844386464;5342.732863356836;5342.893142137155;5337.626366154371;5352.116900711398;5352.732363381835;5354.518093371596;5357.142857142858;5331.840044798662;5341.4925582069645;5348.5453736432255;5361.035013697533;5372.650777699476;5180.663256888291;5281.577473802125;5286.3392075990505;5296.099663057251;5304.098303288451;6183;6193.907091393633;6194.225721784777;6194.5044396448175;6206.069089636546;6248.812535623939;6325.114483972247;6468.06213099098;6513.576617519846;6516.218053833571;6398;6449;6507.967460162717;6551.80344589663;6558.2458017328145;6229.937700622981;6231.096491008823;6257.155284036688;6265.4361107500545;6268.78059267928;6094.573379863456;6736.730530778714;6758.898616520756;6759.2226893906845;6765.019072234597;5126.743662816844;6212.627242365473;6279.686015699197;6290.905636415458;6304.810855674337;6516.902246466307;6526.314736952647;6527.706253218616;6537;6545.14913061299;6378.075179099004;6409.967950160312;6426.389492998158;6430.582012169194;6473.643949582724;6009.849753756191;6227.813165605039;6337.366263373718;6483.222013358427;6492.448141907897;4434.733915965053;5109;5254.6847189168775;5288.550473209743;5290.206469029599;4574.245249533789;4627.259638457831;4673.485916549177;4675.22858728306;4696.718196908197;6255.374462553708;6258.342873998165;6259.937400626027;6293.433590976742;6300.621962682254;6331.746730130835;6567.737290508422;6603.075569420285;6620.569662971891;6633.834154146185;6127.658725805958;6138.878900475427;6144.07838824171;6196.783112591038;6204.193454850931;5719.456651618089;5726.541876649858;5737.827865164052;5744.4829965303325;5774.595778295479;6649.60102393858;6691.130153080166;6700.363465470772;6704.865902682017;6715.059891615276;5426.608045592803;6228.314885362516;6325.557210995186;6359.8092057238355;6361.491080713625;6468.5472016958365;6478.70845811949;6495.967520162369;6496.642684652389;6510.707018184288;6362.45919096882;6373.553851230555;6389.977603583405;6395.20059992501;6453.032045193258;6430.903536447051;6445.1943507061615;6457.644829534421;6491.123698300669;6513.674316284261;6320.6839658016;6374.235091789015;6391.904121438275;6392.744290228339;6395.976643737165;6226.5641405101205;6289.371062893674;6367.140436041261;6377.266614339411;6381.968090159519;6252.187215662117;6316.936830631635;6348.111264422892;6354;6354.555181137276;6361.427471527585;6366.808995730135;6369.777057802955;6376.649284289409;6381.968090159705;6076.605020673642;6307.936920630735;6311.747530098744;6325.905111423241;6329.588576742497 18:46:37 INFO - PID 1641 | |1;ext.html;745.2659130756205;783.4202690009392;801.9117897031325;1118.5861231344404;1119.832025196221;27395;27977.160685179453;28043.15870523885;36676.44985325222;36949.81525092378;38779;38943.61056389442;38951.83144505669;38976;38996.02509937253;8452.15478452153;8635.481871087723;8635.69775057873;8643.17889800468;8648.870266945969;6129.969350153243;6263.185785847856;8469.364797640172;8571.914280857205;8670;8699.130086991281;8713.477191368507;8744.519051452167;8754.212120909116;8761.342899282547;8640.783980400478;8704.086070962585;8716.912830871674;8722.34582406322;8722.86915696265;8509.021462531826;8559.828803423896;8568.014678311949;8569.400141990063;8569.914300856974;36947;37091.629083709086;37170.81414592937;37177.628223717686;37221.441678374846;3547.5388199534154;3576.982115089434;3580.606133325332;3582.1223800168864;3584.4981702561663;30738.23154421135;30761.230969225726;30825.92109276187;30857.228569285722;30906.690933090606;3124.4376012317775;3146.0561831450473;3166.6358368787655;3190.5054716518976;3203.871845126192;252726.73636631883;258559;258647;259178.40821591733;261488.3851161502;48921;48984;49058.50941490539;49089.263661045115;49091.99999999929;50091;50137.74931125321;50219;50246;50249.74875125601;13872.375743091383;14016.789748153786;14020.228887411291;14025.649358765908;14030.877529797592;11210.551577936954;11256.493457794255;11258.155638327158;11428.314301141792;11518.136139789549;10452.529636166391;10626.89373106259;12082.818757718642;12200.694982625506;12309.507619695112;4282.721623094489;5149.867029253558;5150.892558099951;5157.690538567623;5158.638895277295;975.2393133355994;988.461288597716;996.6097294274447;1001.9398836069859;1029.552144816992;21274.893625532084;21677.891610542163;23627.054917803096;23976;24041.879790600935;944.8110377924305;1097.9780404391868;1099.6041425086962;1130.265327537091;1149.976520896403;12207.572734954412;12354.444050017772;12358.938205309096;12359;12391.504339826486;306766;308246;308646;308932;309181;53042;53391.19913201305;53397.1990420144;53474.93050139053;53614;1486.2494440307576;1486.8661820435996;1521.62720133568;1524.41310095614;1531.0430980637102;22206.77793222047;23524.88237558801;23594.52810943806;23742.287731367396;23777.881110594335;37562.81218593889;37601;37641.81179094087;37692;37695.623043769214;301377;301734;302711;303210.4839475789;303651.48174258985;31825.226216067775;34755;34787.9563613092;34827.60689572389;34874.30251394907;41118.383224252255;41245.793771030956;41252.58747412488;41339.1732165361;41472.58527414687;27443.267474017222;28375.858120709265;28914.27714307159;29396.118116456542;29814.85092574523;27802.304942376602;28443.715562844107;28656.856715716287;28969.986050488977;29431.85284073566;33337.166570834954;33609;34473.310533789685;34532;34878.9536313911;22331.553368932855;28537.571936421387;29215;29408.411831762816;29566.817327307526;20697;22320.140923085564;22768.29237807138;22881;23039.6544051836 18:46:37 INFO - PID 1641 | |2;jquery.html;384.2945662535748;426.6542549065239;433.24831417490657;435.49917594766015;454.43422938441626;17633.64732705345;21515.139394424223;22213.777862221414;22944.196953106646;23261.767382326172;22043.779562204374;22391.440213994618;23248.41878953027;23594;23778.5244295114;5039;6011.458968692817;6250.0624906263965;6289.088082228074;6361.522885783562;3728.9254214915686;3939.2909276330247;6360.618362898218;6402.93597064028;6408.263049749292;6099.176611157482;6322.209723784527;6342.016987366966;6345.9365406345805;6355.173827402455;5450.400455949843;6128.908066379008;6275.6548389838545;6332.335104813974;6335.334789847046;5547.750351234205;5616.775328986836;5644.153376993443;5644.520215781664;5651.86962607476;24144.879275603686;27278.318042048908;27569.724302757117;27922.581161282596;29054.418911621648;2207.381933058746;2237.944051398728;2270.4437412833795;2274.8862556872255;2275.795178433949;17646;17811.554711132198;18468.984205868674;18616.720749188775;18678.06609669511;1897.9810201897942;2091.905864236113;2134.541073669169;2167.1548096242477;2189.9671504927437;155236.2238188802;156160;158390;159048.20475897662;167047.3295267056;11624.360660163773;11946.223495472766;12111.576094836639;12317.384130793425;12543.874561254454;14959.92520037393;16017.279222435018;16537.421190258283;16563.08903010322;16648.916755416147;7416.814579635552;9059.094090590888;9157.267418606496;9301.90698093024;9315;8627.309815214769;8659.09079546651;8681.609327580274;8712.869306960274;9074.954625226832;6276.4622667446765;6528.706208220547;7696.576688282086;7717.65270562826;7839.882401763978;1827.0955876841049;2118.69878321331;2148.2051640892655;2150.2474134052954;2317.5944209763225;897.5691667999397;925.6019911438011;930.1721467893581;961.7355227312545;995.6963126246565;20291.28980485676;21062.368128956157;21068.946552672303;21210.893945530483;21240.468988275417;968.5544649461327;1004.467632154959;1009.4756917055299;1010.5957616953277;1014.3406785589426;10635;10930.56277748897;10940.835887461695;10952.726181845359;10980.450977451095;688415;699008;702095;703043;739234;155042;160491.19754401152;163298;164414;165919.68160636962;1024.3751311699857;1174.7826652069396;1227.147132742753;1230.1450491908217;1234.5740719451774;17961.820381796013;20446.591068178852;21853.47025708249;22229.777362244586;22334.553308934057;42328.78835605802;44107.338389923534;64469;64620;66284.66857665681;70827;88095.55952220198;88786;89234;89933.55033224792;7758;8733.126687331216;8787.604557795043;9269.397489163182;9308.860367094634;11918.880811191777;14188.716225675222;14481.927590362402;14856.925715371353;14866.554003379915;9234.03042680509;10323.48382580883;11961.342126183143;12111.81832272499;12417.689557761129;11333.320000799979;11523.53905843757;11530.884691152982;11632.476538555617;11985;12092;12163.513459461876;12291.44688489038;12461.1900226485;12591.87408125907;9020.639174432948;9026.368154229142;9101.40840845343;9114.316426267924;9122.407043542149;10378.65077539915;11877.406129693654;12197.573084941985;12275.815862762245;12311.44598493052 18:46:37 INFO - PID 1641 | |3;mootools.html;1193.2661413230862;1298.2729671384022;1340.5978206538043;1407.1627381707892;1411.4777532313046;11319.490622921967;11814.881851181486;11821.40892955352;11826.58606948757;11844.466998985044;11426.942865285662;11525.827112593297;11836.053115750741;11924.582639607615;11926.582569610066;2502.6246063090543;2546.8599227042505;2549.8087643426725;2549.8980040798347;2550.8979640814346;2453.8405003674793;2459.667944827452;2515.069424313007;2519.949601007979;2521.621756736486;2141.7001619773246;2431.57447446697;2533.3033415810614;2534.8986040558357;2536.695596528419;2482.094035676983;2506.4360518883286;2514.6856642919633;2537.6320433537126;2541.720410754834;2494.0896572751;2507.5737124688662;2512.6984761828517;2517.1315896015844;2520.558902192109;11467.885321146765;11674.824877626843;11690;11691.532338706527;11709.765804683944;6498.187726534184;6759.4930380221185;6761.154855643044;6769.932300676979;6788.185417749853;4177.668985987719;4311;4328.567143285678;4367.301231802896;4442.755648439334;1237.4617041586848;1241.3296819717432;1274.8653698208602;1300.180886041797;1300.4213125159308;14912.105273683614;15346.539603811792;16385.67228655432;16486.620242850186;16497.422590209284;5024.773885175176;5549.916751248735;5738.655680659173;5740.789795928597;5759.798407055733;11892.405379731152;11917.821232681517;11922.940385298018;11928.045756339645;11928.284302941851;5744.971275143597;5939;6572.901406478906;6601.53789234749;6602.86794264112;5199.4020687621405;5257.842264732064;6097.634141951497;6221.440070393686;6314.968425157845;4362.062156636339;4416.712913660602;4424.491183513873;4460.732356058582;4505;1291.6770807298176;1291.819145319656;1293.1529847949607;1313.7044165062975;1319.7822359310605;692.8498692170972;766.5400759544341;768.7924260449759;773.477902415867;784.9450538462253;5921.881562368814;5978.820635380858;5988.730507127189;6222.782202622977;6321.462675672527;580.8751118509541;594.5184400635499;597.1162679234757;602.9087351893088;605.9515238780975;3250.6261779895613;3959.1883663848844;4137.089840235143;4256.722983105056;4318.632916202158;13831.654208644864;14761.114333140045;15112.168830714416;15382.615434614225;15498.690026199412;14835.18406487632;15086.773698394532;15215.923920380328;15266.694666106616;15294.617634559225;9083.45499270046;10811.135109191246;10873.456327183609;11067.114630829514;11067.391293478937;6398.328175541501;6558.967205163944;6578.046183303299;6593.175853018372;6758.628275444898;13445.529406470727;13494.122882012638;13783.104098233583;13787;13804.102733322703;11286;11386.829197561878;12028.75942481128;13645.795313070113;14237.430502779773;8022.7163653815105;8357.749267521984;8458.323334133378;8543.359248056297;8576.4425312357;9277.953610231905;9550.379225350607;10217.33587316822;10547.156227501628;10563.94718026405;8805.823883522422;9056.501892395981;9063.909360906306;9081.273498120267;9121.817563648823;8807.647694092164;8817.030126685931;8870;8870.822583548164;8931.017588065177;7663;7781.727639532589;7957.403194760301;7965.8406831864195;7968.043834739637;5390.757415916215;5841.211436456024;5942.881142377042;6113.969430152821;6117.632942023492;5883;5987.13186587951;6037.09443583466;6053.061775424816;6107.725152368065 18:46:37 INFO - PID 1641 | |4;prototype.html;314.9842507874606;389.1691239204298;397.253164051583;397.4892263441475;401.6465510350889;5000.949800541886;5632.380438151805;5653.406392328808;5680.204771332017;5707.172459993299;4601.608863246628;4683.998317955817;5323.707196104213;5577.69322687252;5606.467385598372;3868.4197370394454;4126.236646220459;4312.611864932155;4336.392904993303;4337.761423121727;3540.6813386795184;3542.3092496963104;3651.981740091309;4100.8722601284735;4331.267493002808;4272.145570885819;4296.656267498593;4302.741835489865;4318.870433886988;4323.956760432387;3416.231347946717;3608.657177568127;3916.9020774480723;4099.221147981874;4135.400366946776;2995.5207166853197;3042.39152169567;3562.6615471530163;3602.3155600436094;3636.6181550937035;5364.892702145974;5382.811601593926;5398.433164517708;5413.566914646819;5416.508406133184;4257.233697934371;4699.4022032508765;4830.468648448667;4849.532584202312;4884.535969082932;4759.048190361941;5054.595632349403;5833.912491312633;5853.619514731571;5912.379200184005;3612.71098312137;3844.7896006834326;4057.2494088593703;4066.6340029397497;4066.8983275418054;5220.7128607926925;5820.497186209928;6091.695415229221;6162.506999440034;6234.345393733638;4111;4126.452580282394;4128.690348223895;4129.359949207877;4142.46148000758;3160.862089647725;4058.817353219112;4190.455240818735;4193.769342686121;4203.936940945827;4663.603593694506;4836.806527738921;4846.418429788448;4847.2729090636685;4862.80548778052;5597.216389705444;5796.681182534917;5840.766369345263;5877.52979761618;5908.645481271052;1112.9927415688815;1402.6843960109097;1417.3976060174384;1426.2440906319666;1429.9857001430062;899.4917871402637;949.1979277510493;962.6967505235968;970.2674480766975;973.6543527047937;4692.648051396159;5365.839024829262;5540.778368865281;5607.80372686954;5638.210650508932;5570.3315602128005;6078.726457309432;6108.694565271719;6129.927262729005;6131.908021379683;4972.1851524194135;5398.541124004503;5618.69097199658;5637.351704553947;5770.88458230833;3023.0628505163468;3192.840357982138;3209.0372888133465;3211.871525138968;3231.30526936705;5772.740226689725;5999.610025348338;6255.186825712628;6313.349552150799;6327.557071005078;6041.728122234422;6225.221847269091;6292.559520833498;6368.395002474757;6370.757702248017;4759.381280433521;6125.050617154348;6185.8144255671505;6196.411340922605;6391.93608063932;6086.555758738221;6192.628442293477;6196.194494715658;6199.876002480015;6202.34875338101;5268.550124192269;5980.43185897339;6059.484943779828;6083.7870674526175;6125.264968203844;6336.398042185985;6347.635258419277;6366.64983425916;6372.840678983062;6379;4011.5386730525197;4118.155778065429;4147.04617937882;4149.128682976609;4172.91654166921;4537.024539723975;5879.794207202727;5914.911276330772;6143.232095988002;6179.876402472015;5230.137027390438;5636.633618814764;5952.375000625046;5971.253593300838;6036.348009454217;5768.653880767168;5814.418558144152;5847.327557330961;5934.376890426443;5936.168936349001;5781.710914454345;5813.82558523245;5815;5818.8254352369495;5841.8024305016515;4902.950970490249;5663.065594177079;5941.346451890202;5987.790427335022;5995.5803093783015;4344.261475549228;5206.114960456655;5775.220345253505;5797;5894.440028197314 18:46:37 INFO - PID 1641 | |5;yui.html;712.2805965974364;864.930805535557;876.163264082801;877.341993504871;896.6234181643711;13168.868311316895;17498.125093745308;17625.82374176258;18195.363162289323;18205.998670073142;16894.324227030935;17352;17364.56588585286;17496.125193740325;17558.473245802616;961.4808003678005;984.8621193032981;989.5547003848297;993.3493561717087;993.4287784523891;644.7936660268716;655.8557117434157;951.5813042261417;1008.1330056151704;1014.1430491234895;1053.0417320238585;1054.72577129946;1061.2730279758352;1063.7393838509577;1064.249703958711;1036.0934182590233;1036.7459972306758;1037.5953378182514;1037.8598889149944;1047.010575006619;772.9497582657109;773.1842905734447;774.0556521044317;775.3835700618022;783.3576467296818;14885.032472889336;15331.23343832815;17087;17185.39851105205;17288;13957.162570245819;16014.439494617633;16146.677066458722;16281.348746050144;16409.507714768577;15430.845691543052;15604.063756174552;15816.683666326724;15852.920735396365;15907.522774316789;5286.471352864722;5291.1534154535475;5299.973500132513;5309.893802123975;5310.840674779763;107424.46287768512;107743;108529.45735271274;108984;109067.9999999992;11462.140339474572;11814.05487560993;12914.741705166032;12999.1550549214;13107.213567186;19155.233790648217;19796.406107816787;19808.504787380432;25068.48493331464;25153.874230629095;8884.37809353352;9076.137766912132;9095.999440061589;9099.272058235325;9120.771980700536;4151.7924103794685;4683.906321873612;4808.807647694053;4862.41651001882;4872.19608764557;4300.935485967713;4393.143337049245;4403.3174857897075;4447.510773814878;4457.821687132543;903.4830212630849;912.9543522823832;916.5050872528893;924.5977999570074;925.5881132895797;638.6200210874522;651.7914267434376;659.2682122843639;663.5189487621436;681.2540268406087;11010.559577616967;11094.778104437866;11483.655490335303;11647.88352116468;12022.458989345325;620.996895015522;649.5518092516148;659.4197106546209;681.4412182010703;687.4534744877834;4654.697444666086;4938.802447902115;5106.693598384109;5435.782568697208;5454.53636440899;84022;105129;105349.89300213952;107145;107787.46106269419;72806;74425;77495;83698;85098;74722;75530;75953.6202318996;76256.85614715784;78567.60716196382;11312.321260724382;11653.53385864556;11720.589779357682;11729;11982.820257695967;39771;41188.55840045584;41234.38148427716;41731.58268417277;41859.581404186785;50641;51504.48495514997;52263.73868130635;52463;53312.66718332073;15908.68182636364;15951.042937423792;16068.758968615713;16113.516594502184;16139.354425822836;17753.378631748343;17884.91057544704;17885.926844389378;18450.446486605422;18644.254229830654;15598.532044038697;16067.919660401623;16307.918460407622;16447.25987330549;16559.503214903572;13324.933375333449;16980.745288820926;17234.827651723823;17270.654586907942;17335.913320433316;15180.620484487976;15526.301316441013;15572.844271557593;15729.606759831096;15910;11707.180497365103;11946.522139114339;12071.215371000857;12097.455614497545;12127.332996684914;12289.064020722966;12395.256284622952;12410.193337433038;12449.688757781129;12624.431900564681 18:46:37 INFO - PID 1641 | __end_tp_report 18:46:37 INFO - PID 1641 | __start_cc_report 18:46:37 INFO - PID 1641 | _x_x_mozilla_cycle_collect,1593 18:46:37 INFO - PID 1641 | __end_cc_report 18:46:37 INFO - PID 1641 | __startTimestamp1491875197885__endTimestamp 18:46:37 INFO - PID 1641 | 18:46:37 INFO - PID 1641 | ------- Summary: start ------- 18:46:37 INFO - PID 1641 | Number of tests: 6 18:46:37 INFO - PID 1641 | 18:46:37 INFO - PID 1641 | [#0] dojo.html Cycles:165 Average:5978.33 Median:6265.44 stddev:718.19 (11.5%) stddev-sans-first:675.58 18:46:37 INFO - PID 1641 | Values: 2794.9 3243.2 3439.6 3500.4 3531.0 5818.3 6025.7 6081.2 6086.8 6094.6 5899.1 5974.4 5976.8 5985.1 5985.6 5223.1 5250.1 5251.1 5300.3 5331.3 4708.4 5083.9 5342.1 5342.7 5342.9 5337.6 5352.1 5352.7 5354.5 5357.1 5331.8 5341.5 5348.5 5361.0 5372.7 5180.7 5281.6 5286.3 5296.1 5304.1 6183.0 6193.9 6194.2 6194.5 6206.1 6248.8 6325.1 6468.1 6513.6 6516.2 6398.0 6449.0 6508.0 6551.8 6558.2 6229.9 6231.1 6257.2 6265.4 6268.8 6094.6 6736.7 6758.9 6759.2 6765.0 5126.7 6212.6 6279.7 6290.9 6304.8 6516.9 6526.3 6527.7 6537.0 6545.1 6378.1 6410.0 6426.4 6430.6 6473.6 6009.8 6227.8 6337.4 6483.2 6492.4 4434.7 5109.0 5254.7 5288.6 5290.2 4574.2 4627.3 4673.5 4675.2 4696.7 6255.4 6258.3 6259.9 6293.4 6300.6 6331.7 6567.7 6603.1 6620.6 6633.8 6127.7 6138.9 6144.1 6196.8 6204.2 5719.5 5726.5 5737.8 5744.5 5774.6 6649.6 6691.1 6700.4 6704.9 6715.1 5426.6 6228.3 6325.6 6359.8 6361.5 6468.5 6478.7 6496.0 6496.6 6510.7 6362.5 6373.6 6390.0 6395.2 6453.0 6430.9 6445.2 6457.6 6491.1 6513.7 6320.7 6374.2 6391.9 6392.7 6396.0 6226.6 6289.4 6367.1 6377.3 6382.0 6252.2 6316.9 6348.1 6354.0 6354.6 6361.4 6366.8 6369.8 6376.6 6382.0 6076.6 6307.9 6311.7 6325.9 6329.6 18:46:37 INFO - PID 1641 | 18:46:37 INFO - PID 1641 | [#1] ext.html Cycles:180 Average:43575.68 Median:3197.19 stddev:76223.43 (2384.1%) stddev-sans-first:76369.42 18:46:37 INFO - PID 1641 | Values: 745.3 783.4 801.9 1118.6 1119.8 27395.0 27977.2 28043.2 36676.4 36949.8 38779.0 38943.6 38951.8 38976.0 38996.0 8452.2 8635.5 8635.7 8643.2 8648.9 6130.0 6263.2 8469.4 8571.9 8670.0 8699.1 8713.5 8744.5 8754.2 8761.3 8640.8 8704.1 8716.9 8722.3 8722.9 8509.0 8559.8 8568.0 8569.4 8569.9 36947.0 37091.6 37170.8 37177.6 37221.4 3547.5 3577.0 3580.6 3582.1 3584.5 30738.2 30761.2 30825.9 30857.2 30906.7 3124.4 3146.1 3166.6 3190.5 3203.9 252726.7 258559.0 258647.0 259178.4 261488.4 48921.0 48984.0 49058.5 49089.3 49092.0 50091.0 50137.7 50219.0 50246.0 50249.7 13872.4 14016.8 14020.2 14025.6 14030.9 11210.6 11256.5 11258.2 11428.3 11518.1 10452.5 10626.9 12082.8 12200.7 12309.5 4282.7 5149.9 5150.9 5157.7 5158.6 975.2 988.5 996.6 1001.9 1029.6 21274.9 21677.9 23627.1 23976.0 24041.9 944.8 1098.0 1099.6 1130.3 1150.0 12207.6 12354.4 12358.9 12359.0 12391.5 306766.0 308246.0 308646.0 308932.0 309181.0 53042.0 53391.2 53397.2 53474.9 53614.0 1486.2 1486.9 1521.6 1524.4 1531.0 22206.8 23524.9 23594.5 23742.3 23777.9 37562.8 37601.0 37641.8 37692.0 37695.6 301377.0 301734.0 302711.0 303210.5 303651.5 31825.2 34755.0 34788.0 34827.6 34874.3 41118.4 41245.8 41252.6 41339.2 41472.6 27443.3 28375.9 28914.3 29396.1 29814.9 27802.3 28443.7 28656.9 28970.0 29431.9 33337.2 33609.0 34473.3 34532.0 34879.0 22331.6 28537.6 29215.0 29408.4 29566.8 20697.0 22320.1 22768.3 22881.0 23039.7 18:46:37 INFO - PID 1641 | 18:46:37 INFO - PID 1641 | [#2] jquery.html Cycles:180 Average:41200.03 Median:2275.34 stddev:118783.37 (5220.5%) stddev-sans-first:119077.05 18:46:37 INFO - PID 1641 | Values: 384.3 426.7 433.2 435.5 454.4 17633.6 21515.1 22213.8 22944.2 23261.8 22043.8 22391.4 23248.4 23594.0 23778.5 5039.0 6011.5 6250.1 6289.1 6361.5 3728.9 3939.3 6360.6 6402.9 6408.3 6099.2 6322.2 6342.0 6345.9 6355.2 5450.4 6128.9 6275.7 6332.3 6335.3 5547.8 5616.8 5644.2 5644.5 5651.9 24144.9 27278.3 27569.7 27922.6 29054.4 2207.4 2237.9 2270.4 2274.9 2275.8 17646.0 17811.6 18469.0 18616.7 18678.1 1898.0 2091.9 2134.5 2167.2 2190.0 155236.2 156160.0 158390.0 159048.2 167047.3 11624.4 11946.2 12111.6 12317.4 12543.9 14959.9 16017.3 16537.4 16563.1 16648.9 7416.8 9059.1 9157.3 9301.9 9315.0 8627.3 8659.1 8681.6 8712.9 9075.0 6276.5 6528.7 7696.6 7717.7 7839.9 1827.1 2118.7 2148.2 2150.2 2317.6 897.6 925.6 930.2 961.7 995.7 20291.3 21062.4 21068.9 21210.9 21240.5 968.6 1004.5 1009.5 1010.6 1014.3 10635.0 10930.6 10940.8 10952.7 10980.5 688415.0 699008.0 702095.0 703043.0 739234.0 155042.0 160491.2 163298.0 164414.0 165919.7 1024.4 1174.8 1227.1 1230.1 1234.6 17961.8 20446.6 21853.5 22229.8 22334.6 42328.8 44107.3 64469.0 64620.0 66284.7 70827.0 88095.6 88786.0 89234.0 89933.6 7758.0 8733.1 8787.6 9269.4 9308.9 11918.9 14188.7 14481.9 14856.9 14866.6 9234.0 10323.5 11961.3 12111.8 12417.7 11333.3 11523.5 11530.9 11632.5 11985.0 12092.0 12163.5 12291.4 12461.2 12591.9 9020.6 9026.4 9101.4 9114.3 9122.4 10378.7 11877.4 12197.6 12275.8 12311.4 18:46:37 INFO - PID 1641 | 18:46:37 INFO - PID 1641 | [#3] mootools.html Cycles:180 Average:7038.37 Median:2550.40 stddev:4481.54 (175.7%) stddev-sans-first:4472.58 18:46:37 INFO - PID 1641 | Values: 1193.3 1298.3 1340.6 1407.2 1411.5 11319.5 11814.9 11821.4 11826.6 11844.5 11426.9 11525.8 11836.1 11924.6 11926.6 2502.6 2546.9 2549.8 2549.9 2550.9 2453.8 2459.7 2515.1 2519.9 2521.6 2141.7 2431.6 2533.3 2534.9 2536.7 2482.1 2506.4 2514.7 2537.6 2541.7 2494.1 2507.6 2512.7 2517.1 2520.6 11467.9 11674.8 11690.0 11691.5 11709.8 6498.2 6759.5 6761.2 6769.9 6788.2 4177.7 4311.0 4328.6 4367.3 4442.8 1237.5 1241.3 1274.9 1300.2 1300.4 14912.1 15346.5 16385.7 16486.6 16497.4 5024.8 5549.9 5738.7 5740.8 5759.8 11892.4 11917.8 11922.9 11928.0 11928.3 5745.0 5939.0 6572.9 6601.5 6602.9 5199.4 5257.8 6097.6 6221.4 6315.0 4362.1 4416.7 4424.5 4460.7 4505.0 1291.7 1291.8 1293.2 1313.7 1319.8 692.8 766.5 768.8 773.5 784.9 5921.9 5978.8 5988.7 6222.8 6321.5 580.9 594.5 597.1 602.9 606.0 3250.6 3959.2 4137.1 4256.7 4318.6 13831.7 14761.1 15112.2 15382.6 15498.7 14835.2 15086.8 15215.9 15266.7 15294.6 9083.5 10811.1 10873.5 11067.1 11067.4 6398.3 6559.0 6578.0 6593.2 6758.6 13445.5 13494.1 13783.1 13787.0 13804.1 11286.0 11386.8 12028.8 13645.8 14237.4 8022.7 8357.7 8458.3 8543.4 8576.4 9278.0 9550.4 10217.3 10547.2 10563.9 8805.8 9056.5 9063.9 9081.3 9121.8 8807.6 8817.0 8870.0 8870.8 8931.0 7663.0 7781.7 7957.4 7965.8 7968.0 5390.8 5841.2 5942.9 6114.0 6117.6 5883.0 5987.1 6037.1 6053.1 6107.7 18:46:37 INFO - PID 1641 | 18:46:37 INFO - PID 1641 | [#4] prototype.html Cycles:180 Average:4783.99 Median:5406.05 stddev:1499.76 (27.7%) stddev-sans-first:1465.97 18:46:37 INFO - PID 1641 | Values: 315.0 389.2 397.3 397.5 401.6 5000.9 5632.4 5653.4 5680.2 5707.2 4601.6 4684.0 5323.7 5577.7 5606.5 3868.4 4126.2 4312.6 4336.4 4337.8 3540.7 3542.3 3652.0 4100.9 4331.3 4272.1 4296.7 4302.7 4318.9 4324.0 3416.2 3608.7 3916.9 4099.2 4135.4 2995.5 3042.4 3562.7 3602.3 3636.6 5364.9 5382.8 5398.4 5413.6 5416.5 4257.2 4699.4 4830.5 4849.5 4884.5 4759.0 5054.6 5833.9 5853.6 5912.4 3612.7 3844.8 4057.2 4066.6 4066.9 5220.7 5820.5 6091.7 6162.5 6234.3 4111.0 4126.5 4128.7 4129.4 4142.5 3160.9 4058.8 4190.5 4193.8 4203.9 4663.6 4836.8 4846.4 4847.3 4862.8 5597.2 5796.7 5840.8 5877.5 5908.6 1113.0 1402.7 1417.4 1426.2 1430.0 899.5 949.2 962.7 970.3 973.7 4692.6 5365.8 5540.8 5607.8 5638.2 5570.3 6078.7 6108.7 6129.9 6131.9 4972.2 5398.5 5618.7 5637.4 5770.9 3023.1 3192.8 3209.0 3211.9 3231.3 5772.7 5999.6 6255.2 6313.3 6327.6 6041.7 6225.2 6292.6 6368.4 6370.8 4759.4 6125.1 6185.8 6196.4 6391.9 6086.6 6192.6 6196.2 6199.9 6202.3 5268.6 5980.4 6059.5 6083.8 6125.3 6336.4 6347.6 6366.6 6372.8 6379.0 4011.5 4118.2 4147.0 4149.1 4172.9 4537.0 5879.8 5914.9 6143.2 6179.9 5230.1 5636.6 5952.4 5971.3 6036.3 5768.7 5814.4 5847.3 5934.4 5936.2 5781.7 5813.8 5815.0 5818.8 5841.8 4903.0 5663.1 5941.3 5987.8 5995.6 4344.3 5206.1 5775.2 5797.0 5894.4 18:46:37 INFO - PID 1641 | 18:46:37 INFO - PID 1641 | [#5] yui.html Cycles:180 Average:20582.69 Median:17885.42 stddev:27710.42 (154.9%) stddev-sans-first:27747.98 18:46:37 INFO - PID 1641 | Values: 712.3 864.9 876.2 877.3 896.6 13168.9 17498.1 17625.8 18195.4 18206.0 16894.3 17352.0 17364.6 17496.1 17558.5 961.5 984.9 989.6 993.3 993.4 644.8 655.9 951.6 1008.1 1014.1 1053.0 1054.7 1061.3 1063.7 1064.2 1036.1 1036.7 1037.6 1037.9 1047.0 772.9 773.2 774.1 775.4 783.4 14885.0 15331.2 17087.0 17185.4 17288.0 13957.2 16014.4 16146.7 16281.3 16409.5 15430.8 15604.1 15816.7 15852.9 15907.5 5286.5 5291.2 5300.0 5309.9 5310.8 107424.5 107743.0 108529.5 108984.0 109068.0 11462.1 11814.1 12914.7 12999.2 13107.2 19155.2 19796.4 19808.5 25068.5 25153.9 8884.4 9076.1 9096.0 9099.3 9120.8 4151.8 4683.9 4808.8 4862.4 4872.2 4300.9 4393.1 4403.3 4447.5 4457.8 903.5 913.0 916.5 924.6 925.6 638.6 651.8 659.3 663.5 681.3 11010.6 11094.8 11483.7 11647.9 12022.5 621.0 649.6 659.4 681.4 687.5 4654.7 4938.8 5106.7 5435.8 5454.5 84022.0 105129.0 105349.9 107145.0 107787.5 72806.0 74425.0 77495.0 83698.0 85098.0 74722.0 75530.0 75953.6 76256.9 78567.6 11312.3 11653.5 11720.6 11729.0 11982.8 39771.0 41188.6 41234.4 41731.6 41859.6 50641.0 51504.5 52263.7 52463.0 53312.7 15908.7 15951.0 16068.8 16113.5 16139.4 17753.4 17884.9 17885.9 18450.4 18644.3 15598.5 16067.9 16307.9 16447.3 16559.5 13324.9 16980.7 17234.8 17270.7 17335.9 15180.6 15526.3 15572.8 15729.6 15910.0 11707.2 11946.5 12071.2 12097.5 12127.3 12289.1 12395.3 12410.2 12449.7 12624.4 18:46:37 INFO - PID 1641 | -------- Summary: end -------- 18:46:37 INFO - PID 1641 | 18:46:38 INFO - TEST-INFO | 1641: exit 0 18:46:38 INFO - TEST-OK | dromaeo_css | took 1120399ms 18:46:38 INFO - TEST-START | kraken 18:46:38 INFO - operating with platform_type : mac_ 18:46:38 INFO - Initialising browser for kraken test... 18:46:38 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpie3qp0/profile http://localhost:49243/getInfo.html) 18:46:41 INFO - PID 1652 | __metrics Screen width/height:1600/1200 18:46:41 INFO - PID 1652 | colorDepth:24 18:46:41 INFO - PID 1652 | Browser inner width/height: 1024/689 18:46:41 INFO - PID 1652 | __metrics 18:46:41 INFO - PID 1652 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:46:41 INFO - PID 1652 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 304: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:46:42 INFO - TEST-INFO | 1652: exit 0 18:46:42 INFO - Browser initialized. 18:46:42 INFO - Running cycle 1/1 for kraken test... 18:46:42 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpie3qp0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:46:54 INFO - PID 1653 | RSS: Main: 240087040 18:46:54 INFO - PID 1653 | 18:46:57 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 18:46:58 INFO - PID 1653 | RSS: Main: 292454400 18:46:58 INFO - PID 1653 | 18:47:01 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 18:47:01 INFO - PID 1653 | RSS: Main: 293838848 18:47:01 INFO - PID 1653 | 18:47:05 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 18:47:06 INFO - PID 1653 | RSS: Main: 390066176 18:47:06 INFO - PID 1653 | 18:47:08 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 18:47:08 INFO - PID 1653 | RSS: Main: 288997376 18:47:08 INFO - PID 1653 | 18:47:11 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:47:11 INFO - PID 1653 | RSS: Main: 290140160 18:47:11 INFO - PID 1653 | 18:47:14 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 18:47:15 INFO - PID 1653 | RSS: Main: 333602816 18:47:15 INFO - PID 1653 | 18:47:18 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 18:47:19 INFO - PID 1653 | RSS: Main: 344526848 18:47:19 INFO - PID 1653 | 18:47:22 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 18:47:22 INFO - PID 1653 | RSS: Main: 352493568 18:47:22 INFO - PID 1653 | 18:47:24 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:47:25 INFO - PID 1653 | RSS: Main: 308916224 18:47:25 INFO - PID 1653 | 18:47:27 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:47:27 INFO - PID 1653 | RSS: Main: 312729600 18:47:27 INFO - PID 1653 | 18:47:30 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:47:30 INFO - PID 1653 | RSS: Main: 316059648 18:47:30 INFO - PID 1653 | 18:47:33 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:47:34 INFO - PID 1653 | RSS: Main: 332865536 18:47:34 INFO - PID 1653 | 18:47:38 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:47:38 INFO - PID 1653 | RSS: Main: 327106560 18:47:38 INFO - PID 1653 | 18:47:40 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 18:47:41 INFO - PID 1653 | RSS: Main: 390156288 18:47:41 INFO - PID 1653 | 18:47:41 INFO - PID 1653 | __start_tp_report 18:47:41 INFO - PID 1653 | _x_x_mozilla_page_load 18:47:41 INFO - PID 1653 | _x_x_mozilla_page_load_details 18:47:41 INFO - PID 1653 | |i|pagename|runs| 18:47:41 INFO - PID 1653 | |0;ai-astar;113;123;123;124;124;123;123;124;137;175 18:47:41 INFO - PID 1653 | |1;audio-beat-detection;153;119;118;162;119;120;115;117;142;120 18:47:41 INFO - PID 1653 | |2;audio-dft;227;259;226;212;208;210;211;218;210;209 18:47:41 INFO - PID 1653 | |3;audio-fft;81;63;64;98;71;63;61;64;80;63 18:47:41 INFO - PID 1653 | |4;audio-oscillator;93;78;95;75;76;76;93;73;76;92 18:47:41 INFO - PID 1653 | |5;imaging-gaussian-blur;93;93;92;93;93;92;93;111;92;92 18:47:41 INFO - PID 1653 | |6;imaging-darkroom;101;101;100;102;101;100;101;102;101;101 18:47:41 INFO - PID 1653 | |7;imaging-desaturate;103;102;103;103;100;104;101;103;101;101 18:47:41 INFO - PID 1653 | |8;json-parse-financial;53;50;52;51;52;52;53;52;51;52 18:47:41 INFO - PID 1653 | |9;json-stringify-tinderbox;60;67;64;69;76;68;67;80;95;94 18:47:41 INFO - PID 1653 | |10;stanford-crypto-aes;93;90;90;91;88;92;91;87;108;114 18:47:41 INFO - PID 1653 | |11;stanford-crypto-ccm;154;165;152;179;179;167;163;153;190;197 18:47:41 INFO - PID 1653 | |12;stanford-crypto-pbkdf2;201;212;202;202;197;204;203;203;199;198 18:47:41 INFO - PID 1653 | |13;stanford-crypto-sha256-iterative;71;72;85;66;87;110;101;102;99;85 18:47:41 INFO - PID 1653 | __end_tp_report 18:47:41 INFO - PID 1653 | __start_cc_report 18:47:41 INFO - PID 1653 | _x_x_mozilla_cycle_collect,2255 18:47:41 INFO - PID 1653 | __end_cc_report 18:47:41 INFO - PID 1653 | __startTimestamp1491875261070__endTimestamp 18:47:41 INFO - PID 1653 | 18:47:41 INFO - PID 1653 | ------- Summary: start ------- 18:47:41 INFO - PID 1653 | Number of tests: 14 18:47:41 INFO - PID 1653 | 18:47:41 INFO - PID 1653 | [#0] ai-astar Cycles:10 Average:128.90 Median:124.00 stddev:17.18 (13.9%) stddev-sans-first:17.23 18:47:41 INFO - PID 1653 | Values: 113.0 123.0 123.0 124.0 124.0 123.0 123.0 124.0 137.0 175.0 18:47:41 INFO - PID 1653 | 18:47:41 INFO - PID 1653 | [#1] audio-beat-detection Cycles:10 Average:128.50 Median:120.00 stddev:17.17 (14.3%) stddev-sans-first:15.76 18:47:41 INFO - PID 1653 | Values: 153.0 119.0 118.0 162.0 119.0 120.0 115.0 117.0 142.0 120.0 18:47:41 INFO - PID 1653 | 18:47:41 INFO - PID 1653 | [#2] audio-dft Cycles:10 Average:219.00 Median:215.00 stddev:15.67 (7.3%) stddev-sans-first:16.35 18:47:41 INFO - PID 1653 | Values: 227.0 259.0 226.0 212.0 208.0 210.0 211.0 218.0 210.0 209.0 18:47:41 INFO - PID 1653 | 18:47:41 INFO - PID 1653 | [#3] audio-fft Cycles:10 Average:70.80 Median:67.50 stddev:12.02 (17.8%) stddev-sans-first:12.17 18:47:41 INFO - PID 1653 | Values: 81.0 63.0 64.0 98.0 71.0 63.0 61.0 64.0 80.0 63.0 18:47:41 INFO - PID 1653 | 18:47:41 INFO - PID 1653 | [#4] audio-oscillator Cycles:10 Average:82.70 Median:85.00 stddev:9.19 (10.8%) stddev-sans-first:8.96 18:47:41 INFO - PID 1653 | Values: 93.0 78.0 95.0 75.0 76.0 76.0 93.0 73.0 76.0 92.0 18:47:41 INFO - PID 1653 | 18:47:41 INFO - PID 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:94.40 Median:93.00 stddev:5.85 (6.3%) stddev-sans-first:6.19 18:47:41 INFO - PID 1653 | Values: 93.0 93.0 92.0 93.0 93.0 92.0 93.0 111.0 92.0 92.0 18:47:41 INFO - PID 1653 | 18:47:41 INFO - PID 1653 | [#6] imaging-darkroom Cycles:10 Average:101.00 Median:101.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 18:47:41 INFO - PID 1653 | Values: 101.0 101.0 100.0 102.0 101.0 100.0 101.0 102.0 101.0 101.0 18:47:41 INFO - PID 1653 | 18:47:41 INFO - PID 1653 | [#7] imaging-desaturate Cycles:10 Average:102.10 Median:103.00 stddev:1.29 (1.2%) stddev-sans-first:1.32 18:47:41 INFO - PID 1653 | Values: 103.0 102.0 103.0 103.0 100.0 104.0 101.0 103.0 101.0 101.0 18:47:41 INFO - PID 1653 | 18:47:41 INFO - PID 1653 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:0.92 (1.8%) stddev-sans-first:0.87 18:47:41 INFO - PID 1653 | Values: 53.0 50.0 52.0 51.0 52.0 52.0 53.0 52.0 51.0 52.0 18:47:41 INFO - PID 1653 | 18:47:41 INFO - PID 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:74.00 Median:72.50 stddev:12.18 (16.8%) stddev-sans-first:11.82 18:47:41 INFO - PID 1653 | Values: 60.0 67.0 64.0 69.0 76.0 68.0 67.0 80.0 95.0 94.0 18:47:41 INFO - PID 1653 | 18:47:41 INFO - PID 1653 | [#10] stanford-crypto-aes Cycles:10 Average:94.40 Median:90.50 stddev:9.03 (10.0%) stddev-sans-first:9.57 18:47:41 INFO - PID 1653 | Values: 93.0 90.0 90.0 91.0 88.0 92.0 91.0 87.0 108.0 114.0 18:47:41 INFO - PID 1653 | 18:47:41 INFO - PID 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:169.90 Median:173.00 stddev:15.79 (9.1%) stddev-sans-first:15.66 18:47:41 INFO - PID 1653 | Values: 154.0 165.0 152.0 179.0 179.0 167.0 163.0 153.0 190.0 197.0 18:47:41 INFO - PID 1653 | 18:47:41 INFO - PID 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:202.10 Median:202.50 stddev:4.18 (2.1%) stddev-sans-first:4.41 18:47:41 INFO - PID 1653 | Values: 201.0 212.0 202.0 202.0 197.0 204.0 203.0 203.0 199.0 198.0 18:47:41 INFO - PID 1653 | 18:47:41 INFO - PID 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:87.80 Median:78.50 stddev:14.97 (19.1%) stddev-sans-first:14.59 18:47:41 INFO - PID 1653 | Values: 71.0 72.0 85.0 66.0 87.0 110.0 101.0 102.0 99.0 85.0 18:47:41 INFO - PID 1653 | -------- Summary: end -------- 18:47:41 INFO - PID 1653 | 18:47:42 INFO - TEST-INFO | 1653: exit 0 18:47:42 INFO - TEST-OK | kraken | took 63582ms 18:47:42 INFO - SUITE-END | took 1183s 18:47:42 INFO - Completed test suite (00:19:43) 18:47:42 INFO - javascript benchmark 18:47:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7658.919866648513, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2794.9099851058086, 3243.172990887324, 3439.5700537432836, 3500.3874321993644, 3531.0466174132985, 5818.3308919474275, 6025.7288422021, 6081.179040829492, 6086.817395478142, 6094.573379863411, 5899.056151015828, 5974.432428919256, 5976.760929562824, 5985.132155837412, 5985.610935289204, 5223.085959956992, 5250.107481728115, 5251.054810134175, 5300.257963885059, 5331.333583302087, 4708.387909571768, 5083.923741143886, 5342.091844386464, 5342.732863356836, 5342.893142137155, 5337.626366154371, 5352.116900711398, 5352.732363381835, 5354.518093371596, 5357.142857142858, 5331.840044798662, 5341.4925582069645, 5348.5453736432255, 5361.035013697533, 5372.650777699476, 5180.663256888291, 5281.577473802125, 5286.3392075990505, 5296.099663057251, 5304.098303288451, 6183.0, 6193.907091393633, 6194.225721784777, 6194.5044396448175, 6206.069089636546, 6248.812535623939, 6325.114483972247, 6468.06213099098, 6513.576617519846, 6516.218053833571, 6398.0, 6449.0, 6507.967460162717, 6551.80344589663, 6558.2458017328145, 6229.937700622981, 6231.096491008823, 6257.155284036688, 6265.4361107500545, 6268.78059267928, 6094.573379863456, 6736.730530778714, 6758.898616520756, 6759.2226893906845, 6765.019072234597, 5126.743662816844, 6212.627242365473, 6279.686015699197, 6290.905636415458, 6304.810855674337, 6516.902246466307, 6526.314736952647, 6527.706253218616, 6537.0, 6545.14913061299, 6378.075179099004, 6409.967950160312, 6426.389492998158, 6430.582012169194, 6473.643949582724, 6009.849753756191, 6227.813165605039, 6337.366263373718, 6483.222013358427, 6492.448141907897, 4434.733915965053, 5109.0, 5254.6847189168775, 5288.550473209743, 5290.206469029599, 4574.245249533789, 4627.259638457831, 4673.485916549177, 4675.22858728306, 4696.718196908197, 6255.374462553708, 6258.342873998165, 6259.937400626027, 6293.433590976742, 6300.621962682254, 6331.746730130835, 6567.737290508422, 6603.075569420285, 6620.569662971891, 6633.834154146185, 6127.658725805958, 6138.878900475427, 6144.07838824171, 6196.783112591038, 6204.193454850931, 5719.456651618089, 5726.541876649858, 5737.827865164052, 5744.4829965303325, 5774.595778295479, 6649.60102393858, 6691.130153080166, 6700.363465470772, 6704.865902682017, 6715.059891615276, 5426.608045592803, 6228.314885362516, 6325.557210995186, 6359.8092057238355, 6361.491080713625, 6468.5472016958365, 6478.70845811949, 6495.967520162369, 6496.642684652389, 6510.707018184288, 6362.45919096882, 6373.553851230555, 6389.977603583405, 6395.20059992501, 6453.032045193258, 6430.903536447051, 6445.1943507061615, 6457.644829534421, 6491.123698300669, 6513.674316284261, 6320.6839658016, 6374.235091789015, 6391.904121438275, 6392.744290228339, 6395.976643737165, 6226.5641405101205, 6289.371062893674, 6367.140436041261, 6377.266614339411, 6381.968090159519, 6252.187215662117, 6316.936830631635, 6348.111264422892, 6354.0, 6354.555181137276, 6361.427471527585, 6366.808995730135, 6369.777057802955, 6376.649284289409, 6381.968090159705, 6076.605020673642, 6307.936920630735, 6311.747530098744, 6325.905111423241, 6329.588576742497], "value": 5928.238461688308, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [745.2659130756205, 783.4202690009392, 801.9117897031325, 1118.5861231344404, 1119.832025196221, 27395.0, 27977.160685179453, 28043.15870523885, 36676.44985325222, 36949.81525092378, 38779.0, 38943.61056389442, 38951.83144505669, 38976.0, 38996.02509937253, 8452.15478452153, 8635.481871087723, 8635.69775057873, 8643.17889800468, 8648.870266945969, 6129.969350153243, 6263.185785847856, 8469.364797640172, 8571.914280857205, 8670.0, 8699.130086991281, 8713.477191368507, 8744.519051452167, 8754.212120909116, 8761.342899282547, 8640.783980400478, 8704.086070962585, 8716.912830871674, 8722.34582406322, 8722.86915696265, 8509.021462531826, 8559.828803423896, 8568.014678311949, 8569.400141990063, 8569.914300856974, 36947.0, 37091.629083709086, 37170.81414592937, 37177.628223717686, 37221.441678374846, 3547.5388199534154, 3576.982115089434, 3580.606133325332, 3582.1223800168864, 3584.4981702561663, 30738.23154421135, 30761.230969225726, 30825.92109276187, 30857.228569285722, 30906.690933090606, 3124.4376012317775, 3146.0561831450473, 3166.6358368787655, 3190.5054716518976, 3203.871845126192, 252726.73636631883, 258559.0, 258647.0, 259178.40821591733, 261488.3851161502, 48921.0, 48984.0, 49058.50941490539, 49089.263661045115, 49091.99999999929, 50091.0, 50137.74931125321, 50219.0, 50246.0, 50249.74875125601, 13872.375743091383, 14016.789748153786, 14020.228887411291, 14025.649358765908, 14030.877529797592, 11210.551577936954, 11256.493457794255, 11258.155638327158, 11428.314301141792, 11518.136139789549, 10452.529636166391, 10626.89373106259, 12082.818757718642, 12200.694982625506, 12309.507619695112, 4282.721623094489, 5149.867029253558, 5150.892558099951, 5157.690538567623, 5158.638895277295, 975.2393133355994, 988.461288597716, 996.6097294274447, 1001.9398836069859, 1029.552144816992, 21274.893625532084, 21677.891610542163, 23627.054917803096, 23976.0, 24041.879790600935, 944.8110377924305, 1097.9780404391868, 1099.6041425086962, 1130.265327537091, 1149.976520896403, 12207.572734954412, 12354.444050017772, 12358.938205309096, 12359.0, 12391.504339826486, 306766.0, 308246.0, 308646.0, 308932.0, 309181.0, 53042.0, 53391.19913201305, 53397.1990420144, 53474.93050139053, 53614.0, 1486.2494440307576, 1486.8661820435996, 1521.62720133568, 1524.41310095614, 1531.0430980637102, 22206.77793222047, 23524.88237558801, 23594.52810943806, 23742.287731367396, 23777.881110594335, 37562.81218593889, 37601.0, 37641.81179094087, 37692.0, 37695.623043769214, 301377.0, 301734.0, 302711.0, 303210.4839475789, 303651.48174258985, 31825.226216067775, 34755.0, 34787.9563613092, 34827.60689572389, 34874.30251394907, 41118.383224252255, 41245.793771030956, 41252.58747412488, 41339.1732165361, 41472.58527414687, 27443.267474017222, 28375.858120709265, 28914.27714307159, 29396.118116456542, 29814.85092574523, 27802.304942376602, 28443.715562844107, 28656.856715716287, 28969.986050488977, 29431.85284073566, 33337.166570834954, 33609.0, 34473.310533789685, 34532.0, 34878.9536313911, 22331.553368932855, 28537.571936421387, 29215.0, 29408.411831762816, 29566.817327307526, 20697.0, 22320.140923085564, 22768.29237807138, 22881.0, 23039.6544051836], "value": 16954.162242239538, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.2945662535748, 426.6542549065239, 433.24831417490657, 435.49917594766015, 454.43422938441626, 17633.64732705345, 21515.139394424223, 22213.777862221414, 22944.196953106646, 23261.767382326172, 22043.779562204374, 22391.440213994618, 23248.41878953027, 23594.0, 23778.5244295114, 5039.0, 6011.458968692817, 6250.0624906263965, 6289.088082228074, 6361.522885783562, 3728.9254214915686, 3939.2909276330247, 6360.618362898218, 6402.93597064028, 6408.263049749292, 6099.176611157482, 6322.209723784527, 6342.016987366966, 6345.9365406345805, 6355.173827402455, 5450.400455949843, 6128.908066379008, 6275.6548389838545, 6332.335104813974, 6335.334789847046, 5547.750351234205, 5616.775328986836, 5644.153376993443, 5644.520215781664, 5651.86962607476, 24144.879275603686, 27278.318042048908, 27569.724302757117, 27922.581161282596, 29054.418911621648, 2207.381933058746, 2237.944051398728, 2270.4437412833795, 2274.8862556872255, 2275.795178433949, 17646.0, 17811.554711132198, 18468.984205868674, 18616.720749188775, 18678.06609669511, 1897.9810201897942, 2091.905864236113, 2134.541073669169, 2167.1548096242477, 2189.9671504927437, 155236.2238188802, 156160.0, 158390.0, 159048.20475897662, 167047.3295267056, 11624.360660163773, 11946.223495472766, 12111.576094836639, 12317.384130793425, 12543.874561254454, 14959.92520037393, 16017.279222435018, 16537.421190258283, 16563.08903010322, 16648.916755416147, 7416.814579635552, 9059.094090590888, 9157.267418606496, 9301.90698093024, 9315.0, 8627.309815214769, 8659.09079546651, 8681.609327580274, 8712.869306960274, 9074.954625226832, 6276.4622667446765, 6528.706208220547, 7696.576688282086, 7717.65270562826, 7839.882401763978, 1827.0955876841049, 2118.69878321331, 2148.2051640892655, 2150.2474134052954, 2317.5944209763225, 897.5691667999397, 925.6019911438011, 930.1721467893581, 961.7355227312545, 995.6963126246565, 20291.28980485676, 21062.368128956157, 21068.946552672303, 21210.893945530483, 21240.468988275417, 968.5544649461327, 1004.467632154959, 1009.4756917055299, 1010.5957616953277, 1014.3406785589426, 10635.0, 10930.56277748897, 10940.835887461695, 10952.726181845359, 10980.450977451095, 688415.0, 699008.0, 702095.0, 703043.0, 739234.0, 155042.0, 160491.19754401152, 163298.0, 164414.0, 165919.68160636962, 1024.3751311699857, 1174.7826652069396, 1227.147132742753, 1230.1450491908217, 1234.5740719451774, 17961.820381796013, 20446.591068178852, 21853.47025708249, 22229.777362244586, 22334.553308934057, 42328.78835605802, 44107.338389923534, 64469.0, 64620.0, 66284.66857665681, 70827.0, 88095.55952220198, 88786.0, 89234.0, 89933.55033224792, 7758.0, 8733.126687331216, 8787.604557795043, 9269.397489163182, 9308.860367094634, 11918.880811191777, 14188.716225675222, 14481.927590362402, 14856.925715371353, 14866.554003379915, 9234.03042680509, 10323.48382580883, 11961.342126183143, 12111.81832272499, 12417.689557761129, 11333.320000799979, 11523.53905843757, 11530.884691152982, 11632.476538555617, 11985.0, 12092.0, 12163.513459461876, 12291.44688489038, 12461.1900226485, 12591.87408125907, 9020.639174432948, 9026.368154229142, 9101.40840845343, 9114.316426267924, 9122.407043542149, 10378.65077539915, 11877.406129693654, 12197.573084941985, 12275.815862762245, 12311.44598493052], "value": 10589.935848348347, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1193.2661413230862, 1298.2729671384022, 1340.5978206538043, 1407.1627381707892, 1411.4777532313046, 11319.490622921967, 11814.881851181486, 11821.40892955352, 11826.58606948757, 11844.466998985044, 11426.942865285662, 11525.827112593297, 11836.053115750741, 11924.582639607615, 11926.582569610066, 2502.6246063090543, 2546.8599227042505, 2549.8087643426725, 2549.8980040798347, 2550.8979640814346, 2453.8405003674793, 2459.667944827452, 2515.069424313007, 2519.949601007979, 2521.621756736486, 2141.7001619773246, 2431.57447446697, 2533.3033415810614, 2534.8986040558357, 2536.695596528419, 2482.094035676983, 2506.4360518883286, 2514.6856642919633, 2537.6320433537126, 2541.720410754834, 2494.0896572751, 2507.5737124688662, 2512.6984761828517, 2517.1315896015844, 2520.558902192109, 11467.885321146765, 11674.824877626843, 11690.0, 11691.532338706527, 11709.765804683944, 6498.187726534184, 6759.4930380221185, 6761.154855643044, 6769.932300676979, 6788.185417749853, 4177.668985987719, 4311.0, 4328.567143285678, 4367.301231802896, 4442.755648439334, 1237.4617041586848, 1241.3296819717432, 1274.8653698208602, 1300.180886041797, 1300.4213125159308, 14912.105273683614, 15346.539603811792, 16385.67228655432, 16486.620242850186, 16497.422590209284, 5024.773885175176, 5549.916751248735, 5738.655680659173, 5740.789795928597, 5759.798407055733, 11892.405379731152, 11917.821232681517, 11922.940385298018, 11928.045756339645, 11928.284302941851, 5744.971275143597, 5939.0, 6572.901406478906, 6601.53789234749, 6602.86794264112, 5199.4020687621405, 5257.842264732064, 6097.634141951497, 6221.440070393686, 6314.968425157845, 4362.062156636339, 4416.712913660602, 4424.491183513873, 4460.732356058582, 4505.0, 1291.6770807298176, 1291.819145319656, 1293.1529847949607, 1313.7044165062975, 1319.7822359310605, 692.8498692170972, 766.5400759544341, 768.7924260449759, 773.477902415867, 784.9450538462253, 5921.881562368814, 5978.820635380858, 5988.730507127189, 6222.782202622977, 6321.462675672527, 580.8751118509541, 594.5184400635499, 597.1162679234757, 602.9087351893088, 605.9515238780975, 3250.6261779895613, 3959.1883663848844, 4137.089840235143, 4256.722983105056, 4318.632916202158, 13831.654208644864, 14761.114333140045, 15112.168830714416, 15382.615434614225, 15498.690026199412, 14835.18406487632, 15086.773698394532, 15215.923920380328, 15266.694666106616, 15294.617634559225, 9083.45499270046, 10811.135109191246, 10873.456327183609, 11067.114630829514, 11067.391293478937, 6398.328175541501, 6558.967205163944, 6578.046183303299, 6593.175853018372, 6758.628275444898, 13445.529406470727, 13494.122882012638, 13783.104098233583, 13787.0, 13804.102733322703, 11286.0, 11386.829197561878, 12028.75942481128, 13645.795313070113, 14237.430502779773, 8022.7163653815105, 8357.749267521984, 8458.323334133378, 8543.359248056297, 8576.4425312357, 9277.953610231905, 9550.379225350607, 10217.33587316822, 10547.156227501628, 10563.94718026405, 8805.823883522422, 9056.501892395981, 9063.909360906306, 9081.273498120267, 9121.817563648823, 8807.647694092164, 8817.030126685931, 8870.0, 8870.822583548164, 8931.017588065177, 7663.0, 7781.727639532589, 7957.403194760301, 7965.8406831864195, 7968.043834739637, 5390.757415916215, 5841.211436456024, 5942.881142377042, 6113.969430152821, 6117.632942023492, 5883.0, 5987.13186587951, 6037.09443583466, 6053.061775424816, 6107.725152368065], "value": 5266.98048271197, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.9842507874606, 389.1691239204298, 397.253164051583, 397.4892263441475, 401.6465510350889, 5000.949800541886, 5632.380438151805, 5653.406392328808, 5680.204771332017, 5707.172459993299, 4601.608863246628, 4683.998317955817, 5323.707196104213, 5577.69322687252, 5606.467385598372, 3868.4197370394454, 4126.236646220459, 4312.611864932155, 4336.392904993303, 4337.761423121727, 3540.6813386795184, 3542.3092496963104, 3651.981740091309, 4100.8722601284735, 4331.267493002808, 4272.145570885819, 4296.656267498593, 4302.741835489865, 4318.870433886988, 4323.956760432387, 3416.231347946717, 3608.657177568127, 3916.9020774480723, 4099.221147981874, 4135.400366946776, 2995.5207166853197, 3042.39152169567, 3562.6615471530163, 3602.3155600436094, 3636.6181550937035, 5364.892702145974, 5382.811601593926, 5398.433164517708, 5413.566914646819, 5416.508406133184, 4257.233697934371, 4699.4022032508765, 4830.468648448667, 4849.532584202312, 4884.535969082932, 4759.048190361941, 5054.595632349403, 5833.912491312633, 5853.619514731571, 5912.379200184005, 3612.71098312137, 3844.7896006834326, 4057.2494088593703, 4066.6340029397497, 4066.8983275418054, 5220.7128607926925, 5820.497186209928, 6091.695415229221, 6162.506999440034, 6234.345393733638, 4111.0, 4126.452580282394, 4128.690348223895, 4129.359949207877, 4142.46148000758, 3160.862089647725, 4058.817353219112, 4190.455240818735, 4193.769342686121, 4203.936940945827, 4663.603593694506, 4836.806527738921, 4846.418429788448, 4847.2729090636685, 4862.80548778052, 5597.216389705444, 5796.681182534917, 5840.766369345263, 5877.52979761618, 5908.645481271052, 1112.9927415688815, 1402.6843960109097, 1417.3976060174384, 1426.2440906319666, 1429.9857001430062, 899.4917871402637, 949.1979277510493, 962.6967505235968, 970.2674480766975, 973.6543527047937, 4692.648051396159, 5365.839024829262, 5540.778368865281, 5607.80372686954, 5638.210650508932, 5570.3315602128005, 6078.726457309432, 6108.694565271719, 6129.927262729005, 6131.908021379683, 4972.1851524194135, 5398.541124004503, 5618.69097199658, 5637.351704553947, 5770.88458230833, 3023.0628505163468, 3192.840357982138, 3209.0372888133465, 3211.871525138968, 3231.30526936705, 5772.740226689725, 5999.610025348338, 6255.186825712628, 6313.349552150799, 6327.557071005078, 6041.728122234422, 6225.221847269091, 6292.559520833498, 6368.395002474757, 6370.757702248017, 4759.381280433521, 6125.050617154348, 6185.8144255671505, 6196.411340922605, 6391.93608063932, 6086.555758738221, 6192.628442293477, 6196.194494715658, 6199.876002480015, 6202.34875338101, 5268.550124192269, 5980.43185897339, 6059.484943779828, 6083.7870674526175, 6125.264968203844, 6336.398042185985, 6347.635258419277, 6366.64983425916, 6372.840678983062, 6379.0, 4011.5386730525197, 4118.155778065429, 4147.04617937882, 4149.128682976609, 4172.91654166921, 4537.024539723975, 5879.794207202727, 5914.911276330772, 6143.232095988002, 6179.876402472015, 5230.137027390438, 5636.633618814764, 5952.375000625046, 5971.253593300838, 6036.348009454217, 5768.653880767168, 5814.418558144152, 5847.327557330961, 5934.376890426443, 5936.168936349001, 5781.710914454345, 5813.82558523245, 5815.0, 5818.8254352369495, 5841.8024305016515, 4902.950970490249, 5663.065594177079, 5941.346451890202, 5987.790427335022, 5995.5803093783015, 4344.261475549228, 5206.114960456655, 5775.220345253505, 5797.0, 5894.440028197314], "value": 4324.581759259875, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.2805965974364, 864.930805535557, 876.163264082801, 877.341993504871, 896.6234181643711, 13168.868311316895, 17498.125093745308, 17625.82374176258, 18195.363162289323, 18205.998670073142, 16894.324227030935, 17352.0, 17364.56588585286, 17496.125193740325, 17558.473245802616, 961.4808003678005, 984.8621193032981, 989.5547003848297, 993.3493561717087, 993.4287784523891, 644.7936660268716, 655.8557117434157, 951.5813042261417, 1008.1330056151704, 1014.1430491234895, 1053.0417320238585, 1054.72577129946, 1061.2730279758352, 1063.7393838509577, 1064.249703958711, 1036.0934182590233, 1036.7459972306758, 1037.5953378182514, 1037.8598889149944, 1047.010575006619, 772.9497582657109, 773.1842905734447, 774.0556521044317, 775.3835700618022, 783.3576467296818, 14885.032472889336, 15331.23343832815, 17087.0, 17185.39851105205, 17288.0, 13957.162570245819, 16014.439494617633, 16146.677066458722, 16281.348746050144, 16409.507714768577, 15430.845691543052, 15604.063756174552, 15816.683666326724, 15852.920735396365, 15907.522774316789, 5286.471352864722, 5291.1534154535475, 5299.973500132513, 5309.893802123975, 5310.840674779763, 107424.46287768512, 107743.0, 108529.45735271274, 108984.0, 109067.9999999992, 11462.140339474572, 11814.05487560993, 12914.741705166032, 12999.1550549214, 13107.213567186, 19155.233790648217, 19796.406107816787, 19808.504787380432, 25068.48493331464, 25153.874230629095, 8884.37809353352, 9076.137766912132, 9095.999440061589, 9099.272058235325, 9120.771980700536, 4151.7924103794685, 4683.906321873612, 4808.807647694053, 4862.41651001882, 4872.19608764557, 4300.935485967713, 4393.143337049245, 4403.3174857897075, 4447.510773814878, 4457.821687132543, 903.4830212630849, 912.9543522823832, 916.5050872528893, 924.5977999570074, 925.5881132895797, 638.6200210874522, 651.7914267434376, 659.2682122843639, 663.5189487621436, 681.2540268406087, 11010.559577616967, 11094.778104437866, 11483.655490335303, 11647.88352116468, 12022.458989345325, 620.996895015522, 649.5518092516148, 659.4197106546209, 681.4412182010703, 687.4534744877834, 4654.697444666086, 4938.802447902115, 5106.693598384109, 5435.782568697208, 5454.53636440899, 84022.0, 105129.0, 105349.89300213952, 107145.0, 107787.46106269419, 72806.0, 74425.0, 77495.0, 83698.0, 85098.0, 74722.0, 75530.0, 75953.6202318996, 76256.85614715784, 78567.60716196382, 11312.321260724382, 11653.53385864556, 11720.589779357682, 11729.0, 11982.820257695967, 39771.0, 41188.55840045584, 41234.38148427716, 41731.58268417277, 41859.581404186785, 50641.0, 51504.48495514997, 52263.73868130635, 52463.0, 53312.66718332073, 15908.68182636364, 15951.042937423792, 16068.758968615713, 16113.516594502184, 16139.354425822836, 17753.378631748343, 17884.91057544704, 17885.926844389378, 18450.446486605422, 18644.254229830654, 15598.532044038697, 16067.919660401623, 16307.918460407622, 16447.25987330549, 16559.503214903572, 13324.933375333449, 16980.745288820926, 17234.827651723823, 17270.654586907942, 17335.913320433316, 15180.620484487976, 15526.301316441013, 15572.844271557593, 15729.606759831096, 15910.0, 11707.180497365103, 11946.522139114339, 12071.215371000857, 12097.455614497545, 12127.332996684914, 12289.064020722966, 12395.256284622952, 12410.193337433038, 12449.688757781129, 12624.431900564681], "value": 8324.713673661305, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1607.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 123.0, 123.0, 124.0, 124.0, 123.0, 123.0, 124.0, 137.0, 175.0], "value": 128.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 119.0, 118.0, 162.0, 119.0, 120.0, 115.0, 117.0, 142.0, 120.0], "value": 128.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [227.0, 259.0, 226.0, 212.0, 208.0, 210.0, 211.0, 218.0, 210.0, 209.0], "value": 219.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 63.0, 64.0, 98.0, 71.0, 63.0, 61.0, 64.0, 80.0, 63.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 78.0, 95.0, 75.0, 76.0, 76.0, 93.0, 73.0, 76.0, 92.0], "value": 82.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 92.0, 93.0, 93.0, 92.0, 93.0, 111.0, 92.0, 92.0], "value": 94.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 101.0, 100.0, 102.0, 101.0, 100.0, 101.0, 102.0, 101.0, 101.0], "value": 101.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 102.0, 103.0, 103.0, 100.0, 104.0, 101.0, 103.0, 101.0, 101.0], "value": 102.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 52.0, 51.0, 52.0, 52.0, 53.0, 52.0, 51.0, 52.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 67.0, 64.0, 69.0, 76.0, 68.0, 67.0, 80.0, 95.0, 94.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 90.0, 90.0, 91.0, 88.0, 92.0, 91.0, 87.0, 108.0, 114.0], "value": 94.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 165.0, 152.0, 179.0, 179.0, 167.0, 163.0, 153.0, 190.0, 197.0], "value": 169.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 212.0, 202.0, 202.0, 197.0, 204.0, 203.0, 203.0, 199.0, 198.0], "value": 202.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 85.0, 66.0, 87.0, 110.0, 101.0, 102.0, 99.0, 85.0], "value": 87.8, "unit": "score"}]}]} 18:47:42 INFO - Return code: 0 18:47:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:47:42 ERROR - # TBPL SUCCESS # 18:47:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:47:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:47:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:47:42 INFO - 2017-04-10 18:47:42.371 screenresolution[1657:9767] starting screenresolution argv=screenresolution get 18:47:42 INFO - 2017-04-10 18:47:42.374 screenresolution[1657:9767] Display 0: 1600x1200x32@60 18:47:42 INFO - 2017-04-10 18:47:42.380 screenresolution[1658:9770] starting screenresolution argv=screenresolution list 18:47:42 INFO - Available Modes on Display 0 18:47:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:47:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:47:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:47:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:47:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:47:42 INFO - Intel Iris: 18:47:42 INFO - Chipset Model: Intel Iris 18:47:42 INFO - Type: GPU 18:47:42 INFO - Bus: Built-In 18:47:42 INFO - VRAM (Dynamic, Max): 1536 MB 18:47:42 INFO - Vendor: Intel (0x8086) 18:47:42 INFO - Device ID: 0x0a2e 18:47:42 INFO - Revision ID: 0x0009 18:47:42 INFO - Displays: 18:47:42 INFO - Display: 18:47:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:47:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:47:42 INFO - Display Serial Number: 1600x1200 60 18:47:42 INFO - Main Display: Yes 18:47:42 INFO - Mirror: Off 18:47:42 INFO - Online: Yes 18:47:42 INFO - Rotation: Supported 18:47:42 INFO - Return code: 0 18:47:42 INFO - Running post-action listener: _resource_record_post_action 18:47:42 INFO - [mozharness: 2017-04-11 01:47:42.631083Z] Finished run-tests step (success) 18:47:42 INFO - Running post-run listener: _resource_record_post_run 18:47:42 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 39744512; Write bytes: 515044352; Read time: 381; Write time: 1696 18:47:42 INFO - TinderboxPrint: CPU usage
53.4% 18:47:42 INFO - TinderboxPrint: I/O read bytes / time
39,744,512 / 381 18:47:42 INFO - TinderboxPrint: I/O write bytes / time
515,044,352 / 1,696 18:47:42 INFO - TinderboxPrint: CPU idle
2,247.4 (46.6%) 18:47:42 INFO - TinderboxPrint: CPU system
962.4 (20.0%) 18:47:42 INFO - TinderboxPrint: CPU user
1,607.9 (33.4%) 18:47:42 INFO - TinderboxPrint: Swap in / out
246,943,744 / 0 18:47:42 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207278592; Write bytes: 205510144; Read time: 15941; Write time: 517 18:47:42 INFO - run-tests - Wall time: 1185s; CPU: 53.0%; Read bytes: 34821120; Write bytes: 302296576; Read time: 349; Write time: 1149 18:47:43 INFO - Running post-run listener: _upload_blobber_files 18:47:43 INFO - Blob upload gear active. 18:47:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:47:43 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 18:47:43 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:47:43 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:47:43 INFO - (blobuploader) - INFO - Open directory for files ... 18:47:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:47:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:47:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:47:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:47:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:47:44 INFO - (blobuploader) - INFO - Done attempting. 18:47:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:47:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:47:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:47:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:47:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:47:45 INFO - (blobuploader) - INFO - Done attempting. 18:47:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:47:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:47:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:47:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:47:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:47:47 INFO - (blobuploader) - INFO - Done attempting. 18:47:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:47:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:47:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:47:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:47:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:47:49 INFO - (blobuploader) - INFO - Done attempting. 18:47:49 INFO - (blobuploader) - INFO - Iteration through files over. 18:47:49 INFO - Return code: 0 18:47:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:47:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:47:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/82fe0e08c6f7d4d453ffe94f3cf542964e7fcb5a785ad81bfbbaa186c0daa564d0c955c7651cf3f9495a94e04656cdcdbaf4c61e5433b644e71a4422b7cac40a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e1e89fae2dbb24d086eec1d59e98f85658d27ffd25a695bb46e19ca49b87a55c92d677998fc3e746df4b735947b098f3379f1de5838fff2ae4cdb832598c6857", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/9a7d27ea9f37d2101143191a9d6f13ab93f640e807358a027fe0f00978586160f5d0063e19f114204ab1e61982ee0ab08321c1e85934457f3c14e293374c3e0f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5a13ba8b2c9b6d96d0c1937c08dbc4401a8093b9694b6d86833f7df787d6bcb74be923bd8602bea6badef02756312132fb52cd6af9e560da04f0a048a0d03a22"} 18:47:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:47:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:47:49 INFO - Contents: 18:47:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/82fe0e08c6f7d4d453ffe94f3cf542964e7fcb5a785ad81bfbbaa186c0daa564d0c955c7651cf3f9495a94e04656cdcdbaf4c61e5433b644e71a4422b7cac40a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e1e89fae2dbb24d086eec1d59e98f85658d27ffd25a695bb46e19ca49b87a55c92d677998fc3e746df4b735947b098f3379f1de5838fff2ae4cdb832598c6857", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/9a7d27ea9f37d2101143191a9d6f13ab93f640e807358a027fe0f00978586160f5d0063e19f114204ab1e61982ee0ab08321c1e85934457f3c14e293374c3e0f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5a13ba8b2c9b6d96d0c1937c08dbc4401a8093b9694b6d86833f7df787d6bcb74be923bd8602bea6badef02756312132fb52cd6af9e560da04f0a048a0d03a22"} 18:47:49 INFO - Running post-run listener: copy_logs_to_upload_dir 18:47:49 INFO - Copying logs to upload dir... 18:47:49 INFO - mkdir: /builds/slave/test/build/upload/logs 18:47:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.332523 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-04-10 18:47:49.375087) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 18:47:49.378273) ========= 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.YIzAxkjSYc/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.w9nkTkU9PV/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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/Pine/sha512/82fe0e08c6f7d4d453ffe94f3cf542964e7fcb5a785ad81bfbbaa186c0daa564d0c955c7651cf3f9495a94e04656cdcdbaf4c61e5433b644e71a4422b7cac40a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e1e89fae2dbb24d086eec1d59e98f85658d27ffd25a695bb46e19ca49b87a55c92d677998fc3e746df4b735947b098f3379f1de5838fff2ae4cdb832598c6857", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/9a7d27ea9f37d2101143191a9d6f13ab93f640e807358a027fe0f00978586160f5d0063e19f114204ab1e61982ee0ab08321c1e85934457f3c14e293374c3e0f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5a13ba8b2c9b6d96d0c1937c08dbc4401a8093b9694b6d86833f7df787d6bcb74be923bd8602bea6badef02756312132fb52cd6af9e560da04f0a048a0d03a22"} build_url:https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010814 build_url: 'https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/82fe0e08c6f7d4d453ffe94f3cf542964e7fcb5a785ad81bfbbaa186c0daa564d0c955c7651cf3f9495a94e04656cdcdbaf4c61e5433b644e71a4422b7cac40a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e1e89fae2dbb24d086eec1d59e98f85658d27ffd25a695bb46e19ca49b87a55c92d677998fc3e746df4b735947b098f3379f1de5838fff2ae4cdb832598c6857", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/9a7d27ea9f37d2101143191a9d6f13ab93f640e807358a027fe0f00978586160f5d0063e19f114204ab1e61982ee0ab08321c1e85934457f3c14e293374c3e0f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5a13ba8b2c9b6d96d0c1937c08dbc4401a8093b9694b6d86833f7df787d6bcb74be923bd8602bea6badef02756312132fb52cd6af9e560da04f0a048a0d03a22"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 18:47:49.411819) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 18:47:49.412158) ========= 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.YIzAxkjSYc/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.w9nkTkU9PV/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.005101 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 18:47:49.461077) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 18:47:49.461403) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 18:47:49.461758) ========= ========= Total master_lag: 0.18 =========