builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0211 starttime: 1491874008.08 results: success (0) buildid: 20170410113323 builduid: 4862cd4d03cb4346a1bf4b763ca80c5a revision: e516c0daef83fdd6542da4d74cabde0683533450 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:48.080275) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:48.081116) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:48.081672) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:48.107494) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:48.108148) ========= 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.wiZuW7p1NY/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.MyV6nLpPLb/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 143M=0s 2017-04-10 18:26:48 (143 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.080976 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:48.211362) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:48.211663) ========= 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.wiZuW7p1NY/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.MyV6nLpPLb/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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.021049 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:48.262059) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 18:26:48.262812) ========= 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.wiZuW7p1NY/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.MyV6nLpPLb/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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:48,355 truncating revision to first 12 chars 2017-04-10 18:26:48,355 Setting DEBUG logging. 2017-04-10 18:26:48,355 attempt 1/10 2017-04-10 18:26:48,355 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:49,613 unpacking tar archive at: pine-e516c0daef83/testing/mozharness/ program finished with exit code 0 elapsedTime=1.530262 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 18:26:49.822425) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:49.823237) ========= script_repo_revision: e516c0daef83fdd6542da4d74cabde0683533450 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:49.823962) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:49.824648) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 18:26:49.846377) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-10 18:26:49.847169) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.wiZuW7p1NY/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.MyV6nLpPLb/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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:50 INFO - MultiFileLogger online at 20170410 18:26:50 in /builds/slave/test 18:26:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 18:26:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:26:50 INFO - {'append_to_log': False, 18:26:50 INFO - 'base_work_dir': '/builds/slave/test', 18:26:50 INFO - 'blob_upload_branch': 'Pine', 18:26:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:26:50 INFO - 'branch': 'Pine', 18:26:50 INFO - 'buildbot_json_path': 'buildprops.json', 18:26:50 INFO - 'config_files': ('talos/mac_config.py',), 18:26:50 INFO - 'default_actions': ('clobber', 18:26:50 INFO - 'read-buildbot-config', 18:26:50 INFO - 'download-and-extract', 18:26:50 INFO - 'populate-webroot', 18:26:50 INFO - 'create-virtualenv', 18:26:50 INFO - 'install', 18:26:50 INFO - 'run-tests'), 18:26:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:26:50 INFO - 'download_minidump_stackwalk': True, 18:26:50 INFO - 'download_symbols': 'ondemand', 18:26:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:26:50 INFO - 'tooltool.py': '/tools/tooltool.py', 18:26:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:26:50 INFO - '/tools/misc-python/virtualenv.py')}, 18:26:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:26:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:26:50 INFO - 'gecko_profile': False, 18:26:50 INFO - 'gecko_profile_interval': 0, 18:26:50 INFO - 'installer_path': 'installer.exe', 18:26:50 INFO - 'log_level': 'info', 18:26:50 INFO - 'log_name': 'talos', 18:26:50 INFO - 'log_to_console': True, 18:26:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:26:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:26:50 INFO - 'opt_config_files': (), 18:26:50 INFO - 'pip_index': False, 18:26:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:26:50 INFO - 'cmd': ('bash', 18:26:50 INFO - '-c', 18:26:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:26:50 INFO - 'enabled': True, 18:26:50 INFO - 'halt_on_failure': False, 18:26:50 INFO - 'name': 'check_screen_resolution'},), 18:26:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:26:50 INFO - 'cmd': ('bash', 18:26:50 INFO - '-c', 18:26:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:26:50 INFO - 'enabled': True, 18:26:50 INFO - 'halt_on_failure': False, 18:26:50 INFO - 'name': 'check_screen_resolution'},), 18:26:50 INFO - 'run_cmd_checks_enabled': True, 18:26:50 INFO - 'suite': 'dromaeojs-e10s', 18:26:50 INFO - 'system_bits': '32', 18:26:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:26:50 INFO - 'title': 't-yosemite-r7-0211', 18:26:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:26:50 INFO - 'use_talos_json': True, 18:26:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:26:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:26:50 INFO - 'work_dir': 'build'} 18:26:50 INFO - [mozharness: 2017-04-11 01:26:50.062059Z] Running clobber step. 18:26:50 INFO - Running pre-action listener: _resource_record_pre_action 18:26:50 INFO - Running main action method: clobber 18:26:50 INFO - rmtree: /builds/slave/test/build 18:26:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:26:52 INFO - Running post-action listener: _resource_record_post_action 18:26:52 INFO - [mozharness: 2017-04-11 01:26:52.250473Z] Finished clobber step (success) 18:26:52 INFO - [mozharness: 2017-04-11 01:26:52.250769Z] Running read-buildbot-config step. 18:26:52 INFO - Running pre-action listener: _resource_record_pre_action 18:26:52 INFO - Running main action method: read_buildbot_config 18:26:52 INFO - Using buildbot properties: 18:26:52 INFO - { 18:26:52 INFO - "project": "", 18:26:52 INFO - "product": "firefox", 18:26:52 INFO - "script_repo_revision": "default", 18:26:52 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 18:26:52 INFO - "repository": "", 18:26:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 18:26:52 INFO - "buildid": "20170410113323", 18:26:52 INFO - "pgo_build": "False", 18:26:52 INFO - "basedir": "/builds/slave/test", 18:26:52 INFO - "buildnumber": 0, 18:26:52 INFO - "slavename": "t-yosemite-r7-0211", 18:26:52 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 18:26:52 INFO - "platform": "yosemite_r7", 18:26:52 INFO - "scheduler": "tests-pine-macosx64-talos", 18:26:52 INFO - "branch": "pine", 18:26:52 INFO - "repo_path": "projects/pine", 18:26:52 INFO - "revision": "e516c0daef83fdd6542da4d74cabde0683533450", 18:26:52 INFO - "stage_platform": "macosx64", 18:26:52 INFO - "builduid": "4862cd4d03cb4346a1bf4b763ca80c5a", 18:26:52 INFO - "slavebuilddir": "test" 18:26:52 INFO - } 18:26:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 18:26:52 INFO - Running post-action listener: _resource_record_post_action 18:26:52 INFO - [mozharness: 2017-04-11 01:26:52.256714Z] Finished read-buildbot-config step (success) 18:26:52 INFO - [mozharness: 2017-04-11 01:26:52.256943Z] Running download-and-extract step. 18:26:52 INFO - Running pre-action listener: _resource_record_pre_action 18:26:52 INFO - Running main action method: download_and_extract 18:26:52 INFO - mkdir: /builds/slave/test/build/tests 18:26:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:52 INFO - trying https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:26:52 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:52 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:53 INFO - Downloaded 1325 bytes. 18:26:53 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:26:53 INFO - Using the following test package requirements: 18:26:53 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:26:53 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:26:53 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:26:53 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:26:53 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:26:53 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:26:53 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:26:53 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:26:53 INFO - u'jsshell-mac.zip'], 18:26:53 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:26:53 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:26:53 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:26:53 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:26:53 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:26:53 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:26:53 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:26:53 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:26:53 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:26:53 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:26:53 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:26:53 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:26:53 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:53 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:53 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:55 INFO - Expected file size: 52584660 18:26:55 INFO - Obtained file size: 52584660 18:27:11 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:27:11 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:27:11 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:27:11 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:27:13 INFO - Expected file size: 52584660 18:27:13 INFO - Obtained file size: 52584660 18:27:28 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:27:28 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:27:28 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:29 INFO - Expected file size: 14405596 18:27:29 INFO - Obtained file size: 14405596 18:27:29 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:29 INFO - trying https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:27:29 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:29 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:31 INFO - Downloaded 70988642 bytes. 18:27:31 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:31 INFO - mkdir: /builds/slave/test/properties 18:27:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:27:31 INFO - Writing to file /builds/slave/test/properties/build_url 18:27:31 INFO - Contents: 18:27:31 INFO - build_url:https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:27:32 INFO - Running post-action listener: _resource_record_post_action 18:27:32 INFO - Running post-action listener: set_extra_try_arguments 18:27:32 INFO - [mozharness: 2017-04-11 01:27:32.531357Z] Finished download-and-extract step (success) 18:27:32 INFO - [mozharness: 2017-04-11 01:27:32.531471Z] Running populate-webroot step. 18:27:32 INFO - Running pre-action listener: _resource_record_pre_action 18:27:32 INFO - Running main action method: populate_webroot 18:27:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:27:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:27:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:27:32 INFO - '--tppagecycles', 18:27:32 INFO - '7'], 18:27:32 INFO - 'tests': ['tsvgm']}}, 18:27:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:27:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:27:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:27:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:27:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:27:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:27:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:27:32 INFO - 'talos_options': ['--disable-e10s'], 18:27:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:27:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:27:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:27:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:27:32 INFO - 'talos_options': ['--disable-e10s'], 18:27:32 INFO - 'tests': ['damp', 'tps']}, 18:27:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:27:32 INFO - 'tests': ['damp', 'tps']}, 18:27:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:27:32 INFO - 'tests': ['dromaeo_dom']}, 18:27:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:27:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:27:32 INFO - 'tests': ['basic_compositor_video']}, 18:27:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:27:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:27:32 INFO - 'tests': ['a11yr', 18:27:32 INFO - 'ts_paint', 18:27:32 INFO - 'tpaint', 18:27:32 INFO - 'sessionrestore', 18:27:32 INFO - 'sessionrestore_no_auto_restore', 18:27:32 INFO - 'tabpaint']}, 18:27:32 INFO - 'other-e10s': {'tests': ['a11yr', 18:27:32 INFO - 'ts_paint', 18:27:32 INFO - 'tpaint', 18:27:32 INFO - 'sessionrestore', 18:27:32 INFO - 'sessionrestore_no_auto_restore', 18:27:32 INFO - 'tabpaint']}, 18:27:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:27:32 INFO - 'ts_paint', 18:27:32 INFO - 'tpaint', 18:27:32 INFO - 'sessionrestore', 18:27:32 INFO - 'sessionrestore_no_auto_restore', 18:27:32 INFO - 'tabpaint']}, 18:27:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:27:32 INFO - 'ts_paint', 18:27:32 INFO - 'tpaint', 18:27:32 INFO - 'sessionrestore', 18:27:32 INFO - 'sessionrestore_no_auto_restore', 18:27:32 INFO - 'tabpaint']}, 18:27:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:27:32 INFO - 'tests': ['a11yr', 18:27:32 INFO - 'ts_paint', 18:27:32 INFO - 'tpaint', 18:27:32 INFO - 'sessionrestore', 18:27:32 INFO - 'sessionrestore_no_auto_restore', 18:27:32 INFO - 'tabpaint']}, 18:27:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:27:32 INFO - 'tests': ['a11yr', 18:27:32 INFO - 'ts_paint', 18:27:32 INFO - 'tpaint', 18:27:32 INFO - 'sessionrestore', 18:27:32 INFO - 'sessionrestore_no_auto_restore', 18:27:32 INFO - 'tabpaint']}, 18:27:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:27:32 INFO - 'tests': ['tsvgx', 18:27:32 INFO - 'tsvgr_opacity', 18:27:32 INFO - 'tart', 18:27:32 INFO - 'tscrollx', 18:27:32 INFO - 'cart', 18:27:32 INFO - 'tsvg_static']}, 18:27:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:27:32 INFO - 'tsvgr_opacity', 18:27:32 INFO - 'tart', 18:27:32 INFO - 'tscrollx', 18:27:32 INFO - 'cart', 18:27:32 INFO - 'tsvg_static']}, 18:27:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:27:32 INFO - 'talos_options': ['--disable-e10s'], 18:27:32 INFO - 'tests': ['tp5o']}, 18:27:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:27:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:27:32 INFO - 'talos_options': ['--disable-e10s', 18:27:32 INFO - '--xperf_path', 18:27:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:27:32 INFO - 'tests': ['tp5n']}, 18:27:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:27:32 INFO - 'talos_options': ['--xperf_path', 18:27:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:27:32 INFO - 'tests': ['tp5n']}}, 18:27:32 INFO - 'talos.zip': {'path': '', 18:27:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:27:32 INFO - Running post-action listener: _resource_record_post_action 18:27:32 INFO - [mozharness: 2017-04-11 01:27:32.549806Z] Finished populate-webroot step (success) 18:27:32 INFO - [mozharness: 2017-04-11 01:27:32.549909Z] Running create-virtualenv step. 18:27:32 INFO - Running pre-action listener: _resource_record_pre_action 18:27:32 INFO - Running main action method: create_virtualenv 18:27:32 INFO - Creating virtualenv /builds/slave/test/build/venv 18:27:32 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:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:27:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:27:32 INFO - Using real prefix '/tools/python27' 18:27:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:27:33 INFO - Installing distribute.............................................................................................................................................................................................done. 18:27:36 INFO - Installing pip.................done. 18:27:36 INFO - Return code: 0 18:27:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:27:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:27:36 INFO - Reading from file tmpfile_stdout 18:27:36 INFO - Output received: 18:27:36 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:36 INFO - Installing pip>=1.5 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', '--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 0x10293db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102940768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1b0ce8de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10271bd30>, '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 0x102642db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102710be8>, '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.wiZuW7p1NY/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.MyV6nLpPLb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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', '--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:36 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:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wiZuW7p1NY/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.MyV6nLpPLb/Listeners', 18:27:36 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:36 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:36 INFO - Cleaning up... 18:27:36 INFO - Return code: 0 18:27:36 INFO - Installing psutil>=3.1.1 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', '--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 0x10293db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102940768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1b0ce8de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10271bd30>, '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 0x102642db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102710be8>, '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.wiZuW7p1NY/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.MyV6nLpPLb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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', '--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:36 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:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wiZuW7p1NY/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.MyV6nLpPLb/Listeners', 18:27:36 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 - Downloading/unpacking psutil>=3.1.1 18:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:27:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:27:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:27:40 INFO - Installing collected packages: psutil 18:27:40 INFO - Running setup.py install for psutil 18:27:40 INFO - building 'psutil._psutil_osx' extension 18:27:40 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:40 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:40 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:40 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:41 INFO - building 'psutil._psutil_posix' extension 18:27:41 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:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:27:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:27:41 INFO - ^ 18:27:41 INFO - 1 warning generated. 18:27:41 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:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:27:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:27:41 INFO - Successfully installed psutil 18:27:41 INFO - Cleaning up... 18:27:41 INFO - Return code: 0 18:27:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:27:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10293db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102940768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1b0ce8de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10271bd30>, '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 0x102642db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102710be8>, '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.wiZuW7p1NY/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.MyV6nLpPLb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:27:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:27:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wiZuW7p1NY/Render', 18:27:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:41 INFO - 'HOME': '/Users/cltbld', 18:27:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:41 INFO - 'LOGNAME': 'cltbld', 18:27:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:41 INFO - 'MOZ_NO_REMOTE': '1', 18:27:41 INFO - 'NO_EM_RESTART': '1', 18:27:41 INFO - 'PAGER': '/bin/cat', 18:27:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:41 INFO - 'PWD': '/builds/slave/test', 18:27:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:41 INFO - 'SHELL': '/bin/bash', 18:27:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MyV6nLpPLb/Listeners', 18:27:41 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:27:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:41 INFO - 'USER': 'cltbld', 18:27:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:41 INFO - 'XPC_FLAGS': '0x0', 18:27:41 INFO - 'XPC_SERVICE_NAME': '0', 18:27:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:27:41 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:41 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:41 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:41 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:41 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:41 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:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:27:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:27:44 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:44 INFO - Installing collected packages: mozsystemmonitor 18:27:44 INFO - Running setup.py install for mozsystemmonitor 18:27:44 INFO - Successfully installed mozsystemmonitor 18:27:44 INFO - Cleaning up... 18:27:44 INFO - Return code: 0 18:27:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10293db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102940768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1b0ce8de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10271bd30>, '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 0x102642db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102710be8>, '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.wiZuW7p1NY/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.MyV6nLpPLb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:27:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:27:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wiZuW7p1NY/Render', 18:27:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:45 INFO - 'HOME': '/Users/cltbld', 18:27:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:45 INFO - 'LOGNAME': 'cltbld', 18:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:45 INFO - 'MOZ_NO_REMOTE': '1', 18:27:45 INFO - 'NO_EM_RESTART': '1', 18:27:45 INFO - 'PAGER': '/bin/cat', 18:27:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:45 INFO - 'PWD': '/builds/slave/test', 18:27:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:45 INFO - 'SHELL': '/bin/bash', 18:27:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MyV6nLpPLb/Listeners', 18:27:45 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:27:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:45 INFO - 'USER': 'cltbld', 18:27:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:45 INFO - 'XPC_FLAGS': '0x0', 18:27:45 INFO - 'XPC_SERVICE_NAME': '0', 18:27:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:45 INFO - Downloading/unpacking jsonschema==2.5.1 18:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:27:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:27:48 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:48 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:48 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:48 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:48 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:48 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:49 INFO - Downloading functools32-3.2.3-2.tar.gz 18:27:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:27:49 INFO - warning: no files found matching '*.txt' 18:27:49 INFO - no previously-included directories found matching 'build' 18:27:49 INFO - no previously-included directories found matching 'dist' 18:27:49 INFO - no previously-included directories found matching '.git*' 18:27:49 INFO - Installing collected packages: jsonschema, functools32 18:27:49 INFO - Running setup.py install for functools32 18:27:49 INFO - warning: no files found matching '*.txt' 18:27:49 INFO - no previously-included directories found matching 'build' 18:27:49 INFO - no previously-included directories found matching 'dist' 18:27:49 INFO - no previously-included directories found matching '.git*' 18:27:49 INFO - Successfully installed jsonschema functools32 18:27:49 INFO - Cleaning up... 18:27:49 INFO - Return code: 0 18:27:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10293db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102940768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1b0ce8de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10271bd30>, '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 0x102642db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102710be8>, '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.wiZuW7p1NY/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.MyV6nLpPLb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:27:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:27:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wiZuW7p1NY/Render', 18:27:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:49 INFO - 'HOME': '/Users/cltbld', 18:27:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:49 INFO - 'LOGNAME': 'cltbld', 18:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:49 INFO - 'MOZ_NO_REMOTE': '1', 18:27:49 INFO - 'NO_EM_RESTART': '1', 18:27:49 INFO - 'PAGER': '/bin/cat', 18:27:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:49 INFO - 'PWD': '/builds/slave/test', 18:27:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:49 INFO - 'SHELL': '/bin/bash', 18:27:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MyV6nLpPLb/Listeners', 18:27:49 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:27:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:49 INFO - 'USER': 'cltbld', 18:27:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:49 INFO - 'XPC_FLAGS': '0x0', 18:27:49 INFO - 'XPC_SERVICE_NAME': '0', 18:27:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:27:49 INFO - Cleaning up... 18:27:49 INFO - Return code: 0 18:27:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10293db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102940768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1b0ce8de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10271bd30>, '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 0x102642db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102710be8>, '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.wiZuW7p1NY/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.MyV6nLpPLb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:27:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:27:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wiZuW7p1NY/Render', 18:27:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:49 INFO - 'HOME': '/Users/cltbld', 18:27:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:49 INFO - 'LOGNAME': 'cltbld', 18:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:49 INFO - 'MOZ_NO_REMOTE': '1', 18:27:49 INFO - 'NO_EM_RESTART': '1', 18:27:49 INFO - 'PAGER': '/bin/cat', 18:27:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:49 INFO - 'PWD': '/builds/slave/test', 18:27:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:49 INFO - 'SHELL': '/bin/bash', 18:27:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MyV6nLpPLb/Listeners', 18:27:49 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:27:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:49 INFO - 'USER': 'cltbld', 18:27:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:49 INFO - 'XPC_FLAGS': '0x0', 18:27:49 INFO - 'XPC_SERVICE_NAME': '0', 18:27:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:49 INFO - Downloading/unpacking blobuploader==1.2.4 18:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:52 INFO - Downloading blobuploader-1.2.4.tar.gz 18:27:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:27:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:27:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:27:53 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:53 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:53 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:53 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:53 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:53 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:53 INFO - Downloading docopt-0.6.1.tar.gz 18:27:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:27:53 INFO - Installing collected packages: blobuploader, requests, docopt 18:27:53 INFO - Running setup.py install for blobuploader 18:27:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:27:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:27:54 INFO - Running setup.py install for requests 18:27:54 INFO - Running setup.py install for docopt 18:27:54 INFO - Successfully installed blobuploader requests docopt 18:27:54 INFO - Cleaning up... 18:27:54 INFO - Return code: 0 18:27:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:54 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 0x10293db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102940768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1b0ce8de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10271bd30>, '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 0x102642db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102710be8>, '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.wiZuW7p1NY/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.MyV6nLpPLb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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:54 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:54 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:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wiZuW7p1NY/Render', 18:27:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:54 INFO - 'HOME': '/Users/cltbld', 18:27:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:54 INFO - 'LOGNAME': 'cltbld', 18:27:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:54 INFO - 'MOZ_NO_REMOTE': '1', 18:27:54 INFO - 'NO_EM_RESTART': '1', 18:27:54 INFO - 'PAGER': '/bin/cat', 18:27:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:54 INFO - 'PWD': '/builds/slave/test', 18:27:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:54 INFO - 'SHELL': '/bin/bash', 18:27:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MyV6nLpPLb/Listeners', 18:27:54 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:27:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:54 INFO - 'USER': 'cltbld', 18:27:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:54 INFO - 'XPC_FLAGS': '0x0', 18:27:54 INFO - 'XPC_SERVICE_NAME': '0', 18:27:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:27:55 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-Jo1ss2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:27:55 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-TmWK9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:27:55 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-amrvfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:27:55 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-0Rfg14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:27:55 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-9MYrTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:27:55 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-u5uNBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:27:55 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-bJDn1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:27:55 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-3b4B6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:27:55 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-nK5Udm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:27:55 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-bb63fx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:27:56 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-MnQTB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:27:56 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-pOOLx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:27:56 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-sUn4Y8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:27:56 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-EjpJsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:27:56 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-_qLP40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:27:56 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-C7U742-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:27:56 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-QJ0HF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:27:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:27:56 INFO - Running setup.py install for manifestparser 18:27:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:27:56 INFO - Running setup.py install for mozcrash 18:27:57 INFO - Running setup.py install for mozdebug 18:27:57 INFO - Running setup.py install for mozdevice 18:27:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:27:57 INFO - Running setup.py install for mozfile 18:27:57 INFO - Running setup.py install for mozhttpd 18:27:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:27:57 INFO - Running setup.py install for mozinfo 18:27:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:27:57 INFO - Running setup.py install for mozInstall 18:27:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:27:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:27:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:27:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:27:57 INFO - Running setup.py install for mozleak 18:27:58 INFO - Running setup.py install for mozlog 18:27:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:27:58 INFO - Running setup.py install for moznetwork 18:27:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:27:58 INFO - Running setup.py install for mozprocess 18:27:58 INFO - Running setup.py install for mozprofile 18:27:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:27:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:27:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:27:58 INFO - Running setup.py install for mozrunner 18:27:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:27:58 INFO - Running setup.py install for mozscreenshot 18:27:59 INFO - Running setup.py install for moztest 18:27:59 INFO - Running setup.py install for mozversion 18:27:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:27:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:27:59 INFO - Cleaning up... 18:27:59 INFO - Return code: 0 18:27:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:59 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 0x10293db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102940768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1b0ce8de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10271bd30>, '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 0x102642db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102710be8>, '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.wiZuW7p1NY/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.MyV6nLpPLb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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:59 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:59 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:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wiZuW7p1NY/Render', 18:27:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:59 INFO - 'HOME': '/Users/cltbld', 18:27:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:59 INFO - 'LOGNAME': 'cltbld', 18:27:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:59 INFO - 'MOZ_NO_REMOTE': '1', 18:27:59 INFO - 'NO_EM_RESTART': '1', 18:27:59 INFO - 'PAGER': '/bin/cat', 18:27:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:59 INFO - 'PWD': '/builds/slave/test', 18:27:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:59 INFO - 'SHELL': '/bin/bash', 18:27:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MyV6nLpPLb/Listeners', 18:27:59 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:27:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:59 INFO - 'USER': 'cltbld', 18:27:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:59 INFO - 'XPC_FLAGS': '0x0', 18:27:59 INFO - 'XPC_SERVICE_NAME': '0', 18:27:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:27:59 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-5O03vI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:27:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:27:59 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-Vr5Qr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:27:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:27:59 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-iaqhV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:27:59 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:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:27:59 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-DgeWAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:28:00 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:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:28:00 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-vg4FQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:28:00 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:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:28:00 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-fyQGOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:28:00 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:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:28:00 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-h7vES5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:28:00 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:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:28:00 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-7TLcdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:28:00 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:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:28:00 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-Uc7U4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:28:00 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:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:28:00 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-HzZocd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:28:00 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:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:28:00 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-4zEfcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:28:00 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:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:28:00 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-YIhTgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:28:00 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:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:28:00 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-N9cS_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:28:01 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:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:28:01 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-qziToY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:28:01 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:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:28:01 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-TuPQgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:28:01 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:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:28:01 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-NMvE5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:28:01 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:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:28:01 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-N6_mlp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:28:01 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:28:01 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:28:01 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:28:01 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:28:01 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:28:01 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:28:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:28:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:04 INFO - Downloading blessings-1.6.tar.gz 18:28:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:28:04 INFO - Installing collected packages: blessings 18:28:04 INFO - Running setup.py install for blessings 18:28:04 INFO - Successfully installed blessings 18:28:04 INFO - Cleaning up... 18:28:04 INFO - Return code: 0 18:28:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:28:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:28:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:28:04 INFO - Reading from file tmpfile_stdout 18:28:04 INFO - Current package versions: 18:28:04 INFO - blessings == 1.6 18:28:04 INFO - blobuploader == 1.2.4 18:28:04 INFO - docopt == 0.6.1 18:28:04 INFO - functools32 == 3.2.3-2 18:28:04 INFO - jsonschema == 2.5.1 18:28:04 INFO - manifestparser == 1.1 18:28:04 INFO - mozInstall == 1.12 18:28:04 INFO - mozcrash == 1.0 18:28:04 INFO - mozdebug == 0.1 18:28:04 INFO - mozdevice == 0.50 18:28:04 INFO - mozfile == 1.2 18:28:04 INFO - mozhttpd == 0.7 18:28:04 INFO - mozinfo == 0.9 18:28:04 INFO - mozleak == 0.1 18:28:04 INFO - mozlog == 3.4 18:28:04 INFO - moznetwork == 0.27 18:28:04 INFO - mozprocess == 0.25 18:28:04 INFO - mozprofile == 0.28 18:28:04 INFO - mozrunner == 6.13 18:28:04 INFO - mozscreenshot == 0.1 18:28:04 INFO - mozsystemmonitor == 0.3 18:28:04 INFO - moztest == 0.8 18:28:04 INFO - mozversion == 1.4 18:28:04 INFO - psutil == 3.1.1 18:28:04 INFO - requests == 1.2.3 18:28:04 INFO - wsgiref == 0.1.2 18:28:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:28:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:04 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 0x10293db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102940768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1b0ce8de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10271bd30>, '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 0x102642db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102710be8>, '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.wiZuW7p1NY/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.MyV6nLpPLb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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:28:04 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:28:04 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:28:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wiZuW7p1NY/Render', 18:28:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:28:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:28:04 INFO - 'HOME': '/Users/cltbld', 18:28:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:28:04 INFO - 'LOGNAME': 'cltbld', 18:28:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:04 INFO - 'MOZ_NO_REMOTE': '1', 18:28:04 INFO - 'NO_EM_RESTART': '1', 18:28:04 INFO - 'PAGER': '/bin/cat', 18:28:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:28:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:04 INFO - 'PWD': '/builds/slave/test', 18:28:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:28:04 INFO - 'SHELL': '/bin/bash', 18:28:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MyV6nLpPLb/Listeners', 18:28:04 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:28:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:28:04 INFO - 'USER': 'cltbld', 18:28:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:28:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:28:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:04 INFO - 'XPC_FLAGS': '0x0', 18:28:04 INFO - 'XPC_SERVICE_NAME': '0', 18:28:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:28:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:04 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:28:04 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:28:04 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:28:04 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:28:04 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:28:04 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:28:04 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:28:04 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:28:04 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:28:04 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:28:04 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:28:04 INFO - Cleaning up... 18:28:04 INFO - Return code: 0 18:28:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:28:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:04 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 0x10293db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102940768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1b0ce8de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10271bd30>, '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 0x102642db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102710be8>, '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.wiZuW7p1NY/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.MyV6nLpPLb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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:28:04 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:28:04 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:28:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wiZuW7p1NY/Render', 18:28:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:28:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:28:04 INFO - 'HOME': '/Users/cltbld', 18:28:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:28:04 INFO - 'LOGNAME': 'cltbld', 18:28:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:04 INFO - 'MOZ_NO_REMOTE': '1', 18:28:04 INFO - 'NO_EM_RESTART': '1', 18:28:04 INFO - 'PAGER': '/bin/cat', 18:28:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:28:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:04 INFO - 'PWD': '/builds/slave/test', 18:28:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:28:04 INFO - 'SHELL': '/bin/bash', 18:28:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MyV6nLpPLb/Listeners', 18:28:04 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:28:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:28:04 INFO - 'USER': 'cltbld', 18:28:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:28:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:28:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:04 INFO - 'XPC_FLAGS': '0x0', 18:28:04 INFO - 'XPC_SERVICE_NAME': '0', 18:28:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:28:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:28:05 INFO - Cleaning up... 18:28:05 INFO - Return code: 0 18:28:05 INFO - Running post-action listener: _resource_record_post_action 18:28:05 INFO - Running post-action listener: _start_resource_monitoring 18:28:05 INFO - Starting resource monitoring. 18:28:05 INFO - [mozharness: 2017-04-11 01:28:05.252058Z] Finished create-virtualenv step (success) 18:28:05 INFO - [mozharness: 2017-04-11 01:28:05.253033Z] Running install step. 18:28:05 INFO - Running pre-action listener: _resource_record_pre_action 18:28:05 INFO - Running main action method: install 18:28:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:28:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:28:05 INFO - Reading from file tmpfile_stdout 18:28:05 INFO - Detecting whether we're running mozinstall >=1.0... 18:28:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:28:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:28:05 INFO - Reading from file tmpfile_stdout 18:28:05 INFO - Output received: 18:28:05 INFO - Usage: mozinstall [options] installer 18:28:05 INFO - Options: 18:28:05 INFO - -h, --help show this help message and exit 18:28:05 INFO - -d DEST, --destination=DEST 18:28:05 INFO - Directory to install application into. [default: 18:28:05 INFO - "/builds/slave/test"] 18:28:05 INFO - --app=APP Application being installed. [default: firefox] 18:28:05 INFO - mkdir: /builds/slave/test/build/application 18:28:05 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:28:05 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:28:25 INFO - Reading from file tmpfile_stdout 18:28:25 INFO - Output received: 18:28:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:28:25 INFO - Running post-action listener: _resource_record_post_action 18:28:25 INFO - [mozharness: 2017-04-11 01:28:25.658099Z] Finished install step (success) 18:28:25 INFO - [mozharness: 2017-04-11 01:28:25.658242Z] Running run-tests step. 18:28:25 INFO - Running pre-action listener: _resource_record_pre_action 18:28:25 INFO - Running main action method: run_tests 18:28:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:28:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:28:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:28:25 INFO - 2017-04-10 18:28:25.673 screenresolution[1908:12533] starting screenresolution argv=screenresolution get 18:28:25 INFO - 2017-04-10 18:28:25.675 screenresolution[1908:12533] Display 0: 1600x1200x32@60 18:28:25 INFO - 2017-04-10 18:28:25.681 screenresolution[1909:12536] starting screenresolution argv=screenresolution list 18:28:25 INFO - Available Modes on Display 0 18:28:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:28:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:28:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:28:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:28:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:28:25 INFO - Intel Iris: 18:28:25 INFO - Chipset Model: Intel Iris 18:28:25 INFO - Type: GPU 18:28:25 INFO - Bus: Built-In 18:28:25 INFO - VRAM (Dynamic, Max): 1536 MB 18:28:25 INFO - Vendor: Intel (0x8086) 18:28:25 INFO - Device ID: 0x0a2e 18:28:25 INFO - Revision ID: 0x0009 18:28:25 INFO - Displays: 18:28:25 INFO - Display: 18:28:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:28:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:28:25 INFO - Display Serial Number: 1600x1200 60 18:28:25 INFO - Main Display: Yes 18:28:25 INFO - Mirror: Off 18:28:25 INFO - Online: Yes 18:28:25 INFO - Rotation: Supported 18:28:25 INFO - Return code: 0 18:28:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:28:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:28:25 INFO - Python 2.7.3 18:28:25 INFO - Return code: 0 18:28:25 INFO - grabbing minidump binary from tooltool 18:28:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:28:25 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 0x10271bd30>, '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 0x102642db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102710be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:28:25 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:28:25 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:28:25 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:28:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:28:26 INFO - Return code: 0 18:28:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:28:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:28:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:28:26 INFO - ENV: RUST_BACKTRACE is now 1 18:28:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:28:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:28:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--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-0211', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:28:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --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-0211 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:28:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wiZuW7p1NY/Render', 18:28:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:28:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:28:26 INFO - 'HOME': '/Users/cltbld', 18:28:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:28:26 INFO - 'LOGNAME': 'cltbld', 18:28:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:28:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:28:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:26 INFO - 'MOZ_NO_REMOTE': '1', 18:28:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:28:26 INFO - 'NO_EM_RESTART': '1', 18:28:26 INFO - 'PAGER': '/bin/cat', 18:28:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:28:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:26 INFO - 'PWD': '/builds/slave/test', 18:28:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:28:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:28:26 INFO - 'RUST_BACKTRACE': '1', 18:28:26 INFO - 'SHELL': '/bin/bash', 18:28:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MyV6nLpPLb/Listeners', 18:28:26 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:28:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:28:26 INFO - 'USER': 'cltbld', 18:28:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:28:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:28:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:26 INFO - 'XPC_FLAGS': '0x0', 18:28:26 INFO - 'XPC_SERVICE_NAME': '0', 18:28:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:28:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--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-0211', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:28:26 INFO - mozversion application_buildid: 20170410113323 18:28:26 INFO - mozversion application_changeset: e516c0daef83fdd6542da4d74cabde0683533450 18:28:26 INFO - mozversion application_display_name: Nightly 18:28:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:28:26 INFO - mozversion application_name: Firefox 18:28:26 INFO - mozversion application_remotingname: firefox 18:28:26 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 18:28:26 INFO - mozversion application_vendor: Mozilla 18:28:26 INFO - mozversion application_version: 55.0a1 18:28:26 INFO - mozversion platform_buildid: 20170410113323 18:28:26 INFO - mozversion platform_changeset: e516c0daef83fdd6542da4d74cabde0683533450 18:28:26 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 18:28:26 INFO - mozversion platform_version: 55.0a1 18:28:26 INFO - using testdate: 1491874106 18:28:26 INFO - actual date: 1491874106 18:28:26 INFO - starting webserver on 'localhost:49672' 18:28:26 INFO - SUITE-START | Running 2 tests 18:28:26 INFO - TEST-START | dromaeo_css 18:28:26 INFO - operating with platform_type : mac_ 18:28:26 INFO - Initialising browser for dromaeo_css test... 18:28:26 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/tmpPIQ_gH/profile http://localhost:49672/getInfo.html) 18:28:29 INFO - PID 1919 | 2017-04-10 18:28:29.282 plugin-container[1921:12702] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 18:28:29 INFO - PID 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:28:29 INFO - PID 1919 | 2017-04-10 18:28:29.285 plugin-container[1921:12702] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 18:28:29 INFO - PID 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:28:29 INFO - PID 1919 | 2017-04-10 18:28:29.285 plugin-container[1921:12702] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:28:29 INFO - PID 1919 | __metrics Screen width/height:1600/1200 18:28:29 INFO - PID 1919 | colorDepth:24 18:28:29 INFO - PID 1919 | Browser inner width/height: 1024/689 18:28:29 INFO - PID 1919 | __metrics 18:28:29 ERROR - PID 1919 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 18:28:30 INFO - TEST-INFO | 1919: exit 0 18:28:30 INFO - Browser initialized. 18:28:30 INFO - Running cycle 1/1 for dromaeo_css test... 18:28:30 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/tmpPIQ_gH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:28:33 INFO - PID 1922 | 2017-04-10 18:28:33.346 plugin-container[1923:12829] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x473b, name = 'com.apple.tsm.portname' 18:28:33 INFO - PID 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:28:33 INFO - PID 1922 | 2017-04-10 18:28:33.347 plugin-container[1923:12829] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 18:28:33 INFO - PID 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:28:33 INFO - PID 1922 | 2017-04-10 18:28:33.347 plugin-container[1923:12829] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:28:43 INFO - PID 1922 | RSS: Main: 235651072 18:28:43 INFO - PID 1922 | 18:31:46 INFO - PID 1922 | Cycle 1(1): loaded http://localhost:49672/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49672/tests/dromaeo/cssquery-ext.html) 18:31:48 INFO - PID 1922 | RSS: Main: 254013440 18:31:48 INFO - PID 1922 | 18:33:31 INFO - PID 1922 | ************************* 18:33:31 INFO - PID 1922 | A coding exception was thrown and uncaught in a Task. 18:33:31 INFO - PID 1922 | 18:33:31 ERROR - PID 1922 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:33:31 INFO - PID 1922 | Full stack: 18:33:31 INFO - PID 1922 | ************************* 18:33:31 INFO - PID 1922 | ************************* 18:33:31 INFO - PID 1922 | A coding exception was thrown and uncaught in a Task. 18:33:31 INFO - PID 1922 | 18:33:31 ERROR - PID 1922 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:33:31 INFO - PID 1922 | Full stack: 18:33:31 INFO - PID 1922 | ************************* 18:33:31 INFO - PID 1922 | ************************* 18:33:31 INFO - PID 1922 | A coding exception was thrown and uncaught in a Task. 18:33:31 INFO - PID 1922 | 18:33:31 ERROR - PID 1922 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:33:31 INFO - PID 1922 | Full stack: 18:33:31 INFO - PID 1922 | ************************* 18:33:31 INFO - PID 1922 | ************************* 18:33:31 INFO - PID 1922 | A coding exception was thrown and uncaught in a Task. 18:33:31 INFO - PID 1922 | 18:33:31 ERROR - PID 1922 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:33:31 INFO - PID 1922 | Full stack: 18:33:31 INFO - PID 1922 | ************************* 18:34:50 INFO - PID 1922 | Cycle 1(1): loaded http://localhost:49672/tests/dromaeo/cssquery-ext.html (next: http://localhost:49672/tests/dromaeo/cssquery-jquery.html) 18:34:50 INFO - PID 1922 | RSS: Main: 260055040 18:34:50 INFO - PID 1922 | 18:37:52 INFO - PID 1922 | Cycle 1(1): loaded http://localhost:49672/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49672/tests/dromaeo/cssquery-mootools.html) 18:37:53 INFO - PID 1922 | RSS: Main: 273043456 18:37:53 INFO - PID 1922 | 18:40:54 INFO - PID 1922 | Cycle 1(1): loaded http://localhost:49672/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49672/tests/dromaeo/cssquery-prototype.html) 18:40:55 INFO - PID 1922 | RSS: Main: 268455936 18:40:55 INFO - PID 1922 | 18:43:58 INFO - PID 1922 | Cycle 1(1): loaded http://localhost:49672/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49672/tests/dromaeo/cssquery-yui.html) 18:43:59 INFO - PID 1922 | RSS: Main: 278896640 18:43:59 INFO - PID 1922 | 18:47:00 INFO - PID 1922 | Cycle 1(1): loaded http://localhost:49672/tests/dromaeo/cssquery-yui.html (next: http://localhost:49672/tests/dromaeo/cssquery-dojo.html) 18:47:01 INFO - PID 1922 | RSS: Main: 272601088 18:47:01 INFO - PID 1922 | 18:47:01 INFO - PID 1922 | __start_tp_report 18:47:01 INFO - PID 1922 | _x_x_mozilla_page_load 18:47:01 INFO - PID 1922 | _x_x_mozilla_page_load_details 18:47:01 INFO - PID 1922 | |i|pagename|runs| 18:47:01 INFO - PID 1922 | |0;dojo.html;3024.455597992361;3526;3549.69827564657;3558.9288214235708;3571.9642803571955;6090.878182436349;6138.447539721425;6148.3851614838495;6193.287771906226;6222.97320942044;6135.324535308553;6163.445289923906;6166.26004879414;6183.25800903891;6287.748490060392;5328.440513746059;5409.134538473845;5455.945440545594;5496.725163741817;5533.750981205855;5351.598630102739;5457.454254574551;5483.561315094783;5495.2856128703415;5508.173773933902;5449.972750136224;5470.124780035176;5495.230667706524;5497.422770609069;5503.367112782022;5371.946280537184;5408.242846001563;5429.348478182644;5444;5488.204210389511;5048.192289233742;5363.222332761728;5369.087255166631;5389.164679474688;5392.379876314235;4730.432348118248;5967.46292833647;6135.877282454326;6219.68901554925;6222.906656400158;6426.2931077581425;6562.2453417856605;6597.406233439014;6628.536002479828;6663.433608143314;6751.932480675179;6760;6768.8307792305095;6770.289119642465;6790.456763458911;6226.034964580406;6300.21247344082;6310.211223597051;6324.525660575429;6332.841678958063;5824.504917082096;6565.080888675589;6758.763443279461;6789.5586786859685;6837.965810170917;6288.402601752826;6480.2547707013355;6508.479321654257;6554.115194448688;6592.044153597702;6528.804135875931;6529.706163222666;6613.074169616258;6621.702023408958;6742;6586.571872828251;6594.604323740495;6598.60408375499;6600.966995164993;6619.50353723463;6284.748610055546;6351.2060992375955;6373.458256048195;6536.019597060384;6604.603723776589;5255.290535777697;5281.207818827206;5307.442718514578;5318.361796584357;5335.11970524859;4671.0891376182;4683.555062269079;4684.812607495662;4702.670813043054;4731.976340118346;5212.192110222922;6222.719977601018;6237.126802247689;6260.499160067275;6290.21372328459;6623.569467984662;6694.866102677919;6716.093327400836;6720.361565651353;6725.159355080615;6241.594296370721;6288.088227207121;6327.367263273728;6334.588251763713;6354.872902541923;5681.318241811009;5911.172435858984;5936.495397891142;5939.940600593939;5944.613600115978;6650.035744816974;6682.76610318656;6800.591964482147;6810.31896810315;6842.281560436082;6682.69927853238;6694.263631000684;6716.899246511404;6720.193576770819;6732.764353247809;6235.532335074979;6626.337366263335;6754.358335958076;6847.07564478789;6866.828329291808;6278.089676996901;6313.084602732486;6529.738810447719;6540.542162048611;6550.541462097798;6693.631850248283;6718.664066796543;6720.6975686093265;6749.392554670103;6766;5299.364076310867;6128.075379514812;6303.054541818764;6480.740770369132;6516.5112616553;6334.524910631629;6411.358864113738;6447.451966582893;6457.160569126171;6459.450946669585;5588.552915766648;6171.321154673072;6270.341614130633;6303.46420554258;6328.557001009885;6349.174607301022;6356.237251529661;6417.2620148681635;6419.26178489461;6426.325235850355;5271.341082364705;6305.779297724557;6312.621242725451;6313.494920406449;6331.2085989251345 18:47:01 INFO - PID 1922 | |1;ext.html;686.9862602747945;798.1419973528457;825.174825174825;1119.6137332620253;1281.8525869524997;29971.700282997164;38211.85364439064;38238.852834414974;38376.23247535047;38434.231315373676;38051.809740951256;38306;38341.616583834155;38403.807980960126;38419.80790096053;8067.314278286352;8180.6318715657935;8220.671173153096;8579.95710021449;8837.160469755363;8691.826163476726;8823.029466758651;8837.690680826172;8862.291016718647;8862.29101671868;8797.912020879774;8805.95597022014;8889.333300002494;8889.733308000737;8891.466512009269;8829.514376709247;8847.247983921374;8854.026057133775;8871.733847984571;8871.866921996176;8642.092580279043;8665.480071195749;8701.869471957925;8713.085126061736;8722.607482663296;37989.81005094985;38142.23715525702;38169.85490435291;38209.23581528381;38233.80883095567;3563.127033876691;3584.5698516178227;3602.8198590070656;3607.837647305851;3608.0438683748825;30284.394312113633;30409.69590304091;30466.695333046606;30551.08346749601;30602.387952241053;2758.213909035915;3156.889508867179;3182.6817318268218;3185.2196211927994;3198.712115909556;215378;220649;251655.74172129206;269914;271253;47265.76367118211;47642;47647.28529072067;47665.5233447668;47755.80610484696;49232.261516077284;49648.50351496511;49838;49934;49945.25082123771;13713.657158571115;13890.444382224598;13898.51355202563;13902.026858120036;13905.443782248596;11220.326780393203;11270.211085223955;11316.20786544934;11334.60328888485;11348.205625606293;11811.822822657667;13536.984726145578;13623.455061797615;13649.727005459836;13737.381817818223;5870.853228669317;5931.703414829328;5943;5948.672822994777;5956.761729530857;1025.3284098915221;1041.6979076068008;1045.132539991805;1067.199600299775;1073.849661047454;21496.892515537635;22025.66961495579;22852;22981.310560683207;24169.637455438184;999.6950929966284;1062.0229388962018;1123.5730422439585;1152.4468255237532;1164.6214980131492;9774.95112524447;11586.942065289619;12286.262824230575;12295.877041229653;12300.261984280973;361546;362298;362545;362876;363179;52941.2058819118;53201;53222.73388633032;53276.46723532715;53331.46668533265;1490.7987421697933;1512.2060918018128;1539.415022291522;1550.3720992997853;1551;20968;21780.237691680715;23820.523589527766;23826.40433989164;23851;33044.339113218084;35121.473177902866;37075.44386834253;38002.61997380101;38022.859314220615;272929.63535182195;316207;321227;321625;324345;28421.857890710413;34359.141021474665;34712.43794029224;34782.47826282658;35391;44850.65448036564;46090.07819843517;46118;46147.538524615666;46367.53632463632;25052.49895002126;30001.849990749906;30392.39215215639;31402.52896206513;31444.842775785975;30448.84775576108;30598.694013059583;31280.374392511567;31422;31449.842750787015;31805.20486987844;36537.634623654485;37095;37101.814490927376;37200.44199336958;25293.747062529874;25856.741432586186;26030.56831874265;26806.65966701696;30354;20532.794672053686;21585.352439426842;23368.415789605395;24055.278341649777;24369.878150609133 18:47:01 INFO - PID 1922 | |2;jquery.html;388.41349562161145;417.62831080338486;422.34325623655207;447.4362303497593;449.55044955044957;19831.801681983194;21681.4579635509;22217.77782222177;22260.332190034325;22399.888000559993;22032.559348813014;22589.661155082646;23490;23579.882100589515;23772.643410348857;5978.133170690258;6148.200733904586;6165.475934545569;6180.1965744453155;6200.534959878005;5747.310322761254;6083.6349819011;6116.44951954324;6124.755009799603;6127.080937859335;5067.265246539249;6058.636481811083;6081.939180608181;6084.969575152118;6088.391160883898;6037.849053773669;6128.44843964041;6128.693565321716;6128.969355153218;6149.077638354238;5379.811706590251;5471.808486702986;5473;5554.555635549187;5585.804496842591;27193.320166995785;27351.589726154125;27766.61166941645;27976.44047119046;28238.435231295465;2225.3991422315885;2292.5300313435705;2299.528596637685;2299.609066458706;2304.3547806614174;17352.219150138273;17440.47678569645;17806.821931780647;18256.726149107773;18386.264549418007;2186.967195492069;2187.6937228787983;2231.4979129695853;2232.285668586053;2239.317008312464;155762;161184;161439;162990;164901.17549412296;12483.50065997368;12503.499860005499;12647.304398258;12786.936065319614;12850.678733031562;13975.79036314456;16453.83546164523;16800.07599582035;16809.41167059147;16865.831341686673;8584.613692383859;8916;8924.55377231136;8951.776205594912;9045.909540904508;8394.832103358021;8402.873956890651;8439.324854011664;8583;8593.44142630727;7812.140664526897;7910.920890791019;7949.364050875916;7958.562279074591;7979.241972012649;1986.3345779163853;2002.8383537548186;2040.7755146933825;2154.9353519394444;2279.5326957973907;913.9131782480654;935.3312381647019;974.9122578967927;997.1922742578535;1032.8708911386077;15656.843431565538;19893;20986.497932340437;21074.262400815896;21377.46556336073;985.4629227071264;1010.3735683876043;1039.667306461925;1040.3653771199563;1052.8368102944055;10647.84028239577;11151.163662725166;11170.497327620276;11175.273607215506;11189;610158;685856;694758;698586;713392;151989;157495;163836;167972.16013919853;168094;1211.6486218996567;1229.7048708309942;1260.7415479826614;1262.987370126287;1264.2351377416715;19985.900070499556;21552.922353881928;22611.886940565193;22863.08547658075;22910;60836.695816522406;62090.75818483515;62379;63601;63710;73200;81577;83767;85158;85828;7847.5683837387205;8919.777005574913;8924.86612700823;9072.364934454525;9335.833020872391;14433.7834932474;14550.4907328243;14629.92685036568;14885.479008234659;14920.477783277533;11994;12149.757004859677;12220.755584888075;12254.387280635754;12427.937860311002;11504.424778761197;11682.70793230176;11694.94152529266;11934.820977685515;12008.939955300168;11297.43512824372;11961.64115076549;12219.75560488803;12417.565385211474;12462.314572698226;8919.42023768453;8960.014398416039;9162.541872906195;9273;9308.534573271174;11965.282083075042;12006;12201.14591978553;12308.446119924445;12336.198147120767 18:47:01 INFO - PID 1922 | |3;mootools.html;1381.6269607206054;1421.6588018875473;1433.3836450326355;1440.4238304678133;1458.6134674311309;11324.490397932092;11383.715407114827;11723.5896743614;11776.175667703264;11777.823332650018;11428.942855285733;11670.124740644444;11690.064794816415;11709.180357374966;11752.882471175286;2477.1949116537153;2526.393665520271;2535.847849129058;2543.8728063596745;2544.5165418570414;2463.7043554773454;2467.3214865911928;2501.762332578402;2502.424442378245;2509.3099397665605;2445.3153117127144;2472.468419289852;2489.3403248139257;2522.9781938315014;2528.645989561463;2433.6957880264877;2473.332200305925;2486.353548077495;2512.635667828173;2515.308290220186;2278.4303924018996;2339.6724458575814;2411.3368823573483;2521.382261345964;2522.7225005249406;11558.826617600742;11606.593769218036;11680.065594752485;11711.238769480042;11737.706557336049;6513.902291465632;6684.064231007663;6784.287649796799;6785.321467853225;6805.319468053155;4067.51189857219;4087.693422993287;4341.761203133827;4413.492448368414;4417.558244175589;1244.6390546741434;1262.671705356614;1308.9541866034642;1311.7638825011495;1311.7704401729754;14962;15574.844251557453;16312.673746525003;16315.265813038443;16456.424025159064;5527.474889885454;5660.971695141498;5804.622699524517;5872.148538461899;5876.265466816648;11787.29276243411;11986.160968732105;12022.759544809054;12042.458089385998;12074.698132546757;6600;6604.768833090819;6616.4376028037195;6619.205695316593;6629.469642428594;6056.36408177135;6152.846178845475;6172.351903050205;6225.844353891189;6229.813105606839;3799.601041890617;4265.87202383929;4418.182636212311;4462.977685111554;4477.664175186809;1312.7965165399378;1329.541308248672;1332.4137379552965;1351.49319005373;1351.86481351866;781.6013832945239;798.7729572526927;801.9959900200579;808.2281421242699;813.4753084260619;5163.277141200235;5577.972110139423;5848.912266316009;6288.52836037299;6454.838629034218;601.9578629495893;610.1122866229625;619.7397093220858;623.5790841182184;625.8372823066036;4094.324436468009;4289.892752681146;4344.847930322424;4526.23054080807;4533.863984080483;14097.295135243197;14705.632359191106;14710.33803478846;14715.96988210815;14718.705625887422;14102.083364581269;14574.344154513072;14578.125312481285;14734.63163420923;14749.262536873113;10415.323004004715;10707;10707.62523311696;10729.731756705989;10736.892631073746;5793.478586927028;5807.303123625023;6801.489888258301;6966.164060312795;6990.056342393712;12788.872111278768;12899.226046437243;13202;13227.3386330685;13288.867111328764;13663.79504307456;13786.931065344608;13818.447262109403;13969.301534923416;14051.859481405056;9145.496997664926;9291.814163716823;9466.810663786548;9568.282378821477;9577.23382129414;7945.6027198640995;9472.668456603986;9713.805723885624;10606.257561970588;10674.57301707923;8999.32505062136;9024.232940200156;9047.276217902683;9181.311401645036;9201.171894529525;7820.530768153929;8307.670772676585;8690;8880.467171969702;9144.542772861198;6957.339052789976;8104.635291412019;8186.058603260463;8210.178982101743;8359.205875441823;5102.4897510248675;5143.3056537368475;6068.969655151696;6085;6127.754889804358;6167.753289868355;6175.325681219667;6203.379662033761;6216.720247588779;6237.345078766664 18:47:01 INFO - PID 1922 | |4;prototype.html;406.41476274165217;407.7064513550244;408.8221623593736;414.47982781609073;417.1177958617523;6245.531585131116;6367.076773867792;6368.203974503187;6382.489400847932;6388.275165705262;5090.338256026721;5921.674307913063;6168.845778855531;6209.875802483948;6235.812925612239;4687.578117969382;4725.929111063337;4732.668713190078;4770.522947705219;4784.492884741313;4505.752183629899;4731.313959475874;4745.525447455244;4757.976210118944;4782.450018247894;4269.850555230568;4643.651726120537;4676.836310729125;4697.107549565589;4703.858884233461;4114.773687447189;4472.709273897202;4683.882902927437;4712.599429048535;4730.763461826895;3961.2247678741787;4164.250434921712;4176.60322269384;4181.414601955728;4190.455240818705;4902.764175644058;5193.454687257859;5291;5700.916825803084;5710.971445142789;5566.55467562594;5587.497125258747;5595.7481913313995;5610.971945140289;5617.21359009739;6447.54867159299;6572.572782769104;6598.307177746316;6641.103451034097;6675.632840193787;4258.382534532476;4691.741954192518;4732.692374995614;4737.549932756383;4739.14695354836;5988.940110598882;6618.635975021371;7010.964945175292;7046.894296585555;7072.611006394646;4788.592969597623;4846.345743324677;4848.884756506023;4871.269309603588;4926.842192084857;4980.726060066731;4989.426215985136;5002.474740152232;5008.17365134756;5014.887792010516;4854.368932038812;5351.331083614548;5510.641808282448;5541.639793413416;5631.2960879890015;6654.968479885625;6666.266710661824;6681.866362672719;6686.264510903797;6702.83242918931;1589.9824112567965;1656.180190805559;1692.1116413882564;1711.2812618699927;1720.7160818465063;1043.2540733375672;1176.9117316201318;1184.8518935133109;1186.5372504723166;1187.8633957094871;5480.671159730429;5535.263697004506;6306.527010474233;6337.37371718648;6407.807765767035;6156.26124865019;6650.634215118215;6691.594765099287;6897.62063086525;6911.6544172791155;6270.184875966095;6327.841803954846;6337.33457986914;6347.952982568393;6379.681015949184;3430.433978393537;3584.2473080653353;3622.840690978914;3624.4925710400566;3631.2011357501306;5704.286964129484;6921.377076063179;7076.973838793346;7090.361867431853;7100.396466300319;6999.790006299819;7059.682314295869;7074;7089.574625522485;7100.46746494015;6044.879102417927;6493.383128602681;6730.966345168243;7111.2533184015965;7135.215126335995;6273.65494897785;6519.380658837403;6889.621070841152;6962.268961758942;7013.789586312419;5924.703764811656;6299.897517934435;6506.869862602627;6635.933640663531;6858.731134740089;5675.971620141873;6907.654617269016;7060.399866011447;7102.573845569283;7143.4999550031;4044.595540445932;4592.816287348469;4885.511448855086;4946.851594452172;4967.776450059684;5442.945570544244;5762.711864406679;6929.965350173217;6940;6947.588281441423;5324.9733751331;6642.833929151617;6657.3009833966735;6710.899336509859;6714.496412768965;5825.41745825431;5974.7296027509265;6048.002079656983;6590.70341834609;6674.532782705358;5454.672719636835;5974.342822289458;6499.285078641251;6508.934910651023;6534.281229064894;4856.878578035577;5716.657000579979;6521.836909099178;6672.228522328054;6695.497837662292;6333.936660633335;6437.549371543947;6482.157319548429;6541.70562324706;6592.373724496165 18:47:01 INFO - PID 1922 | |5;yui.html;833.1293797981109;843.7300063979529;864.7578677970171;887.5029983209402;888.0009988762642;15532.45636402726;16318.592035199112;16352.345906163753;17539.2107355169;18355.265789368408;16649;17042.233099510522;17111.74332385015;17155.399561015365;17155.884867483604;991.6380521109779;1005.3013155856682;1016.872890888639;1016.9288149829515;1019.918406527476;1014.4268488304122;1016.547636301846;1016.588281745894;1017.013496907998;1017.9949100254526;1049.6063976008998;1057.0750593230923;1060.2472244706269;1062.9627963021296;1065.9413732244723;1046.9581216751321;1054.0671505717457;1054.4569546683426;1054.9261551691384;1055.2244100586063;740.1118657610834;790.5730905311125;792.7700966719644;794.8847417124484;795.2882170457408;15725.842741572553;16105.27526261321;16126.951748136333;16138.031718096834;16146.03123812575;16037.2783224754;16401.261943212583;16586.58533536659;16678.49964501067;16789.83210167895;12217.450214740358;13869.861301386958;14113.788293175508;14116.647083822883;14140.646483837883;3390.559227300435;3408.897733068012;3426.0749597608506;3452.2405070884615;3461.8442170102408;101021.989780102;101844;120945;121790;122041;11756.177067605186;12676.30280334572;12987.41556629954;13290;13341.266230357229;23229.53540929172;23620.881895590755;23990.28029159128;24034.39914002164;24059.639105413433;8417.705380311781;8592.5703714814;8670.653173873101;8718.302535797122;8733.781655458664;4445.82216711328;4526.388937493462;4535.727856328631;4546.249868771682;4547.067851090518;3395.37185620656;4332.4367832181615;4335.588119128678;4427.048184640318;4464.464264288306;841.6212704283097;893.8301722672671;925.9583318750538;932.1005229953128;932.5989824375448;625.5839866488825;630.3696303696304;637.9298277189505;683.460066547422;705.47794631973;10560.47197640115;10951.561937522569;11281.548738050551;11452.198346115858;11996.700082497833;595.8152972578514;658.4074333100248;671.8488340123433;689.9034135221073;695.0894328429773;4812.735299558558;5407.242985981966;5484.972575137179;5630.577706671934;5674.347450043216;75287.62356188185;85622.57188714141;88378;91704.6244306336;95810;50899.74550127226;67013.32466688275;83185.3362932738;84807.15192848117;87321;74436;75296.87054694183;75427;76295.61852190703;76315;10724.249302548747;11409.429528523375;11446.255993360737;11956.222845515014;12348.938255309025;29804.70195298106;38001.09994500319;38931.416028760155;39028;39460.07669025157;42528.7241382759;47168.764156179;48205.75897120492;48498.545043648744;48691;15155.696886061996;16217.026978381335;16322.265498052853;16422.67154656924;16552.66894662124;17787.377441789475;17900.820991790435;18146.274149033892;18151.546211344295;18156.45530634083;16313.836861631231;16583.25375358136;16592.917035414746;16666.749998749787;16675.999440033636;16192.67614647724;16381;16581.58546036359;16732.498025059267;16761.497155085366;16084.597885052968;16143.434979775651;16161.75757363664;16383.754243686593;16389.918050409673;11674.649760507198;11888.108391870817;11912;12030.796920308248;12062.215955962834;12648.620541383772;12777.87222127804;12822.551210707581;12822.551210707581;12988.35058247065 18:47:01 INFO - PID 1922 | __end_tp_report 18:47:01 INFO - PID 1922 | __start_cc_report 18:47:01 INFO - PID 1922 | _x_x_mozilla_cycle_collect,426 18:47:01 INFO - PID 1922 | __end_cc_report 18:47:01 INFO - PID 1922 | __startTimestamp1491875221157__endTimestamp 18:47:01 INFO - PID 1922 | 18:47:01 INFO - PID 1922 | ------- Summary: start ------- 18:47:01 INFO - PID 1922 | Number of tests: 6 18:47:01 INFO - PID 1922 | 18:47:01 INFO - PID 1922 | [#0] dojo.html Cycles:165 Average:6076.58 Median:6305.78 stddev:725.54 (11.5%) stddev-sans-first:687.13 18:47:01 INFO - PID 1922 | Values: 3024.5 3526.0 3549.7 3558.9 3572.0 6090.9 6138.4 6148.4 6193.3 6223.0 6135.3 6163.4 6166.3 6183.3 6287.7 5328.4 5409.1 5455.9 5496.7 5533.8 5351.6 5457.5 5483.6 5495.3 5508.2 5450.0 5470.1 5495.2 5497.4 5503.4 5371.9 5408.2 5429.3 5444.0 5488.2 5048.2 5363.2 5369.1 5389.2 5392.4 4730.4 5967.5 6135.9 6219.7 6222.9 6426.3 6562.2 6597.4 6628.5 6663.4 6751.9 6760.0 6768.8 6770.3 6790.5 6226.0 6300.2 6310.2 6324.5 6332.8 5824.5 6565.1 6758.8 6789.6 6838.0 6288.4 6480.3 6508.5 6554.1 6592.0 6528.8 6529.7 6613.1 6621.7 6742.0 6586.6 6594.6 6598.6 6601.0 6619.5 6284.7 6351.2 6373.5 6536.0 6604.6 5255.3 5281.2 5307.4 5318.4 5335.1 4671.1 4683.6 4684.8 4702.7 4732.0 5212.2 6222.7 6237.1 6260.5 6290.2 6623.6 6694.9 6716.1 6720.4 6725.2 6241.6 6288.1 6327.4 6334.6 6354.9 5681.3 5911.2 5936.5 5939.9 5944.6 6650.0 6682.8 6800.6 6810.3 6842.3 6682.7 6694.3 6716.9 6720.2 6732.8 6235.5 6626.3 6754.4 6847.1 6866.8 6278.1 6313.1 6529.7 6540.5 6550.5 6693.6 6718.7 6720.7 6749.4 6766.0 5299.4 6128.1 6303.1 6480.7 6516.5 6334.5 6411.4 6447.5 6457.2 6459.5 5588.6 6171.3 6270.3 6303.5 6328.6 6349.2 6356.2 6417.3 6419.3 6426.3 5271.3 6305.8 6312.6 6313.5 6331.2 18:47:01 INFO - PID 1922 | 18:47:01 INFO - PID 1922 | [#1] ext.html Cycles:180 Average:45335.15 Median:34747.46 stddev:81787.83 (235.4%) stddev-sans-first:81948.56 18:47:01 INFO - PID 1922 | Values: 687.0 798.1 825.2 1119.6 1281.9 29971.7 38211.9 38238.9 38376.2 38434.2 38051.8 38306.0 38341.6 38403.8 38419.8 8067.3 8180.6 8220.7 8580.0 8837.2 8691.8 8823.0 8837.7 8862.3 8862.3 8797.9 8806.0 8889.3 8889.7 8891.5 8829.5 8847.2 8854.0 8871.7 8871.9 8642.1 8665.5 8701.9 8713.1 8722.6 37989.8 38142.2 38169.9 38209.2 38233.8 3563.1 3584.6 3602.8 3607.8 3608.0 30284.4 30409.7 30466.7 30551.1 30602.4 2758.2 3156.9 3182.7 3185.2 3198.7 215378.0 220649.0 251655.7 269914.0 271253.0 47265.8 47642.0 47647.3 47665.5 47755.8 49232.3 49648.5 49838.0 49934.0 49945.3 13713.7 13890.4 13898.5 13902.0 13905.4 11220.3 11270.2 11316.2 11334.6 11348.2 11811.8 13537.0 13623.5 13649.7 13737.4 5870.9 5931.7 5943.0 5948.7 5956.8 1025.3 1041.7 1045.1 1067.2 1073.8 21496.9 22025.7 22852.0 22981.3 24169.6 999.7 1062.0 1123.6 1152.4 1164.6 9775.0 11586.9 12286.3 12295.9 12300.3 361546.0 362298.0 362545.0 362876.0 363179.0 52941.2 53201.0 53222.7 53276.5 53331.5 1490.8 1512.2 1539.4 1550.4 1551.0 20968.0 21780.2 23820.5 23826.4 23851.0 33044.3 35121.5 37075.4 38002.6 38022.9 272929.6 316207.0 321227.0 321625.0 324345.0 28421.9 34359.1 34712.4 34782.5 35391.0 44850.7 46090.1 46118.0 46147.5 46367.5 25052.5 30001.8 30392.4 31402.5 31444.8 30448.8 30598.7 31280.4 31422.0 31449.8 31805.2 36537.6 37095.0 37101.8 37200.4 25293.7 25856.7 26030.6 26806.7 30354.0 20532.8 21585.4 23368.4 24055.3 24369.9 18:47:01 INFO - PID 1922 | 18:47:01 INFO - PID 1922 | [#2] jquery.html Cycles:180 Average:40726.88 Median:12571.31 stddev:114888.13 (913.9%) stddev-sans-first:115170.50 18:47:01 INFO - PID 1922 | Values: 388.4 417.6 422.3 447.4 449.6 19831.8 21681.5 22217.8 22260.3 22399.9 22032.6 22589.7 23490.0 23579.9 23772.6 5978.1 6148.2 6165.5 6180.2 6200.5 5747.3 6083.6 6116.4 6124.8 6127.1 5067.3 6058.6 6081.9 6085.0 6088.4 6037.8 6128.4 6128.7 6129.0 6149.1 5379.8 5471.8 5473.0 5554.6 5585.8 27193.3 27351.6 27766.6 27976.4 28238.4 2225.4 2292.5 2299.5 2299.6 2304.4 17352.2 17440.5 17806.8 18256.7 18386.3 2187.0 2187.7 2231.5 2232.3 2239.3 155762.0 161184.0 161439.0 162990.0 164901.2 12483.5 12503.5 12647.3 12786.9 12850.7 13975.8 16453.8 16800.1 16809.4 16865.8 8584.6 8916.0 8924.6 8951.8 9045.9 8394.8 8402.9 8439.3 8583.0 8593.4 7812.1 7910.9 7949.4 7958.6 7979.2 1986.3 2002.8 2040.8 2154.9 2279.5 913.9 935.3 974.9 997.2 1032.9 15656.8 19893.0 20986.5 21074.3 21377.5 985.5 1010.4 1039.7 1040.4 1052.8 10647.8 11151.2 11170.5 11175.3 11189.0 610158.0 685856.0 694758.0 698586.0 713392.0 151989.0 157495.0 163836.0 167972.2 168094.0 1211.6 1229.7 1260.7 1263.0 1264.2 19985.9 21552.9 22611.9 22863.1 22910.0 60836.7 62090.8 62379.0 63601.0 63710.0 73200.0 81577.0 83767.0 85158.0 85828.0 7847.6 8919.8 8924.9 9072.4 9335.8 14433.8 14550.5 14629.9 14885.5 14920.5 11994.0 12149.8 12220.8 12254.4 12427.9 11504.4 11682.7 11694.9 11934.8 12008.9 11297.4 11961.6 12219.8 12417.6 12462.3 8919.4 8960.0 9162.5 9273.0 9308.5 11965.3 12006.0 12201.1 12308.4 12336.2 18:47:01 INFO - PID 1922 | 18:47:01 INFO - PID 1922 | [#3] mootools.html Cycles:180 Average:7070.39 Median:2544.19 stddev:4452.56 (175.0%) stddev-sans-first:4444.53 18:47:01 INFO - PID 1922 | Values: 1381.6 1421.7 1433.4 1440.4 1458.6 11324.5 11383.7 11723.6 11776.2 11777.8 11428.9 11670.1 11690.1 11709.2 11752.9 2477.2 2526.4 2535.8 2543.9 2544.5 2463.7 2467.3 2501.8 2502.4 2509.3 2445.3 2472.5 2489.3 2523.0 2528.6 2433.7 2473.3 2486.4 2512.6 2515.3 2278.4 2339.7 2411.3 2521.4 2522.7 11558.8 11606.6 11680.1 11711.2 11737.7 6513.9 6684.1 6784.3 6785.3 6805.3 4067.5 4087.7 4341.8 4413.5 4417.6 1244.6 1262.7 1309.0 1311.8 1311.8 14962.0 15574.8 16312.7 16315.3 16456.4 5527.5 5661.0 5804.6 5872.1 5876.3 11787.3 11986.2 12022.8 12042.5 12074.7 6600.0 6604.8 6616.4 6619.2 6629.5 6056.4 6152.8 6172.4 6225.8 6229.8 3799.6 4265.9 4418.2 4463.0 4477.7 1312.8 1329.5 1332.4 1351.5 1351.9 781.6 798.8 802.0 808.2 813.5 5163.3 5578.0 5848.9 6288.5 6454.8 602.0 610.1 619.7 623.6 625.8 4094.3 4289.9 4344.8 4526.2 4533.9 14097.3 14705.6 14710.3 14716.0 14718.7 14102.1 14574.3 14578.1 14734.6 14749.3 10415.3 10707.0 10707.6 10729.7 10736.9 5793.5 5807.3 6801.5 6966.2 6990.1 12788.9 12899.2 13202.0 13227.3 13288.9 13663.8 13786.9 13818.4 13969.3 14051.9 9145.5 9291.8 9466.8 9568.3 9577.2 7945.6 9472.7 9713.8 10606.3 10674.6 8999.3 9024.2 9047.3 9181.3 9201.2 7820.5 8307.7 8690.0 8880.5 9144.5 6957.3 8104.6 8186.1 8210.2 8359.2 5102.5 5143.3 6069.0 6085.0 6127.8 6167.8 6175.3 6203.4 6216.7 6237.3 18:47:01 INFO - PID 1922 | 18:47:01 INFO - PID 1922 | [#4] prototype.html Cycles:180 Average:5406.90 Median:5794.06 stddev:1621.66 (28.0%) stddev-sans-first:1582.18 18:47:01 INFO - PID 1922 | Values: 406.4 407.7 408.8 414.5 417.1 6245.5 6367.1 6368.2 6382.5 6388.3 5090.3 5921.7 6168.8 6209.9 6235.8 4687.6 4725.9 4732.7 4770.5 4784.5 4505.8 4731.3 4745.5 4758.0 4782.5 4269.9 4643.7 4676.8 4697.1 4703.9 4114.8 4472.7 4683.9 4712.6 4730.8 3961.2 4164.3 4176.6 4181.4 4190.5 4902.8 5193.5 5291.0 5700.9 5711.0 5566.6 5587.5 5595.7 5611.0 5617.2 6447.5 6572.6 6598.3 6641.1 6675.6 4258.4 4691.7 4732.7 4737.5 4739.1 5988.9 6618.6 7011.0 7046.9 7072.6 4788.6 4846.3 4848.9 4871.3 4926.8 4980.7 4989.4 5002.5 5008.2 5014.9 4854.4 5351.3 5510.6 5541.6 5631.3 6655.0 6666.3 6681.9 6686.3 6702.8 1590.0 1656.2 1692.1 1711.3 1720.7 1043.3 1176.9 1184.9 1186.5 1187.9 5480.7 5535.3 6306.5 6337.4 6407.8 6156.3 6650.6 6691.6 6897.6 6911.7 6270.2 6327.8 6337.3 6348.0 6379.7 3430.4 3584.2 3622.8 3624.5 3631.2 5704.3 6921.4 7077.0 7090.4 7100.4 6999.8 7059.7 7074.0 7089.6 7100.5 6044.9 6493.4 6731.0 7111.3 7135.2 6273.7 6519.4 6889.6 6962.3 7013.8 5924.7 6299.9 6506.9 6635.9 6858.7 5676.0 6907.7 7060.4 7102.6 7143.5 4044.6 4592.8 4885.5 4946.9 4967.8 5442.9 5762.7 6930.0 6940.0 6947.6 5325.0 6642.8 6657.3 6710.9 6714.5 5825.4 5974.7 6048.0 6590.7 6674.5 5454.7 5974.3 6499.3 6508.9 6534.3 4856.9 5716.7 6521.8 6672.2 6695.5 6333.9 6437.5 6482.2 6541.7 6592.4 18:47:01 INFO - PID 1922 | 18:47:01 INFO - PID 1922 | [#5] yui.html Cycles:180 Average:19918.05 Median:17155.64 stddev:26743.88 (155.9%) stddev-sans-first:26780.50 18:47:01 INFO - PID 1922 | Values: 833.1 843.7 864.8 887.5 888.0 15532.5 16318.6 16352.3 17539.2 18355.3 16649.0 17042.2 17111.7 17155.4 17155.9 991.6 1005.3 1016.9 1016.9 1019.9 1014.4 1016.5 1016.6 1017.0 1018.0 1049.6 1057.1 1060.2 1063.0 1065.9 1047.0 1054.1 1054.5 1054.9 1055.2 740.1 790.6 792.8 794.9 795.3 15725.8 16105.3 16127.0 16138.0 16146.0 16037.3 16401.3 16586.6 16678.5 16789.8 12217.5 13869.9 14113.8 14116.6 14140.6 3390.6 3408.9 3426.1 3452.2 3461.8 101022.0 101844.0 120945.0 121790.0 122041.0 11756.2 12676.3 12987.4 13290.0 13341.3 23229.5 23620.9 23990.3 24034.4 24059.6 8417.7 8592.6 8670.7 8718.3 8733.8 4445.8 4526.4 4535.7 4546.2 4547.1 3395.4 4332.4 4335.6 4427.0 4464.5 841.6 893.8 926.0 932.1 932.6 625.6 630.4 637.9 683.5 705.5 10560.5 10951.6 11281.5 11452.2 11996.7 595.8 658.4 671.8 689.9 695.1 4812.7 5407.2 5485.0 5630.6 5674.3 75287.6 85622.6 88378.0 91704.6 95810.0 50899.7 67013.3 83185.3 84807.2 87321.0 74436.0 75296.9 75427.0 76295.6 76315.0 10724.2 11409.4 11446.3 11956.2 12348.9 29804.7 38001.1 38931.4 39028.0 39460.1 42528.7 47168.8 48205.8 48498.5 48691.0 15155.7 16217.0 16322.3 16422.7 16552.7 17787.4 17900.8 18146.3 18151.5 18156.5 16313.8 16583.3 16592.9 16666.7 16676.0 16192.7 16381.0 16581.6 16732.5 16761.5 16084.6 16143.4 16161.8 16383.8 16389.9 11674.6 11888.1 11912.0 12030.8 12062.2 12648.6 12777.9 12822.6 12822.6 12988.4 18:47:01 INFO - PID 1922 | -------- Summary: end -------- 18:47:01 INFO - PID 1922 | 18:47:01 INFO - TEST-INFO | 1922: exit 0 18:47:01 INFO - TEST-OK | dromaeo_css | took 1115565ms 18:47:01 INFO - TEST-START | kraken 18:47:01 INFO - operating with platform_type : mac_ 18:47:01 INFO - Initialising browser for kraken test... 18:47:01 INFO - TEST-INFO | started process 1929 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/tmpFGQLYe/profile http://localhost:49672/getInfo.html) 18:47:04 INFO - PID 1929 | 2017-04-10 18:47:04.437 plugin-container[1930:15165] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 18:47:04 INFO - PID 1929 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:04 INFO - PID 1929 | 2017-04-10 18:47:04.441 plugin-container[1930:15165] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 18:47:04 INFO - PID 1929 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:04 INFO - PID 1929 | 2017-04-10 18:47:04.441 plugin-container[1930:15165] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:47:04 INFO - PID 1929 | __metrics Screen width/height:1600/1200 18:47:04 INFO - PID 1929 | colorDepth:24 18:47:04 INFO - PID 1929 | Browser inner width/height: 1024/689 18:47:04 INFO - PID 1929 | __metrics 18:47:04 ERROR - PID 1929 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 18:47:05 INFO - TEST-INFO | 1929: exit 0 18:47:05 INFO - Browser initialized. 18:47:05 INFO - Running cycle 1/1 for kraken test... 18:47:05 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/tmpFGQLYe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:47:08 INFO - PID 1931 | 2017-04-10 18:47:08.410 plugin-container[1932:15286] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a47, name = 'com.apple.tsm.portname' 18:47:08 INFO - PID 1931 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:08 INFO - PID 1931 | 2017-04-10 18:47:08.411 plugin-container[1932:15286] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 18:47:08 INFO - PID 1931 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:08 INFO - PID 1931 | 2017-04-10 18:47:08.411 plugin-container[1932:15286] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:47:18 INFO - PID 1931 | RSS: Main: 250552320 18:47:18 INFO - PID 1931 | 18:47:20 INFO - PID 1931 | Cycle 1(1): loaded http://localhost:49672/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49672/tests/kraken/driver.html?testName=audio-beat-detection) 18:47:21 INFO - PID 1931 | RSS: Main: 260726784 18:47:21 INFO - PID 1931 | 18:47:24 INFO - PID 1931 | Cycle 1(1): loaded http://localhost:49672/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49672/tests/kraken/driver.html?testName=audio-dft) 18:47:25 INFO - PID 1931 | RSS: Main: 267259904 18:47:25 INFO - PID 1931 | 18:47:28 INFO - PID 1931 | Cycle 1(1): loaded http://localhost:49672/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49672/tests/kraken/driver.html?testName=audio-fft) 18:47:29 INFO - PID 1931 | RSS: Main: 263184384 18:47:29 INFO - PID 1931 | 18:47:31 INFO - PID 1931 | Cycle 1(1): loaded http://localhost:49672/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49672/tests/kraken/driver.html?testName=audio-oscillator) 18:47:32 INFO - PID 1931 | RSS: Main: 265240576 18:47:32 INFO - PID 1931 | 18:47:34 INFO - PID 1931 | Cycle 1(1): loaded http://localhost:49672/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49672/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:47:35 INFO - PID 1931 | RSS: Main: 265895936 18:47:35 INFO - PID 1931 | 18:47:38 INFO - PID 1931 | Cycle 1(1): loaded http://localhost:49672/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49672/tests/kraken/driver.html?testName=imaging-darkroom) 18:47:38 INFO - PID 1931 | RSS: Main: 270942208 18:47:38 INFO - PID 1931 | 18:47:42 INFO - PID 1931 | Cycle 1(1): loaded http://localhost:49672/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49672/tests/kraken/driver.html?testName=imaging-desaturate) 18:47:42 INFO - PID 1931 | RSS: Main: 271904768 18:47:42 INFO - PID 1931 | 18:47:46 INFO - PID 1931 | Cycle 1(1): loaded http://localhost:49672/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49672/tests/kraken/driver.html?testName=json-parse-financial) 18:47:47 INFO - PID 1931 | RSS: Main: 269119488 18:47:47 INFO - PID 1931 | 18:47:48 INFO - PID 1931 | Cycle 1(1): loaded http://localhost:49672/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49672/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:47:49 INFO - PID 1931 | RSS: Main: 270323712 18:47:49 INFO - PID 1931 | 18:47:51 INFO - PID 1931 | Cycle 1(1): loaded http://localhost:49672/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49672/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:47:51 INFO - PID 1931 | RSS: Main: 270589952 18:47:51 INFO - PID 1931 | 18:47:54 INFO - PID 1931 | Cycle 1(1): loaded http://localhost:49672/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49672/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:47:54 INFO - PID 1931 | RSS: Main: 270712832 18:47:54 INFO - PID 1931 | 18:47:57 INFO - PID 1931 | Cycle 1(1): loaded http://localhost:49672/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49672/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:47:58 INFO - PID 1931 | RSS: Main: 271302656 18:47:58 INFO - PID 1931 | 18:48:01 INFO - PID 1931 | Cycle 1(1): loaded http://localhost:49672/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49672/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:48:02 INFO - PID 1931 | RSS: Main: 270987264 18:48:02 INFO - PID 1931 | 18:48:04 INFO - PID 1931 | Cycle 1(1): loaded http://localhost:49672/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49672/tests/kraken/driver.html?testName=ai-astar) 18:48:04 INFO - PID 1931 | RSS: Main: 274251776 18:48:04 INFO - PID 1931 | 18:48:04 INFO - PID 1931 | __start_tp_report 18:48:04 INFO - PID 1931 | _x_x_mozilla_page_load 18:48:04 INFO - PID 1931 | _x_x_mozilla_page_load_details 18:48:04 INFO - PID 1931 | |i|pagename|runs| 18:48:04 INFO - PID 1931 | |0;ai-astar;108;122;132;119;123;122;123;123;121;123 18:48:04 INFO - PID 1931 | |1;audio-beat-detection;155;116;115;170;146;149;140;114;139;150 18:48:04 INFO - PID 1931 | |2;audio-dft;208;219;221;232;223;288;297;205;232;291 18:48:04 INFO - PID 1931 | |3;audio-fft;119;50;89;119;86;86;79;60;74;60 18:48:04 INFO - PID 1931 | |4;audio-oscillator;81;73;93;73;73;74;87;87;103;86 18:48:04 INFO - PID 1931 | |5;imaging-gaussian-blur;93;101;87;138;97;92;138;83;136;137 18:48:04 INFO - PID 1931 | |6;imaging-darkroom;137;114;156;101;100;101;110;100;108;146 18:48:04 INFO - PID 1931 | |7;imaging-desaturate;122;95;156;122;105;90;161;113;153;142 18:48:04 INFO - PID 1931 | |8;json-parse-financial;51;51;52;50;52;51;52;53;52;53 18:48:04 INFO - PID 1931 | |9;json-stringify-tinderbox;61;55;68;97;105;60;81;85;84;52 18:48:04 INFO - PID 1931 | |10;stanford-crypto-aes;88;90;97;97;87;90;85;87;88;100 18:48:04 INFO - PID 1931 | |11;stanford-crypto-ccm;154;169;164;159;165;173;163;173;232;148 18:48:04 INFO - PID 1931 | |12;stanford-crypto-pbkdf2;201;202;191;194;198;194;191;197;217;194 18:48:04 INFO - PID 1931 | |13;stanford-crypto-sha256-iterative;72;71;83;65;66;96;97;99;84;73 18:48:04 INFO - PID 1931 | __end_tp_report 18:48:04 INFO - PID 1931 | __start_cc_report 18:48:04 INFO - PID 1931 | _x_x_mozilla_cycle_collect,1036 18:48:04 INFO - PID 1931 | __end_cc_report 18:48:04 INFO - PID 1931 | __startTimestamp1491875284642__endTimestamp 18:48:04 INFO - PID 1931 | 18:48:04 INFO - PID 1931 | ------- Summary: start ------- 18:48:04 INFO - PID 1931 | Number of tests: 14 18:48:04 INFO - PID 1931 | 18:48:04 INFO - PID 1931 | [#0] ai-astar Cycles:10 Average:121.60 Median:123.00 stddev:5.85 (4.8%) stddev-sans-first:3.59 18:48:04 INFO - PID 1931 | Values: 108.0 122.0 132.0 119.0 123.0 122.0 123.0 123.0 121.0 123.0 18:48:04 INFO - PID 1931 | 18:48:04 INFO - PID 1931 | [#1] audio-beat-detection Cycles:10 Average:139.40 Median:147.50 stddev:18.90 (12.8%) stddev-sans-first:19.19 18:48:04 INFO - PID 1931 | Values: 155.0 116.0 115.0 170.0 146.0 149.0 140.0 114.0 139.0 150.0 18:48:04 INFO - PID 1931 | 18:48:04 INFO - PID 1931 | [#2] audio-dft Cycles:10 Average:241.60 Median:232.00 stddev:35.90 (15.5%) stddev-sans-first:35.95 18:48:04 INFO - PID 1931 | Values: 208.0 219.0 221.0 232.0 223.0 288.0 297.0 205.0 232.0 291.0 18:48:04 INFO - PID 1931 | 18:48:04 INFO - PID 1931 | [#3] audio-fft Cycles:10 Average:82.20 Median:76.50 stddev:23.29 (30.5%) stddev-sans-first:20.55 18:48:04 INFO - PID 1931 | Values: 119.0 50.0 89.0 119.0 86.0 86.0 79.0 60.0 74.0 60.0 18:48:04 INFO - PID 1931 | 18:48:04 INFO - PID 1931 | [#4] audio-oscillator Cycles:10 Average:83.00 Median:83.50 stddev:10.14 (12.1%) stddev-sans-first:10.73 18:48:04 INFO - PID 1931 | Values: 81.0 73.0 93.0 73.0 73.0 74.0 87.0 87.0 103.0 86.0 18:48:04 INFO - PID 1931 | 18:48:04 INFO - PID 1931 | [#5] imaging-gaussian-blur Cycles:10 Average:110.20 Median:85.00 stddev:23.79 (28.0%) stddev-sans-first:24.41 18:48:04 INFO - PID 1931 | Values: 93.0 101.0 87.0 138.0 97.0 92.0 138.0 83.0 136.0 137.0 18:48:04 INFO - PID 1931 | 18:48:04 INFO - PID 1931 | [#6] imaging-darkroom Cycles:10 Average:117.30 Median:112.00 stddev:21.06 (18.8%) stddev-sans-first:21.09 18:48:04 INFO - PID 1931 | Values: 137.0 114.0 156.0 101.0 100.0 101.0 110.0 100.0 108.0 146.0 18:48:04 INFO - PID 1931 | 18:48:04 INFO - PID 1931 | [#7] imaging-desaturate Cycles:10 Average:125.90 Median:154.50 stddev:25.84 (16.7%) stddev-sans-first:27.37 18:48:04 INFO - PID 1931 | Values: 122.0 95.0 156.0 122.0 105.0 90.0 161.0 113.0 153.0 142.0 18:48:04 INFO - PID 1931 | 18:48:04 INFO - PID 1931 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:52.00 stddev:0.95 (1.8%) stddev-sans-first:0.97 18:48:04 INFO - PID 1931 | Values: 51.0 51.0 52.0 50.0 52.0 51.0 52.0 53.0 52.0 53.0 18:48:04 INFO - PID 1931 | 18:48:04 INFO - PID 1931 | [#9] json-stringify-tinderbox Cycles:10 Average:74.80 Median:74.50 stddev:18.26 (24.5%) stddev-sans-first:18.67 18:48:04 INFO - PID 1931 | Values: 61.0 55.0 68.0 97.0 105.0 60.0 81.0 85.0 84.0 52.0 18:48:04 INFO - PID 1931 | 18:48:04 INFO - PID 1931 | [#10] stanford-crypto-aes Cycles:10 Average:90.90 Median:89.00 stddev:5.17 (5.8%) stddev-sans-first:5.38 18:48:04 INFO - PID 1931 | Values: 88.0 90.0 97.0 97.0 87.0 90.0 85.0 87.0 88.0 100.0 18:48:04 INFO - PID 1931 | 18:48:04 INFO - PID 1931 | [#11] stanford-crypto-ccm Cycles:10 Average:170.00 Median:167.00 stddev:23.18 (13.9%) stddev-sans-first:23.85 18:48:04 INFO - PID 1931 | Values: 154.0 169.0 164.0 159.0 165.0 173.0 163.0 173.0 232.0 148.0 18:48:04 INFO - PID 1931 | 18:48:04 INFO - PID 1931 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.90 Median:197.50 stddev:7.69 (3.9%) stddev-sans-first:8.08 18:48:04 INFO - PID 1931 | Values: 201.0 202.0 191.0 194.0 198.0 194.0 191.0 197.0 217.0 194.0 18:48:04 INFO - PID 1931 | 18:48:04 INFO - PID 1931 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.60 Median:83.50 stddev:13.09 (15.7%) stddev-sans-first:13.51 18:48:04 INFO - PID 1931 | Values: 72.0 71.0 83.0 65.0 66.0 96.0 97.0 99.0 84.0 73.0 18:48:04 INFO - PID 1931 | -------- Summary: end -------- 18:48:04 INFO - PID 1931 | 18:48:05 INFO - TEST-INFO | 1931: exit 0 18:48:05 INFO - TEST-OK | kraken | took 64056ms 18:48:05 INFO - SUITE-END | took 1179s 18:48:06 INFO - Completed test suite (00:19:40) 18:48:06 INFO - javascript benchmark 18:48:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7889.327053221236, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3024.455597992361, 3526.0, 3549.69827564657, 3558.9288214235708, 3571.9642803571955, 6090.878182436349, 6138.447539721425, 6148.3851614838495, 6193.287771906226, 6222.97320942044, 6135.324535308553, 6163.445289923906, 6166.26004879414, 6183.25800903891, 6287.748490060392, 5328.440513746059, 5409.134538473845, 5455.945440545594, 5496.725163741817, 5533.750981205855, 5351.598630102739, 5457.454254574551, 5483.561315094783, 5495.2856128703415, 5508.173773933902, 5449.972750136224, 5470.124780035176, 5495.230667706524, 5497.422770609069, 5503.367112782022, 5371.946280537184, 5408.242846001563, 5429.348478182644, 5444.0, 5488.204210389511, 5048.192289233742, 5363.222332761728, 5369.087255166631, 5389.164679474688, 5392.379876314235, 4730.432348118248, 5967.46292833647, 6135.877282454326, 6219.68901554925, 6222.906656400158, 6426.2931077581425, 6562.2453417856605, 6597.406233439014, 6628.536002479828, 6663.433608143314, 6751.932480675179, 6760.0, 6768.8307792305095, 6770.289119642465, 6790.456763458911, 6226.034964580406, 6300.21247344082, 6310.211223597051, 6324.525660575429, 6332.841678958063, 5824.504917082096, 6565.080888675589, 6758.763443279461, 6789.5586786859685, 6837.965810170917, 6288.402601752826, 6480.2547707013355, 6508.479321654257, 6554.115194448688, 6592.044153597702, 6528.804135875931, 6529.706163222666, 6613.074169616258, 6621.702023408958, 6742.0, 6586.571872828251, 6594.604323740495, 6598.60408375499, 6600.966995164993, 6619.50353723463, 6284.748610055546, 6351.2060992375955, 6373.458256048195, 6536.019597060384, 6604.603723776589, 5255.290535777697, 5281.207818827206, 5307.442718514578, 5318.361796584357, 5335.11970524859, 4671.0891376182, 4683.555062269079, 4684.812607495662, 4702.670813043054, 4731.976340118346, 5212.192110222922, 6222.719977601018, 6237.126802247689, 6260.499160067275, 6290.21372328459, 6623.569467984662, 6694.866102677919, 6716.093327400836, 6720.361565651353, 6725.159355080615, 6241.594296370721, 6288.088227207121, 6327.367263273728, 6334.588251763713, 6354.872902541923, 5681.318241811009, 5911.172435858984, 5936.495397891142, 5939.940600593939, 5944.613600115978, 6650.035744816974, 6682.76610318656, 6800.591964482147, 6810.31896810315, 6842.281560436082, 6682.69927853238, 6694.263631000684, 6716.899246511404, 6720.193576770819, 6732.764353247809, 6235.532335074979, 6626.337366263335, 6754.358335958076, 6847.07564478789, 6866.828329291808, 6278.089676996901, 6313.084602732486, 6529.738810447719, 6540.542162048611, 6550.541462097798, 6693.631850248283, 6718.664066796543, 6720.6975686093265, 6749.392554670103, 6766.0, 5299.364076310867, 6128.075379514812, 6303.054541818764, 6480.740770369132, 6516.5112616553, 6334.524910631629, 6411.358864113738, 6447.451966582893, 6457.160569126171, 6459.450946669585, 5588.552915766648, 6171.321154673072, 6270.341614130633, 6303.46420554258, 6328.557001009885, 6349.174607301022, 6356.237251529661, 6417.2620148681635, 6419.26178489461, 6426.325235850355, 5271.341082364705, 6305.779297724557, 6312.621242725451, 6313.494920406449, 6331.2085989251345], "value": 6028.665414367503, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.9862602747945, 798.1419973528457, 825.174825174825, 1119.6137332620253, 1281.8525869524997, 29971.700282997164, 38211.85364439064, 38238.852834414974, 38376.23247535047, 38434.231315373676, 38051.809740951256, 38306.0, 38341.616583834155, 38403.807980960126, 38419.80790096053, 8067.314278286352, 8180.6318715657935, 8220.671173153096, 8579.95710021449, 8837.160469755363, 8691.826163476726, 8823.029466758651, 8837.690680826172, 8862.291016718647, 8862.29101671868, 8797.912020879774, 8805.95597022014, 8889.333300002494, 8889.733308000737, 8891.466512009269, 8829.514376709247, 8847.247983921374, 8854.026057133775, 8871.733847984571, 8871.866921996176, 8642.092580279043, 8665.480071195749, 8701.869471957925, 8713.085126061736, 8722.607482663296, 37989.81005094985, 38142.23715525702, 38169.85490435291, 38209.23581528381, 38233.80883095567, 3563.127033876691, 3584.5698516178227, 3602.8198590070656, 3607.837647305851, 3608.0438683748825, 30284.394312113633, 30409.69590304091, 30466.695333046606, 30551.08346749601, 30602.387952241053, 2758.213909035915, 3156.889508867179, 3182.6817318268218, 3185.2196211927994, 3198.712115909556, 215378.0, 220649.0, 251655.74172129206, 269914.0, 271253.0, 47265.76367118211, 47642.0, 47647.28529072067, 47665.5233447668, 47755.80610484696, 49232.261516077284, 49648.50351496511, 49838.0, 49934.0, 49945.25082123771, 13713.657158571115, 13890.444382224598, 13898.51355202563, 13902.026858120036, 13905.443782248596, 11220.326780393203, 11270.211085223955, 11316.20786544934, 11334.60328888485, 11348.205625606293, 11811.822822657667, 13536.984726145578, 13623.455061797615, 13649.727005459836, 13737.381817818223, 5870.853228669317, 5931.703414829328, 5943.0, 5948.672822994777, 5956.761729530857, 1025.3284098915221, 1041.6979076068008, 1045.132539991805, 1067.199600299775, 1073.849661047454, 21496.892515537635, 22025.66961495579, 22852.0, 22981.310560683207, 24169.637455438184, 999.6950929966284, 1062.0229388962018, 1123.5730422439585, 1152.4468255237532, 1164.6214980131492, 9774.95112524447, 11586.942065289619, 12286.262824230575, 12295.877041229653, 12300.261984280973, 361546.0, 362298.0, 362545.0, 362876.0, 363179.0, 52941.2058819118, 53201.0, 53222.73388633032, 53276.46723532715, 53331.46668533265, 1490.7987421697933, 1512.2060918018128, 1539.415022291522, 1550.3720992997853, 1551.0, 20968.0, 21780.237691680715, 23820.523589527766, 23826.40433989164, 23851.0, 33044.339113218084, 35121.473177902866, 37075.44386834253, 38002.61997380101, 38022.859314220615, 272929.63535182195, 316207.0, 321227.0, 321625.0, 324345.0, 28421.857890710413, 34359.141021474665, 34712.43794029224, 34782.47826282658, 35391.0, 44850.65448036564, 46090.07819843517, 46118.0, 46147.538524615666, 46367.53632463632, 25052.49895002126, 30001.849990749906, 30392.39215215639, 31402.52896206513, 31444.842775785975, 30448.84775576108, 30598.694013059583, 31280.374392511567, 31422.0, 31449.842750787015, 31805.20486987844, 36537.634623654485, 37095.0, 37101.814490927376, 37200.44199336958, 25293.747062529874, 25856.741432586186, 26030.56831874265, 26806.65966701696, 30354.0, 20532.794672053686, 21585.352439426842, 23368.415789605395, 24055.278341649777, 24369.878150609133], "value": 17391.497296326474, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.41349562161145, 417.62831080338486, 422.34325623655207, 447.4362303497593, 449.55044955044957, 19831.801681983194, 21681.4579635509, 22217.77782222177, 22260.332190034325, 22399.888000559993, 22032.559348813014, 22589.661155082646, 23490.0, 23579.882100589515, 23772.643410348857, 5978.133170690258, 6148.200733904586, 6165.475934545569, 6180.1965744453155, 6200.534959878005, 5747.310322761254, 6083.6349819011, 6116.44951954324, 6124.755009799603, 6127.080937859335, 5067.265246539249, 6058.636481811083, 6081.939180608181, 6084.969575152118, 6088.391160883898, 6037.849053773669, 6128.44843964041, 6128.693565321716, 6128.969355153218, 6149.077638354238, 5379.811706590251, 5471.808486702986, 5473.0, 5554.555635549187, 5585.804496842591, 27193.320166995785, 27351.589726154125, 27766.61166941645, 27976.44047119046, 28238.435231295465, 2225.3991422315885, 2292.5300313435705, 2299.528596637685, 2299.609066458706, 2304.3547806614174, 17352.219150138273, 17440.47678569645, 17806.821931780647, 18256.726149107773, 18386.264549418007, 2186.967195492069, 2187.6937228787983, 2231.4979129695853, 2232.285668586053, 2239.317008312464, 155762.0, 161184.0, 161439.0, 162990.0, 164901.17549412296, 12483.50065997368, 12503.499860005499, 12647.304398258, 12786.936065319614, 12850.678733031562, 13975.79036314456, 16453.83546164523, 16800.07599582035, 16809.41167059147, 16865.831341686673, 8584.613692383859, 8916.0, 8924.55377231136, 8951.776205594912, 9045.909540904508, 8394.832103358021, 8402.873956890651, 8439.324854011664, 8583.0, 8593.44142630727, 7812.140664526897, 7910.920890791019, 7949.364050875916, 7958.562279074591, 7979.241972012649, 1986.3345779163853, 2002.8383537548186, 2040.7755146933825, 2154.9353519394444, 2279.5326957973907, 913.9131782480654, 935.3312381647019, 974.9122578967927, 997.1922742578535, 1032.8708911386077, 15656.843431565538, 19893.0, 20986.497932340437, 21074.262400815896, 21377.46556336073, 985.4629227071264, 1010.3735683876043, 1039.667306461925, 1040.3653771199563, 1052.8368102944055, 10647.84028239577, 11151.163662725166, 11170.497327620276, 11175.273607215506, 11189.0, 610158.0, 685856.0, 694758.0, 698586.0, 713392.0, 151989.0, 157495.0, 163836.0, 167972.16013919853, 168094.0, 1211.6486218996567, 1229.7048708309942, 1260.7415479826614, 1262.987370126287, 1264.2351377416715, 19985.900070499556, 21552.922353881928, 22611.886940565193, 22863.08547658075, 22910.0, 60836.695816522406, 62090.75818483515, 62379.0, 63601.0, 63710.0, 73200.0, 81577.0, 83767.0, 85158.0, 85828.0, 7847.5683837387205, 8919.777005574913, 8924.86612700823, 9072.364934454525, 9335.833020872391, 14433.7834932474, 14550.4907328243, 14629.92685036568, 14885.479008234659, 14920.477783277533, 11994.0, 12149.757004859677, 12220.755584888075, 12254.387280635754, 12427.937860311002, 11504.424778761197, 11682.70793230176, 11694.94152529266, 11934.820977685515, 12008.939955300168, 11297.43512824372, 11961.64115076549, 12219.75560488803, 12417.565385211474, 12462.314572698226, 8919.42023768453, 8960.014398416039, 9162.541872906195, 9273.0, 9308.534573271174, 11965.282083075042, 12006.0, 12201.14591978553, 12308.446119924445, 12336.198147120767], "value": 10751.469114048059, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.6269607206054, 1421.6588018875473, 1433.3836450326355, 1440.4238304678133, 1458.6134674311309, 11324.490397932092, 11383.715407114827, 11723.5896743614, 11776.175667703264, 11777.823332650018, 11428.942855285733, 11670.124740644444, 11690.064794816415, 11709.180357374966, 11752.882471175286, 2477.1949116537153, 2526.393665520271, 2535.847849129058, 2543.8728063596745, 2544.5165418570414, 2463.7043554773454, 2467.3214865911928, 2501.762332578402, 2502.424442378245, 2509.3099397665605, 2445.3153117127144, 2472.468419289852, 2489.3403248139257, 2522.9781938315014, 2528.645989561463, 2433.6957880264877, 2473.332200305925, 2486.353548077495, 2512.635667828173, 2515.308290220186, 2278.4303924018996, 2339.6724458575814, 2411.3368823573483, 2521.382261345964, 2522.7225005249406, 11558.826617600742, 11606.593769218036, 11680.065594752485, 11711.238769480042, 11737.706557336049, 6513.902291465632, 6684.064231007663, 6784.287649796799, 6785.321467853225, 6805.319468053155, 4067.51189857219, 4087.693422993287, 4341.761203133827, 4413.492448368414, 4417.558244175589, 1244.6390546741434, 1262.671705356614, 1308.9541866034642, 1311.7638825011495, 1311.7704401729754, 14962.0, 15574.844251557453, 16312.673746525003, 16315.265813038443, 16456.424025159064, 5527.474889885454, 5660.971695141498, 5804.622699524517, 5872.148538461899, 5876.265466816648, 11787.29276243411, 11986.160968732105, 12022.759544809054, 12042.458089385998, 12074.698132546757, 6600.0, 6604.768833090819, 6616.4376028037195, 6619.205695316593, 6629.469642428594, 6056.36408177135, 6152.846178845475, 6172.351903050205, 6225.844353891189, 6229.813105606839, 3799.601041890617, 4265.87202383929, 4418.182636212311, 4462.977685111554, 4477.664175186809, 1312.7965165399378, 1329.541308248672, 1332.4137379552965, 1351.49319005373, 1351.86481351866, 781.6013832945239, 798.7729572526927, 801.9959900200579, 808.2281421242699, 813.4753084260619, 5163.277141200235, 5577.972110139423, 5848.912266316009, 6288.52836037299, 6454.838629034218, 601.9578629495893, 610.1122866229625, 619.7397093220858, 623.5790841182184, 625.8372823066036, 4094.324436468009, 4289.892752681146, 4344.847930322424, 4526.23054080807, 4533.863984080483, 14097.295135243197, 14705.632359191106, 14710.33803478846, 14715.96988210815, 14718.705625887422, 14102.083364581269, 14574.344154513072, 14578.125312481285, 14734.63163420923, 14749.262536873113, 10415.323004004715, 10707.0, 10707.62523311696, 10729.731756705989, 10736.892631073746, 5793.478586927028, 5807.303123625023, 6801.489888258301, 6966.164060312795, 6990.056342393712, 12788.872111278768, 12899.226046437243, 13202.0, 13227.3386330685, 13288.867111328764, 13663.79504307456, 13786.931065344608, 13818.447262109403, 13969.301534923416, 14051.859481405056, 9145.496997664926, 9291.814163716823, 9466.810663786548, 9568.282378821477, 9577.23382129414, 7945.6027198640995, 9472.668456603986, 9713.805723885624, 10606.257561970588, 10674.57301707923, 8999.32505062136, 9024.232940200156, 9047.276217902683, 9181.311401645036, 9201.171894529525, 7820.530768153929, 8307.670772676585, 8690.0, 8880.467171969702, 9144.542772861198, 6957.339052789976, 8104.635291412019, 8186.058603260463, 8210.178982101743, 8359.205875441823, 5102.4897510248675, 5143.3056537368475, 6068.969655151696, 6085.0, 6127.754889804358, 6167.753289868355, 6175.325681219667, 6203.379662033761, 6216.720247588779, 6237.345078766664], "value": 5318.140471550305, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.41476274165217, 407.7064513550244, 408.8221623593736, 414.47982781609073, 417.1177958617523, 6245.531585131116, 6367.076773867792, 6368.203974503187, 6382.489400847932, 6388.275165705262, 5090.338256026721, 5921.674307913063, 6168.845778855531, 6209.875802483948, 6235.812925612239, 4687.578117969382, 4725.929111063337, 4732.668713190078, 4770.522947705219, 4784.492884741313, 4505.752183629899, 4731.313959475874, 4745.525447455244, 4757.976210118944, 4782.450018247894, 4269.850555230568, 4643.651726120537, 4676.836310729125, 4697.107549565589, 4703.858884233461, 4114.773687447189, 4472.709273897202, 4683.882902927437, 4712.599429048535, 4730.763461826895, 3961.2247678741787, 4164.250434921712, 4176.60322269384, 4181.414601955728, 4190.455240818705, 4902.764175644058, 5193.454687257859, 5291.0, 5700.916825803084, 5710.971445142789, 5566.55467562594, 5587.497125258747, 5595.7481913313995, 5610.971945140289, 5617.21359009739, 6447.54867159299, 6572.572782769104, 6598.307177746316, 6641.103451034097, 6675.632840193787, 4258.382534532476, 4691.741954192518, 4732.692374995614, 4737.549932756383, 4739.14695354836, 5988.940110598882, 6618.635975021371, 7010.964945175292, 7046.894296585555, 7072.611006394646, 4788.592969597623, 4846.345743324677, 4848.884756506023, 4871.269309603588, 4926.842192084857, 4980.726060066731, 4989.426215985136, 5002.474740152232, 5008.17365134756, 5014.887792010516, 4854.368932038812, 5351.331083614548, 5510.641808282448, 5541.639793413416, 5631.2960879890015, 6654.968479885625, 6666.266710661824, 6681.866362672719, 6686.264510903797, 6702.83242918931, 1589.9824112567965, 1656.180190805559, 1692.1116413882564, 1711.2812618699927, 1720.7160818465063, 1043.2540733375672, 1176.9117316201318, 1184.8518935133109, 1186.5372504723166, 1187.8633957094871, 5480.671159730429, 5535.263697004506, 6306.527010474233, 6337.37371718648, 6407.807765767035, 6156.26124865019, 6650.634215118215, 6691.594765099287, 6897.62063086525, 6911.6544172791155, 6270.184875966095, 6327.841803954846, 6337.33457986914, 6347.952982568393, 6379.681015949184, 3430.433978393537, 3584.2473080653353, 3622.840690978914, 3624.4925710400566, 3631.2011357501306, 5704.286964129484, 6921.377076063179, 7076.973838793346, 7090.361867431853, 7100.396466300319, 6999.790006299819, 7059.682314295869, 7074.0, 7089.574625522485, 7100.46746494015, 6044.879102417927, 6493.383128602681, 6730.966345168243, 7111.2533184015965, 7135.215126335995, 6273.65494897785, 6519.380658837403, 6889.621070841152, 6962.268961758942, 7013.789586312419, 5924.703764811656, 6299.897517934435, 6506.869862602627, 6635.933640663531, 6858.731134740089, 5675.971620141873, 6907.654617269016, 7060.399866011447, 7102.573845569283, 7143.4999550031, 4044.595540445932, 4592.816287348469, 4885.511448855086, 4946.851594452172, 4967.776450059684, 5442.945570544244, 5762.711864406679, 6929.965350173217, 6940.0, 6947.588281441423, 5324.9733751331, 6642.833929151617, 6657.3009833966735, 6710.899336509859, 6714.496412768965, 5825.41745825431, 5974.7296027509265, 6048.002079656983, 6590.70341834609, 6674.532782705358, 5454.672719636835, 5974.342822289458, 6499.285078641251, 6508.934910651023, 6534.281229064894, 4856.878578035577, 5716.657000579979, 6521.836909099178, 6672.228522328054, 6695.497837662292, 6333.936660633335, 6437.549371543947, 6482.157319548429, 6541.70562324706, 6592.373724496165], "value": 4914.187826679111, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [833.1293797981109, 843.7300063979529, 864.7578677970171, 887.5029983209402, 888.0009988762642, 15532.45636402726, 16318.592035199112, 16352.345906163753, 17539.2107355169, 18355.265789368408, 16649.0, 17042.233099510522, 17111.74332385015, 17155.399561015365, 17155.884867483604, 991.6380521109779, 1005.3013155856682, 1016.872890888639, 1016.9288149829515, 1019.918406527476, 1014.4268488304122, 1016.547636301846, 1016.588281745894, 1017.013496907998, 1017.9949100254526, 1049.6063976008998, 1057.0750593230923, 1060.2472244706269, 1062.9627963021296, 1065.9413732244723, 1046.9581216751321, 1054.0671505717457, 1054.4569546683426, 1054.9261551691384, 1055.2244100586063, 740.1118657610834, 790.5730905311125, 792.7700966719644, 794.8847417124484, 795.2882170457408, 15725.842741572553, 16105.27526261321, 16126.951748136333, 16138.031718096834, 16146.03123812575, 16037.2783224754, 16401.261943212583, 16586.58533536659, 16678.49964501067, 16789.83210167895, 12217.450214740358, 13869.861301386958, 14113.788293175508, 14116.647083822883, 14140.646483837883, 3390.559227300435, 3408.897733068012, 3426.0749597608506, 3452.2405070884615, 3461.8442170102408, 101021.989780102, 101844.0, 120945.0, 121790.0, 122041.0, 11756.177067605186, 12676.30280334572, 12987.41556629954, 13290.0, 13341.266230357229, 23229.53540929172, 23620.881895590755, 23990.28029159128, 24034.39914002164, 24059.639105413433, 8417.705380311781, 8592.5703714814, 8670.653173873101, 8718.302535797122, 8733.781655458664, 4445.82216711328, 4526.388937493462, 4535.727856328631, 4546.249868771682, 4547.067851090518, 3395.37185620656, 4332.4367832181615, 4335.588119128678, 4427.048184640318, 4464.464264288306, 841.6212704283097, 893.8301722672671, 925.9583318750538, 932.1005229953128, 932.5989824375448, 625.5839866488825, 630.3696303696304, 637.9298277189505, 683.460066547422, 705.47794631973, 10560.47197640115, 10951.561937522569, 11281.548738050551, 11452.198346115858, 11996.700082497833, 595.8152972578514, 658.4074333100248, 671.8488340123433, 689.9034135221073, 695.0894328429773, 4812.735299558558, 5407.242985981966, 5484.972575137179, 5630.577706671934, 5674.347450043216, 75287.62356188185, 85622.57188714141, 88378.0, 91704.6244306336, 95810.0, 50899.74550127226, 67013.32466688275, 83185.3362932738, 84807.15192848117, 87321.0, 74436.0, 75296.87054694183, 75427.0, 76295.61852190703, 76315.0, 10724.249302548747, 11409.429528523375, 11446.255993360737, 11956.222845515014, 12348.938255309025, 29804.70195298106, 38001.09994500319, 38931.416028760155, 39028.0, 39460.07669025157, 42528.7241382759, 47168.764156179, 48205.75897120492, 48498.545043648744, 48691.0, 15155.696886061996, 16217.026978381335, 16322.265498052853, 16422.67154656924, 16552.66894662124, 17787.377441789475, 17900.820991790435, 18146.274149033892, 18151.546211344295, 18156.45530634083, 16313.836861631231, 16583.25375358136, 16592.917035414746, 16666.749998749787, 16675.999440033636, 16192.67614647724, 16381.0, 16581.58546036359, 16732.498025059267, 16761.497155085366, 16084.597885052968, 16143.434979775651, 16161.75757363664, 16383.754243686593, 16389.918050409673, 11674.649760507198, 11888.108391870817, 11912.0, 12030.796920308248, 12062.215955962834, 12648.620541383772, 12777.87222127804, 12822.551210707581, 12822.551210707581, 12988.35058247065], "value": 8184.120739677786, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1687.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 122.0, 132.0, 119.0, 123.0, 122.0, 123.0, 123.0, 121.0, 123.0], "value": 121.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 116.0, 115.0, 170.0, 146.0, 149.0, 140.0, 114.0, 139.0, 150.0], "value": 139.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 219.0, 221.0, 232.0, 223.0, 288.0, 297.0, 205.0, 232.0, 291.0], "value": 241.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 50.0, 89.0, 119.0, 86.0, 86.0, 79.0, 60.0, 74.0, 60.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 73.0, 93.0, 73.0, 73.0, 74.0, 87.0, 87.0, 103.0, 86.0], "value": 83.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 101.0, 87.0, 138.0, 97.0, 92.0, 138.0, 83.0, 136.0, 137.0], "value": 110.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 114.0, 156.0, 101.0, 100.0, 101.0, 110.0, 100.0, 108.0, 146.0], "value": 117.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 95.0, 156.0, 122.0, 105.0, 90.0, 161.0, 113.0, 153.0, 142.0], "value": 125.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 52.0, 50.0, 52.0, 51.0, 52.0, 53.0, 52.0, 53.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 55.0, 68.0, 97.0, 105.0, 60.0, 81.0, 85.0, 84.0, 52.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 97.0, 97.0, 87.0, 90.0, 85.0, 87.0, 88.0, 100.0], "value": 90.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 169.0, 164.0, 159.0, 165.0, 173.0, 163.0, 173.0, 232.0, 148.0], "value": 170.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 202.0, 191.0, 194.0, 198.0, 194.0, 191.0, 197.0, 217.0, 194.0], "value": 197.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 83.0, 65.0, 66.0, 96.0, 97.0, 99.0, 84.0, 73.0], "value": 80.6, "unit": "score"}]}]} 18:48:06 INFO - Return code: 0 18:48:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:48:06 ERROR - # TBPL SUCCESS # 18:48:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:48:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:48:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:48:06 INFO - 2017-04-10 18:48:06.474 screenresolution[1936:15685] starting screenresolution argv=screenresolution get 18:48:06 INFO - 2017-04-10 18:48:06.477 screenresolution[1936:15685] Display 0: 1600x1200x32@60 18:48:06 INFO - 2017-04-10 18:48:06.483 screenresolution[1937:15688] starting screenresolution argv=screenresolution list 18:48:06 INFO - Available Modes on Display 0 18:48:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:48:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:48:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:48:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:48:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:48:06 INFO - Intel Iris: 18:48:06 INFO - Chipset Model: Intel Iris 18:48:06 INFO - Type: GPU 18:48:06 INFO - Bus: Built-In 18:48:06 INFO - VRAM (Dynamic, Max): 1536 MB 18:48:06 INFO - Vendor: Intel (0x8086) 18:48:06 INFO - Device ID: 0x0a2e 18:48:06 INFO - Revision ID: 0x0009 18:48:06 INFO - Displays: 18:48:06 INFO - Display: 18:48:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:48:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:48:06 INFO - Display Serial Number: 1600x1200 60 18:48:06 INFO - Main Display: Yes 18:48:06 INFO - Mirror: Off 18:48:06 INFO - Online: Yes 18:48:06 INFO - Rotation: Supported 18:48:06 INFO - Return code: 0 18:48:06 INFO - Running post-action listener: _resource_record_post_action 18:48:06 INFO - [mozharness: 2017-04-11 01:48:06.717179Z] Finished run-tests step (success) 18:48:06 INFO - Running post-run listener: _resource_record_post_run 18:48:06 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 696320; Write bytes: 519569408; Read time: 9; Write time: 1677 18:48:06 INFO - TinderboxPrint: CPU usage
54.4% 18:48:06 INFO - TinderboxPrint: I/O read bytes / time
696,320 / 9 18:48:06 INFO - TinderboxPrint: I/O write bytes / time
519,569,408 / 1,677 18:48:06 INFO - TinderboxPrint: CPU idle
2,189.7 (45.6%) 18:48:06 INFO - TinderboxPrint: CPU system
967.2 (20.1%) 18:48:06 INFO - TinderboxPrint: CPU user
1,644.8 (34.3%) 18:48:06 INFO - TinderboxPrint: Swap in / out
208,703,488 / 0 18:48:06 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 204915200; Write bytes: 202711040; Read time: 16308; Write time: 438 18:48:06 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 696320; Write bytes: 308189696; Read time: 9; Write time: 1197 18:48:07 INFO - Running post-run listener: _upload_blobber_files 18:48:07 INFO - Blob upload gear active. 18:48:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:48:07 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:48:07 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:48:07 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:48:07 INFO - (blobuploader) - INFO - Open directory for files ... 18:48:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:48:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:48:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:48:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:48:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:48:08 INFO - (blobuploader) - INFO - Done attempting. 18:48:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:48:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:48:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:48:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:48:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:48:09 INFO - (blobuploader) - INFO - Done attempting. 18:48:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:48:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:48:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:48:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:48:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:48:10 INFO - (blobuploader) - INFO - Done attempting. 18:48:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:48:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:48:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:48:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:48:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:48:13 INFO - (blobuploader) - INFO - Done attempting. 18:48:13 INFO - (blobuploader) - INFO - Iteration through files over. 18:48:13 INFO - Return code: 0 18:48:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:48:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:48:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/76b3e502973ba1af8c847fa6b4633e276042f0d67f5d6ddbc5fe353a6f96fd556a12c85cc4f8259fc7c5e3e87515b8896d341c6be24dccfb3a964ec029243c65", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6e7bfb155e83a59005da80c1bafb20a76d5b5a50fe50af65a27bdd536a3e93815d076449beb02e66509e6d1f25c1a89b20cf1ee06b65594f38eb2279d3c546b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e563f37736a2e5ffe5e8b429f13295aad646a98b1ecc2c350310aee3a10bc567a66567a03515ba85fd27b10f796c7cbc3b7bd77490fc8231e3596b9f3eb116dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/03f5fca43c452b031ab87294638256a0f839a145433c8e0d8695e2e2a88847da90d4a0aa5b3a66efbb996bd41812b0f6774f21b81a5c06db2b15d909ce4a45c5"} 18:48:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:48:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:48:13 INFO - Contents: 18:48:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/76b3e502973ba1af8c847fa6b4633e276042f0d67f5d6ddbc5fe353a6f96fd556a12c85cc4f8259fc7c5e3e87515b8896d341c6be24dccfb3a964ec029243c65", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6e7bfb155e83a59005da80c1bafb20a76d5b5a50fe50af65a27bdd536a3e93815d076449beb02e66509e6d1f25c1a89b20cf1ee06b65594f38eb2279d3c546b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e563f37736a2e5ffe5e8b429f13295aad646a98b1ecc2c350310aee3a10bc567a66567a03515ba85fd27b10f796c7cbc3b7bd77490fc8231e3596b9f3eb116dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/03f5fca43c452b031ab87294638256a0f839a145433c8e0d8695e2e2a88847da90d4a0aa5b3a66efbb996bd41812b0f6774f21b81a5c06db2b15d909ce4a45c5"} 18:48:13 INFO - Running post-run listener: copy_logs_to_upload_dir 18:48:13 INFO - Copying logs to upload dir... 18:48:13 INFO - mkdir: /builds/slave/test/build/upload/logs 18:48:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.255268 ========= master_lag: 0.58 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-10 18:48:13.687399) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2017-04-10 18:48:13.688474) ========= 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.wiZuW7p1NY/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.MyV6nLpPLb/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/76b3e502973ba1af8c847fa6b4633e276042f0d67f5d6ddbc5fe353a6f96fd556a12c85cc4f8259fc7c5e3e87515b8896d341c6be24dccfb3a964ec029243c65", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6e7bfb155e83a59005da80c1bafb20a76d5b5a50fe50af65a27bdd536a3e93815d076449beb02e66509e6d1f25c1a89b20cf1ee06b65594f38eb2279d3c546b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e563f37736a2e5ffe5e8b429f13295aad646a98b1ecc2c350310aee3a10bc567a66567a03515ba85fd27b10f796c7cbc3b7bd77490fc8231e3596b9f3eb116dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/03f5fca43c452b031ab87294638256a0f839a145433c8e0d8695e2e2a88847da90d4a0aa5b3a66efbb996bd41812b0f6774f21b81a5c06db2b15d909ce4a45c5"} 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.011296 build_url: 'https://queue.taskcluster.net/v1/task/fDgY9fIWRUSkIXB_dTGgWQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/76b3e502973ba1af8c847fa6b4633e276042f0d67f5d6ddbc5fe353a6f96fd556a12c85cc4f8259fc7c5e3e87515b8896d341c6be24dccfb3a964ec029243c65", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6e7bfb155e83a59005da80c1bafb20a76d5b5a50fe50af65a27bdd536a3e93815d076449beb02e66509e6d1f25c1a89b20cf1ee06b65594f38eb2279d3c546b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e563f37736a2e5ffe5e8b429f13295aad646a98b1ecc2c350310aee3a10bc567a66567a03515ba85fd27b10f796c7cbc3b7bd77490fc8231e3596b9f3eb116dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/03f5fca43c452b031ab87294638256a0f839a145433c8e0d8695e2e2a88847da90d4a0aa5b3a66efbb996bd41812b0f6774f21b81a5c06db2b15d909ce4a45c5"}' ========= master_lag: 2.15 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2017-04-10 18:48:15.850274) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2017-04-10 18:48:15.850653) ========= 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.wiZuW7p1NY/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.MyV6nLpPLb/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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.005240 ========= master_lag: 4.85 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2017-04-10 18:48:20.704759) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 18:48:20.705117) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 18:48:20.712878) ========= ========= Total master_lag: 7.67 =========